vm-test-run-wifi
checks.aarch64-linux.wifi
· build #513
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13second: QEMU running (pid 47)14second # Disk image does not exist, creating the virtualisation disk image...15test # Disk image does not exist, creating the virtualisation disk image...16second # Formatting '/build/vm-state-second/tmp.rMky7vApm1', fmt=raw size=107374182417test # Formatting '/build/vm-state-test/tmp.1wVdyGhPsL', fmt=raw size=107374182418second # mke2fs 1.47.4 (6-Mar-2025)19test # mke2fs 1.47.4 (6-Mar-2025)20second # Discarding device blocks: 0/262144 done21test # Discarding device blocks: 0/262144 done22second # Creating filesystem with 262144 4k blocks and 65536 inodes23test # Creating filesystem with 262144 4k blocks and 65536 inodes24second # Filesystem UUID: 53c27437-8307-4a34-93b6-404d6ef248d025test: QEMU running (pid 48)26second # Superblock backups stored on blocks:27test # Filesystem UUID: f33491b6-5fa3-4aeb-a71d-8c591ce8428d28second # 32768, 98304, 163840, 22937629test # Superblock backups stored on blocks:30second # 31(finished: start all VMs, in 0.46 seconds)32second # Allocating group tables: 0/8 done33test: waiting for unit NetworkManager.service34second # Writing inode tables: 0/8 done35test: waiting for the VM to finish booting36test # 32768, 98304, 163840, 22937637second # Creating journal (8192 blocks): done38test # 39second # Writing superblocks and filesystem accounting information: 0/8 done40test # Allocating group tables: 0/8 done41second # 42test # Writing inode tables: 0/8 done43second # Virtualisation disk image created.44test # Creating journal (8192 blocks): done45test # Writing superblocks and filesystem accounting information: 0/8 done46test # 47test # Virtualisation disk image created.48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Linux version 6.18.46 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 23 12:27:10 UTC 202650second # [ 0.000000] KASLR enabled51second # [ 0.000000] random: crng init done52second # [ 0.000000] Machine model: linux,dummy-virt53second # [ 0.000000] efi: UEFI not found.54second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT55second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]56second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]57second # [ 0.000000] Zone ranges:58second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]59second # [ 0.000000] DMA32 empty60second # [ 0.000000] Normal empty61second # [ 0.000000] Device empty62second # [ 0.000000] Movable zone start for each node63second # [ 0.000000] Early memory node ranges64second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]65second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]66second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000067second # [ 0.000000] psci: probing for conduit method from DT.68second # [ 0.000000] psci: PSCIv1.3 detected in firmware.69second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs70second # [ 0.000000] psci: Trusted OS migration not required71second # [ 0.000000] psci: SMC Calling Convention v1.172second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)73second # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129674second # [ 0.000000] Detected PIPT I-cache on CPU075second # [ 0.000000] CPU features: detected: GICv3 CPU interface76second # [ 0.000000] CPU features: detected: Spectre-v477second # [ 0.000000] CPU features: detected: Spectre-BHB78second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR79second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)80second # [ 0.000000] CPU features: detected: ARM erratum 141804081second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion82second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing83second # [ 0.000000] alternatives: applying boot alternatives84second # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/29awshk1m2c8c67w17wv6fs5di5qdlf8-nixos-system-second-test/init regInfo=/nix/store/vqxz7mv8pj7mwf1nk16gy63vr1n6v4zg-closure-info/registration console=ttyAMA0,115200n8 console=tty085test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]86second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/vqxz7mv8pj7mwf1nk16gy63vr1n6v4zg-closure-info/registration", will be passed to user space.87test # [ 0.000000] Linux version 6.18.46 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Sun Aug 23 12:27:10 UTC 202688test # [ 0.000000] KASLR enabled89second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes90test # [ 0.000000] random: crng init done91test # [ 0.000000] Machine model: linux,dummy-virt92second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)93test # [ 0.000000] efi: UEFI not found.94second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)95test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT96second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB97test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]98second # [ 0.000000] software IO TLB: area num 1.99test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]100test # [ 0.000000] Zone ranges:101second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)102second # [ 0.000000] Fallback order for Node 0: 0103test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]104test # [ 0.000000] DMA32 empty105test # [ 0.000000] Normal empty106second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144107test # [ 0.000000] Device empty108second # [ 0.000000] Policy zone: DMA109test # [ 0.000000] Movable zone start for each node110second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off111test # [ 0.000000] Early memory node ranges112second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1113test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]114second # [ 0.000000] allocated 2097152 bytes of page_ext115test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]116second # [ 0.000000] ftrace: allocating 74884 entries in 294 pages117test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000118second # [ 0.000000] ftrace: allocated 294 pages with 4 groups119test # [ 0.000000] psci: probing for conduit method from DT.120second # [ 0.000000] rcu: Hierarchical RCU implementation.121test # [ 0.000000] psci: PSCIv1.3 detected in firmware.122second # [ 0.000000] rcu: RCU event tracing is enabled.123test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs124second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.125test # [ 0.000000] psci: Trusted OS migration not required126second # [ 0.000000] Trampoline variant of Tasks RCU enabled.127test # [ 0.000000] psci: SMC Calling Convention v1.1128second # [ 0.000000] Rude variant of Tasks RCU enabled.129second # [ 0.000000] Tracing variant of Tasks RCU enabled.130second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.131second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1132second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.133second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.134test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)135test # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296136second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.137test # [ 0.000000] Detected PIPT I-cache on CPU0138test # [ 0.000000] CPU features: detected: GICv3 CPU interface139test # [ 0.000000] CPU features: detected: Spectre-v4140test # [ 0.000000] CPU features: detected: Spectre-BHB141test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)143test # [ 0.000000] CPU features: detected: ARM erratum 1418040144second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0145test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion146second # [ 0.000000] GICv3: 256 SPIs implemented147test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing148second # [ 0.000000] GICv3: 0 Extended SPIs implemented149second # [ 0.000000] Root IRQ handler: gic_handle_irq150test # [ 0.000000] alternatives: applying boot alternatives151second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI152second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0153second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000154second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]155second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)156test # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/irbs3f64vgnzp87r2b3s2xc8ay5135q3-nixos-system-test-test/init regInfo=/nix/store/4bxyavijz3613ljd0p880md3fprl54kd-closure-info/registration console=ttyAMA0,115200n8 console=tty0157second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)158second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000159test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4bxyavijz3613ljd0p880md3fprl54kd-closure-info/registration", will be passed to user space.160second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000161test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes162second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.163test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)164second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns165test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)166second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040167test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168test # [ 0.000000] software IO TLB: area num 1.169second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).170test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)171second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns172test # [ 0.000000] Fallback order for Node 0: 0173second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns174test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144175test # [ 0.000000] Policy zone: DMA176second # [ 0.000048] arm-pv: using stolen time PV177test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off178second # [ 0.000333] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)179test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1180second # [ 0.000577] Console: colour dummy device 80x25181test # [ 0.000000] allocated 2097152 bytes of page_ext182second # [ 0.000589] printk: legacy console [tty0] enabled183test # [ 0.000000] ftrace: allocating 74884 entries in 294 pages184test # [ 0.000000] ftrace: allocated 294 pages with 4 groups185second # [ 0.000792] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)186test # [ 0.000000] rcu: Hierarchical RCU implementation.187second # [ 0.000797] pid_max: default: 32768 minimum: 301188test # [ 0.000000] rcu: RCU event tracing is enabled.189second # [ 0.000876] LSM: initializing lsm=capability,landlock,yama,bpf,ima190test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.191second # [ 0.001009] landlock: Up and running.192second # [ 0.001012] Yama: becoming mindful.193test # [ 0.000000] Trampoline variant of Tasks RCU enabled.194second # [ 0.001384] LSM support for eBPF active195test # [ 0.000000] Rude variant of Tasks RCU enabled.196second # [ 0.001534] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)197test # [ 0.000000] Tracing variant of Tasks RCU enabled.198second # [ 0.001553] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)199test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.200second # [ 0.003326] cacheinfo: Unable to detect cache hierarchy for CPU 0201test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1202second # [ 0.004173] rcu: Hierarchical SRCU implementation.203test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.204second # [ 0.004178] rcu: Max phase no-delay instances is 1000.205second # [ 0.005139] fsl-mc MSI: its@8080000 domain created206test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.207second # [ 0.005294] EFI services will not be available.208second # [ 0.005377] smp: Bringing up secondary CPUs ...209test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.210second # [ 0.005404] smp: Brought up 1 node, 1 CPU211second # [ 0.005407] SMP: Total of 1 processors activated.212test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0213second # [ 0.005409] CPU: All CPU(s) started at EL1214test # [ 0.000000] GICv3: 256 SPIs implemented215test # [ 0.000000] GICv3: 0 Extended SPIs implemented216second # [ 0.005421] CPU features: detected: 32-bit EL0 Support217test # [ 0.000000] Root IRQ handler: gic_handle_irq218second # [ 0.005424] CPU features: detected: Data cache clean to the PoU not required for I/D coherence219test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI220test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0221second # [ 0.005427] CPU features: detected: Common not Private translations222second # [ 0.005429] CPU features: detected: CRC32 instructions223test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000224test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]225second # [ 0.005431] CPU features: detected: Data cache clean to Point of Persistence226second # [ 0.005433] CPU features: detected: Enhanced Virtualization Traps227test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)228second # [ 0.005443] CPU features: detected: RCpc load-acquire (LDAPR)229second # [ 0.005446] CPU features: detected: LSE atomic instructions230test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)231second # [ 0.005447] CPU features: detected: Privileged Access Never232test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000233second # [ 0.005449] CPU features: detected: PMUv3234test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000235second # [ 0.005451] CPU features: detected: RAS Extension Support236second # [ 0.005455] CPU features: detected: Speculative Store Bypassing Safe (SSBS)237test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.238second # [ 0.005488] alternatives: applying system-wide alternatives239test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns240second # [ 0.006862] CPU features: detected: Hardware dirty bit management on CPU0241test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040242test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).243second # [ 0.007085] Memory: 892316K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 114948K reserved, 32768K cma-reserved)244second # [ 0.007544] devtmpfs: initialized245test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns246second # [ 0.009001] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)247test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns248test # [ 0.000050] arm-pv: using stolen time PV249second # [ 0.009023] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).250second # [ 0.009158] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL251test # [ 0.000358] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)252second # [ 0.009162] 0 pages in range for non-PLT usage253test # [ 0.000614] Console: colour dummy device 80x25254second # [ 0.009163] 508288 pages in range for PLT usage255test # [ 0.000625] printk: legacy console [tty0] enabled256second # [ 0.009275] pinctrl core: initialized pinctrl subsystem257second # [ 0.010055] DMI not present or invalid.258test # [ 0.000829] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)259second # [ 0.013253] NET: Registered PF_NETLINK/PF_ROUTE protocol family260test # [ 0.000834] pid_max: default: 32768 minimum: 301261second # [ 0.015505] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations262test # [ 0.000925] LSM: initializing lsm=capability,landlock,yama,bpf,ima263test # [ 0.001083] landlock: Up and running.264second # [ 0.015636] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations265test # [ 0.001086] Yama: becoming mindful.266test # [ 0.001428] LSM support for eBPF active267second # [ 0.015787] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations268second # [ 0.015804] audit: initializing netlink subsys (disabled)269test # [ 0.001564] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)270second # [ 0.016139] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1271test # [ 0.001583] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)272second # [ 0.016438] thermal_sys: Registered thermal governor 'fair_share'273test # [ 0.003250] cacheinfo: Unable to detect cache hierarchy for CPU 0274test # [ 0.003951] rcu: Hierarchical SRCU implementation.275second # [ 0.016441] thermal_sys: Registered thermal governor 'bang_bang'276test # [ 0.003956] rcu: Max phase no-delay instances is 1000.277second # [ 0.016444] thermal_sys: Registered thermal governor 'step_wise'278test # [ 0.004997] fsl-mc MSI: its@8080000 domain created279second # [ 0.016445] thermal_sys: Registered thermal governor 'user_space'280test # [ 0.005155] EFI services will not be available.281second # [ 0.016448] thermal_sys: Registered thermal governor 'power_allocator'282test # [ 0.005279] smp: Bringing up secondary CPUs ...283second # [ 0.016478] cpuidle: using governor ladder284test # [ 0.005305] smp: Brought up 1 node, 1 CPU285second # [ 0.016485] cpuidle: using governor menu286test # [ 0.005307] SMP: Total of 1 processors activated.287test # [ 0.005309] CPU: All CPU(s) started at EL1288second # [ 0.016618] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.289test # [ 0.005322] CPU features: detected: 32-bit EL0 Support290second # [ 0.016641] ASID allocator initialised with 32768 entries291second # [ 0.017304] Serial: AMBA PL011 UART driver292test # [ 0.005325] CPU features: detected: Data cache clean to the PoU not required for I/D coherence293second # [ 0.020422] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1294test # [ 0.005328] CPU features: detected: Common not Private translations295second # [ 0.020557] printk: console [ttyAMA0] enabled296test # [ 0.005330] CPU features: detected: CRC32 instructions297test # [ 0.005333] CPU features: detected: Data cache clean to Point of Persistence298test # [ 0.005335] CPU features: detected: Enhanced Virtualization Traps299test # [ 0.005344] CPU features: detected: RCpc load-acquire (LDAPR)300test # [ 0.005346] CPU features: detected: LSE atomic instructions301test # [ 0.005348] CPU features: detected: Privileged Access Never302test # [ 0.005350] CPU features: detected: PMUv3303test # [ 0.005352] CPU features: detected: RAS Extension Support304test # [ 0.005355] CPU features: detected: Speculative Store Bypassing Safe (SSBS)305test # [ 0.005388] alternatives: applying system-wide alternatives306test # [ 0.006793] CPU features: detected: Hardware dirty bit management on CPU0307test # [ 0.006961] Memory: 892328K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 114932K reserved, 32768K cma-reserved)308test # [ 0.007396] devtmpfs: initialized309test # [ 0.008847] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)310test # [ 0.008870] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).311test # [ 0.009006] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL312test # [ 0.009010] 0 pages in range for non-PLT usage313test # [ 0.009011] 508288 pages in range for PLT usage314test # [ 0.009131] pinctrl core: initialized pinctrl subsystem315second # [ 0.093678] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages316test # [ 0.009865] DMI not present or invalid.317second # [ 0.093699] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page318test # [ 0.012995] NET: Registered PF_NETLINK/PF_ROUTE protocol family319second # [ 0.093706] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages320test # [ 0.015424] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations321second # [ 0.093709] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page322test # [ 0.015558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations323second # [ 0.093713] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages324second # [ 0.093716] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page325test # [ 0.015709] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations326second # [ 0.093720] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages327test # [ 0.015725] audit: initializing netlink subsys (disabled)328second # [ 0.093723] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page329test # [ 0.016107] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1330test # [ 0.016317] thermal_sys: Registered thermal governor 'fair_share'331test # [ 0.016318] thermal_sys: Registered thermal governor 'bang_bang'332test # [ 0.016322] thermal_sys: Registered thermal governor 'step_wise'333second # [ 0.098851] fbcon: Taking over console334test # [ 0.016324] thermal_sys: Registered thermal governor 'user_space'335second # [ 0.098870] ACPI: Interpreter disabled.336test # [ 0.016326] thermal_sys: Registered thermal governor 'power_allocator'337second # [ 0.100122] iommu: Default domain type: Translated338test # [ 0.016351] cpuidle: using governor ladder339test # [ 0.016357] cpuidle: using governor menu340second # [ 0.100132] iommu: DMA domain TLB invalidation policy: strict mode341test # [ 0.016488] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.342second # [ 0.101269] SCSI subsystem initialized343test # [ 0.016509] ASID allocator initialised with 32768 entries344test # [ 0.017171] Serial: AMBA PL011 UART driver345second # [ 0.101819] usbcore: registered new interface driver usbfs346test # [ 0.020326] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1347second # [ 0.101861] usbcore: registered new interface driver hub348test # [ 0.020458] printk: console [ttyAMA0] enabled349second # [ 0.101870] usbcore: registered new device driver usb350second # [ 0.102068] pps_core: LinuxPPS API ver. 1 registered351second # [ 0.102074] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>352second # [ 0.102080] PTP clock support registered353second # [ 0.102119] EDAC MC: Ver: 3.0.0354second # [ 0.105000] scmi_core: SCMI protocol bus registered355second # [ 0.105692] FPGA manager framework356test # [ 0.092681] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages357test # [ 0.092701] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page358test # [ 0.092708] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages359test # [ 0.092712] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page360test # [ 0.092715] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages361test # [ 0.092718] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page362test # [ 0.092721] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages363test # [ 0.092725] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page364second # [ 0.113812] vgaarb: loaded365second # [ 0.114283] clocksource: Switched to clocksource arch_sys_counter366test # [ 0.097898] fbcon: Taking over console367test # [ 0.097915] ACPI: Interpreter disabled.368test # [ 0.099152] iommu: Default domain type: Translated369test # [ 0.099162] iommu: DMA domain TLB invalidation policy: strict mode370test # [ 0.100343] SCSI subsystem initialized371test # [ 0.100995] usbcore: registered new interface driver usbfs372test # [ 0.101034] usbcore: registered new interface driver hub373test # [ 0.101044] usbcore: registered new device driver usb374test # [ 0.101238] pps_core: LinuxPPS API ver. 1 registered375second # [ 0.119347] VFS: Disk quotas dquot_6.6.0376test # [ 0.101245] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>377second # [ 0.119382] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)378test # [ 0.101251] PTP clock support registered379test # [ 0.101280] EDAC MC: Ver: 3.0.0380second # [ 0.120481] netfs: FS-Cache loaded381second # [ 0.120580] pnp: PnP ACPI: disabled382test # [ 0.108880] scmi_core: SCMI protocol bus registered383second # [ 0.124183] NET: Registered PF_INET protocol family384test # [ 0.109666] FPGA manager framework385second # [ 0.124387] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)386test # [ 0.110700] vgaarb: loaded387test # [ 0.111151] clocksource: Switched to clocksource arch_sys_counter388test # [ 0.121063] VFS: Disk quotas dquot_6.6.0389test # [ 0.121100] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390test # [ 0.123479] netfs: FS-Cache loaded391test # [ 0.123596] pnp: PnP ACPI: disabled392test # [ 0.126031] NET: Registered PF_INET protocol family393test # [ 0.126217] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394second # [ 0.148772] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395second # [ 0.148801] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396second # [ 0.148813] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397second # [ 0.148852] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398second # [ 0.148930] TCP: Hash tables configured (established 8192 bind 8192)399second # [ 0.149033] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400second # [ 0.149097] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401second # [ 0.149121] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402second # [ 0.149264] NET: Registered PF_UNIX/PF_LOCAL protocol family403second # [ 0.149307] NET: Registered PF_XDP protocol family404second # [ 0.149328] PCI: CLS 0 bytes, default 64405second # [ 0.149529] Trying to unpack rootfs image as initramfs...406second # [ 0.160176] kvm [1]: HYP mode not available407test # [ 0.150839] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)408test # [ 0.150870] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)409test # [ 0.150884] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)410test # [ 0.150923] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)411test # [ 0.150991] TCP: Hash tables configured (established 8192 bind 8192)412test # [ 0.151092] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)413test # [ 0.154783] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)414test # [ 0.154845] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)415test # [ 0.154980] NET: Registered PF_UNIX/PF_LOCAL protocol family416test # [ 0.155017] NET: Registered PF_XDP protocol family417test # [ 0.155039] PCI: CLS 0 bytes, default 64418test # [ 0.155244] Trying to unpack rootfs image as initramfs...419test # [ 0.165827] kvm [1]: HYP mode not available420second # [ 0.230654] Initialise system trusted keyrings421second # [ 0.231151] workingset: timestamp_bits=42 max_order=18 bucket_order=0422second # [ 0.232057] squashfs: version 4.0 (2009/01/31) Phillip Lougher423second # [ 0.232553] 9p: Installing v9fs 9p2000 file system support424test # [ 0.231609] Initialise system trusted keyrings425test # [ 0.232141] workingset: timestamp_bits=42 max_order=18 bucket_order=0426test # [ 0.233099] squashfs: version 4.0 (2009/01/31) Phillip Lougher427test # [ 0.233614] 9p: Installing v9fs 9p2000 file system support428second # [ 0.255476] Key type asymmetric registered429second # [ 0.255500] Asymmetric key parser 'x509' registered430second # [ 0.255551] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431second # [ 0.256855] io scheduler mq-deadline registered432second # [ 0.256867] io scheduler kyber registered433second # [ 0.266390] pl061_gpio 9030000.pl061: PL061 GPIO chip registered434test # [ 0.256501] Key type asymmetric registered435test # [ 0.256527] Asymmetric key parser 'x509' registered436test # [ 0.256587] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)437test # [ 0.258018] io scheduler mq-deadline registered438second # [ 0.267203] ledtrig-cpu: registered to indicate activity on CPUs439test # [ 0.258031] io scheduler kyber registered440second # [ 0.267517] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:441second # [ 0.267540] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000442second # [ 0.267564] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000443second # [ 0.267570] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000444second # [ 0.267605] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits445second # [ 0.267627] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]446second # [ 0.267702] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00447second # [ 0.267720] pci_bus 0000:00: root bus resource [bus 00-ff]448second # [ 0.267725] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]449second # [ 0.267729] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]450second # [ 0.267733] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]451second # [ 0.267815] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint452second # [ 0.268234] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint453second # [ 0.268364] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]454second # [ 0.268376] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]455second # [ 0.268396] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]456second # [ 0.268407] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]457second # [ 0.268724] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint458second # [ 0.268849] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]459second # [ 0.268860] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]460second # [ 0.268880] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]461second # [ 0.269201] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint462second # [ 0.269327] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]463second # [ 0.269338] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]464second # [ 0.269358] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]465second # [ 0.269665] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint466second # [ 0.269790] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]467second # [ 0.269801] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]468second # [ 0.269821] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]469second # [ 0.270132] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint470second # [ 0.270257] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]471second # [ 0.270269] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]472second # [ 0.270310] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473test # [ 0.267263] pl061_gpio 9030000.pl061: PL061 GPIO chip registered474second # [ 0.270624] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint475test # [ 0.267608] ledtrig-cpu: registered to indicate activity on CPUs476second # [ 0.270749] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]477second # [ 0.270760] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]478test # [ 0.267909] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:479second # [ 0.270781] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]480test # [ 0.267929] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000481second # [ 0.271087] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint482test # [ 0.267943] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000483second # [ 0.271213] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]484second # [ 0.271224] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]485test # [ 0.267949] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000486second # [ 0.271248] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]487test # [ 0.267982] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits488second # [ 0.271259] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]489test # [ 0.268004] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]490second # [ 0.271595] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint491test # [ 0.268081] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00492second # [ 0.271724] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]493test # [ 0.268099] pci_bus 0000:00: root bus resource [bus 00-ff]494second # [ 0.271745] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]495test # [ 0.268104] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]496second # [ 0.272063] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint497test # [ 0.268108] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]498second # [ 0.272190] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.268112] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]500second # [ 0.272210] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501test # [ 0.268226] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint502second # [ 0.272474] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint503second # [ 0.272596] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]504test # [ 0.268668] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint505test # [ 0.268799] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]506second # [ 0.272764] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint507test # [ 0.268811] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]508second # [ 0.273013] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]509second # [ 0.273026] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]510test # [ 0.268831] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511second # [ 0.273047] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512test # [ 0.268841] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]513second # [ 0.273368] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint514test # [ 0.269173] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint515test # [ 0.269302] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]516second # [ 0.273496] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]517second # [ 0.273507] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]518test # [ 0.269314] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]519test # [ 0.269335] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520second # [ 0.273528] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521second # [ 0.273943] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned522test # [ 0.269657] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint523second # [ 0.273953] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned524test # [ 0.269785] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]525test # [ 0.269796] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]526second # [ 0.273958] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned527test # [ 0.269816] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528second # [ 0.273991] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned529test # [ 0.270129] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint530second # [ 0.274023] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned531test # [ 0.270256] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]532second # [ 0.274054] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned533test # [ 0.270268] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]534test # [ 0.270288] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535second # [ 0.274085] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned536test # [ 0.270619] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint537second # [ 0.274116] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned538test # [ 0.270749] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]539second # [ 0.274147] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned540test # [ 0.270761] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]541second # [ 0.274179] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned542test # [ 0.270781] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543second # [ 0.274210] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned544test # [ 0.271098] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint545test # [ 0.271246] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]546second # [ 0.274241] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned547test # [ 0.271258] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]548test # [ 0.271278] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549test # [ 0.271594] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint550test # [ 0.271723] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]551test # [ 0.271734] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]552test # [ 0.271758] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553test # [ 0.271768] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]554test # [ 0.272117] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint555test # [ 0.272255] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]556test # [ 0.272275] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557test # [ 0.272599] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint558test # [ 0.272739] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]559second # [ 0.314370] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned560test # [ 0.272760] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]561second # [ 0.314538] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned562test # [ 0.273034] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint563second # [ 0.314554] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned564test # [ 0.273158] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]565second # [ 0.314569] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned566test # [ 0.273329] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint567second # [ 0.314584] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned568test # [ 0.273543] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]569second # [ 0.314599] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned570test # [ 0.273556] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]571second # [ 0.314613] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned572test # [ 0.273576] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573second # [ 0.314628] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned574test # [ 0.273895] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint575second # [ 0.314643] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned576test # [ 0.274024] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]577second # [ 0.314658] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned578test # [ 0.274035] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]579second # [ 0.314672] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned580test # [ 0.274055] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]581second # [ 0.314687] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned582test # [ 0.274480] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned583second # [ 0.314701] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned584test # [ 0.274491] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned585second # [ 0.314715] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned586test # [ 0.274496] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned587second # [ 0.314728] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned588second # [ 0.314742] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned589test # [ 0.274529] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned590second # [ 0.314756] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned591test # [ 0.274561] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned592second # [ 0.314774] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned593test # [ 0.274592] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned594second # [ 0.314788] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned595second # [ 0.314802] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned596test # [ 0.274623] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned597second # [ 0.314816] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned598test # [ 0.274654] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned599second # [ 0.314829] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned600second # [ 0.314851] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]601test # [ 0.274685] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned602second # [ 0.314857] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]603test # [ 0.274716] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned604second # [ 0.314861] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]605test # [ 0.274747] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned606second # [ 0.315626] pci 0000:00:0a.0: enabling device (0000 -> 0002)607test # [ 0.274778] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned608test # [ 0.274833] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned609test # [ 0.274894] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned610test # [ 0.274908] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned611test # [ 0.274922] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned612test # [ 0.274936] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned613test # [ 0.274949] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned614test # [ 0.274963] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned615test # [ 0.274977] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned616test # [ 0.274990] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned617test # [ 0.275004] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned618test # [ 0.275017] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned619test # [ 0.275031] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned620test # [ 0.275045] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned621test # [ 0.275059] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned622test # [ 0.275072] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned623test # [ 0.275086] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned624test # [ 0.275099] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned625test # [ 0.275117] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned626test # [ 0.275130] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned627test # [ 0.275144] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned628second # [ 0.339084] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)629second # [ 0.341112] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)630test # [ 0.331218] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned631test # [ 0.331243] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned632test # [ 0.331269] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]633test # [ 0.331275] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]634test # [ 0.331278] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]635test # [ 0.332047] pci 0000:00:0a.0: enabling device (0000 -> 0002)636second # [ 0.350759] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)637second # [ 0.352801] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)638second # [ 0.358717] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)639second # [ 0.360571] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)640test # [ 0.349025] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)641test # [ 0.350934] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)642test # [ 0.353786] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)643second # [ 0.371387] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)644second # [ 0.373431] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)645test # [ 0.356405] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)646test # [ 0.358348] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)647second # [ 0.376467] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)648second # [ 0.378082] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)649test # [ 0.368194] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)650test # [ 0.370044] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)651second # [ 0.387516] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)652second # [ 0.395075] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled653test # [ 0.375934] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)654test # [ 0.377856] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)655second # [ 0.396546] msm_serial: driver initialized656second # [ 0.396620] SuperH (H)SCI(F) driver initialized657second # [ 0.396655] STM32 USART driver initialized658test # [ 0.388325] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)659test # [ 0.390457] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)660test # [ 0.402964] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661second # [ 0.419358] loop: module loaded662second # [ 0.419493] virtio_blk virtio5: 1/0/0 default/read/poll queues663second # [ 0.420017] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)664test # [ 0.404525] msm_serial: driver initialized665test # [ 0.404617] SuperH (H)SCI(F) driver initialized666test # [ 0.404669] STM32 USART driver initialized667second # [ 0.426706] megasas: 07.734.00.00-rc1668second # [ 0.427184] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]669second # [ 0.429166] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000670second # [ 0.429186] Intel/Sharp Extended Query Table at 0x0031671second # [ 0.430842] Using buffer write method672second # [ 0.430894] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]673second # [ 0.432620] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000674second # [ 0.432639] Intel/Sharp Extended Query Table at 0x0031675test # [ 0.429437] loop: module loaded676test # [ 0.429587] virtio_blk virtio5: 1/0/0 default/read/poll queues677test # [ 0.430124] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)678second # [ 0.450326] Using buffer write method679second # [ 0.450353] Concatenating MTD devices:680second # [ 0.450356] (0): "0.flash"681second # [ 0.450359] (1): "0.flash"682second # [ 0.450362] into device "0.flash"683test # [ 0.435589] megasas: 07.734.00.00-rc1684test # [ 0.436077] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685test # [ 0.460327] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686test # [ 0.460357] Intel/Sharp Extended Query Table at 0x0031687test # [ 0.461970] Using buffer write method688test # [ 0.462043] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689test # [ 0.466089] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690test # [ 0.466110] Intel/Sharp Extended Query Table at 0x0031691test # [ 0.468893] Using buffer write method692test # [ 0.468917] Concatenating MTD devices:693test # [ 0.468920] (0): "0.flash"694test # [ 0.468923] (1): "0.flash"695test # [ 0.468925] into device "0.flash"696second # [ 0.645269] Freeing initrd memory: 26144K697second # [ 0.651940] tun: Universal TUN/TAP device driver, 1.6698second # [ 0.654611] thunder_xcv, ver 1.0699second # [ 0.654635] thunder_bgx, ver 1.0700second # [ 0.654647] nicpf, ver 1.0701second # [ 0.654934] e1000: Intel(R) PRO/1000 Network Driver702second # [ 0.654942] e1000: Copyright (c) 1999-2006 Intel Corporation.703second # [ 0.654956] e1000e: Intel(R) PRO/1000 Network Driver704second # [ 0.654962] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.705second # [ 0.654975] igb: Intel(R) Gigabit Ethernet Network Driver706second # [ 0.654978] igb: Copyright (c) 2007-2014 Intel Corporation.707second # [ 0.654989] igbvf: Intel(R) Gigabit Virtual Function Network Driver708second # [ 0.654992] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.709second # [ 0.655054] sky2: driver version 1.30710second # [ 0.655817] usbcore: registered new interface driver usb-storage711second # [ 0.655907] usbcore: registered new interface driver usbserial_generic712second # [ 0.655916] usbserial: USB Serial support registered for generic713second # [ 0.656193] hv_vmbus: registering driver hyperv_keyboard714second # [ 0.657213] ehci-pci 0000:00:0a.0: EHCI Host Controller715second # [ 0.657244] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716second # [ 0.657436] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717second # [ 0.665058] rtc-pl031 9010000.pl031: registered as rtc0718second # [ 0.665097] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T20:11:43 UTC (1787861503)719second # [ 0.665262] i2c_dev: i2c /dev entries driver720second # [ 0.667159] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00721second # [ 0.667490] hub 1-0:1.0: USB hub found722second # [ 0.667512] hub 1-0:1.0: 6 ports detected723second # [ 0.669629] sdhci: Secure Digital Host Controller Interface driver724second # [ 0.669644] sdhci: Copyright(c) Pierre Ossman725second # [ 0.669772] Synopsys Designware Multimedia Card Interface Driver726second # [ 0.669932] sdhci-pltfm: SDHCI platform and OF driver helper727test # [ 0.662891] Freeing initrd memory: 26128K728test # [ 0.669672] tun: Universal TUN/TAP device driver, 1.6729test # [ 0.672240] thunder_xcv, ver 1.0730test # [ 0.672267] thunder_bgx, ver 1.0731test # [ 0.672279] nicpf, ver 1.0732test # [ 0.672579] e1000: Intel(R) PRO/1000 Network Driver733test # [ 0.672587] e1000: Copyright (c) 1999-2006 Intel Corporation.734test # [ 0.672602] e1000e: Intel(R) PRO/1000 Network Driver735test # [ 0.672608] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.736test # [ 0.672620] igb: Intel(R) Gigabit Ethernet Network Driver737test # [ 0.672637] igb: Copyright (c) 2007-2014 Intel Corporation.738test # [ 0.672649] igbvf: Intel(R) Gigabit Virtual Function Network Driver739test # [ 0.672653] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.740test # [ 0.672721] sky2: driver version 1.30741test # [ 0.673531] usbcore: registered new interface driver usb-storage742test # [ 0.673584] usbcore: registered new interface driver usbserial_generic743test # [ 0.673592] usbserial: USB Serial support registered for generic744test # [ 0.673874] hv_vmbus: registering driver hyperv_keyboard745test # [ 0.681199] ehci-pci 0000:00:0a.0: EHCI Host Controller746test # [ 0.681237] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1747test # [ 0.681463] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000748test # [ 0.683045] rtc-pl031 9010000.pl031: registered as rtc0749test # [ 0.683086] rtc-pl031 9010000.pl031: setting system clock to 2026-08-27T20:11:43 UTC (1787861503)750test # [ 0.684383] i2c_dev: i2c /dev entries driver751test # [ 0.686105] sdhci: Secure Digital Host Controller Interface driver752test # [ 0.686120] sdhci: Copyright(c) Pierre Ossman753test # [ 0.686257] Synopsys Designware Multimedia Card Interface Driver754test # [ 0.686424] sdhci-pltfm: SDHCI platform and OF driver helper755test # [ 0.689005] hid: raw HID events driver (C) Jiri Kosina756second # [ 0.672477] hid: raw HID events driver (C) Jiri Kosina757test # [ 0.689182] usbcore: registered new interface driver usbhid758second # [ 0.672628] usbcore: registered new interface driver usbhid759test # [ 0.689188] usbhid: USB HID core driver760second # [ 0.672633] usbhid: USB HID core driver761test # [ 0.701968] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00762test # [ 0.702321] hub 1-0:1.0: USB hub found763test # [ 0.702344] hub 1-0:1.0: 6 ports detected764second # [ 0.718427] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available765second # [ 0.719199] drop_monitor: Initializing network drop monitor service766second # [ 0.719374] NET: Registered PF_INET6 protocol family767test # [ 0.703677] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available768second # [ 0.721511] Segment Routing with IPv6769test # [ 0.704439] drop_monitor: Initializing network drop monitor service770second # [ 0.721537] In-situ OAM (IOAM) with IPv6771test # [ 0.704553] NET: Registered PF_INET6 protocol family772second # [ 0.721587] NET: Registered PF_PACKET protocol family773test # [ 0.706722] Segment Routing with IPv6774test # [ 0.706749] In-situ OAM (IOAM) with IPv6775test # [ 0.706798] NET: Registered PF_PACKET protocol family776second # [ 0.722703] 9pnet: Installing 9P2000 support777second # [ 0.724202] Key type dns_resolver registered778test # [ 0.707906] 9pnet: Installing 9P2000 support779test # [ 0.709378] Key type dns_resolver registered780second # [ 0.727725] registered taskstats version 1781second # [ 0.727891] Loading compiled-in X.509 certificates782test # [ 0.712885] registered taskstats version 1783test # [ 0.713065] Loading compiled-in X.509 certificates784second # [ 0.733184] Demotion targets for Node 0: null785second # [ 0.733251] Key type .fscrypt registered786second # [ 0.733256] Key type fscrypt-provisioning registered787second # [ 0.733362] ima: No TPM chip found, activating TPM-bypass!788second # [ 0.733375] ima: Allocated hash algorithm: sha1789test # [ 0.718394] Demotion targets for Node 0: null790second # [ 0.733426] ima: No architecture policies found791test # [ 0.718461] Key type .fscrypt registered792test # [ 0.718467] Key type fscrypt-provisioning registered793test # [ 0.718573] ima: No TPM chip found, activating TPM-bypass!794test # [ 0.718586] ima: Allocated hash algorithm: sha1795second # [ 0.736319] input: gpio-keys as /devices/platform/gpio-keys/input/input0796test # [ 0.718637] ima: No architecture policies found797test # [ 0.721401] input: gpio-keys as /devices/platform/gpio-keys/input/input0798second # [ 0.746451] clk: Disabling unused clocks799second # [ 0.746467] PM: genpd: Disabling unused power domains800test # [ 0.731785] clk: Disabling unused clocks801second # [ 0.749552] Freeing unused kernel memory: 4736K802test # [ 0.731801] PM: genpd: Disabling unused power domains803second # [ 0.749644] Run /init as init process804test # [ 0.734902] Freeing unused kernel memory: 4736K805test # [ 0.735003] Run /init as init process806second # [ 0.769052] systemd[1]: Successfully made /usr/ read-only.807test # [ 0.753746] systemd[1]: Successfully made /usr/ read-only.808second # [ 0.938342] usb 1-1: new high-speed USB device number 2 using ehci-pci809test # [ 0.947206] usb 1-1: new high-speed USB device number 2 using ehci-pci810second # [ 1.090365] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1811second # [ 1.103963] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)812test # [ 1.088276] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)813second # [ 1.110916] systemd[1]: Detected virtualization qemu.814test # [ 1.095094] systemd[1]: Detected virtualization qemu.815second # [ 1.112015] systemd[1]: Detected architecture arm64.816second # [ 1.113140] systemd[1]: Running in initrd.817test # [ 1.096757] systemd[1]: Detected architecture arm64.818test # [ 1.098008] systemd[1]: Running in initrd.819second # [ 1.114902] systemd[1]: Initializing machine ID from random generator.820test # [ 1.100121] systemd[1]: Initializing machine ID from random generator.821second # [ 1.116597] systemd[1]: Hostname set to <second>.822test # [ 1.102168] systemd[1]: Hostname set to <test>.823test # [ 1.111318] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1824second # [ 1.170485] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825test # [ 1.195381] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826second # [ 1.290324] usb 1-2: new high-speed USB device number 3 using ehci-pci827test # [ 1.315353] usb 1-2: new high-speed USB device number 3 using ehci-pci828second # [ 1.383247] systemd[1]: bpf-restrict-fs: LSM BPF program attached829test # [ 1.380313] systemd[1]: bpf-restrict-fs: LSM BPF program attached830second # [ 1.443960] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2831second # [ 1.446507] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0832test # [ 1.445824] systemd[1]: Queued start job for default target Initrd Default Target.833test # [ 1.452598] systemd[1]: Created slice Slice /system/modprobe.834test # [ 1.453271] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.835test # [ 1.454027] systemd[1]: Expecting device /dev/disk/by-label/nixos...836test # [ 1.454569] systemd[1]: Reached target Path Units.837test # [ 1.454982] systemd[1]: Reached target Slice Units.838test # [ 1.455499] systemd[1]: Reached target Swaps.839test # [ 1.455885] systemd[1]: Reached target Timer Units.840test # [ 1.456428] systemd[1]: Listening on D-Bus System Message Bus Socket.841test # [ 1.457105] systemd[1]: Listening on Journal Socket (/dev/log).842test # [ 1.457769] systemd[1]: Listening on Journal Sockets.843test # [ 1.458277] systemd[1]: Listening on udev Control Socket.844test # [ 1.458827] systemd[1]: Listening on udev Kernel Socket.845test # [ 1.459319] systemd[1]: Reached target Socket Units.846test # [ 1.460807] systemd[1]: Starting Create List of Static Device Nodes...847test # [ 1.464607] systemd[1]: Starting Load Kernel Module 9pnet_virtio...848test # [ 1.465380] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs849second # [ 1.481919] systemd[1]: Queued start job for default target Initrd Default Target.850test # [ 1.470899] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2851test # [ 1.472161] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0852test # [ 1.476371] systemd[1]: Mounting Kernel Configuration File System...853second # [ 1.492591] systemd[1]: Created slice Slice /system/modprobe.854second # [ 1.493745] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.855second # [ 1.494978] systemd[1]: Expecting device /dev/disk/by-label/nixos...856second # [ 1.495030] systemd[1]: Reached target Path Units.857second # [ 1.495062] systemd[1]: Reached target Slice Units.858second # [ 1.495092] systemd[1]: Reached target Swaps.859second # [ 1.495121] systemd[1]: Reached target Timer Units.860second # [ 1.495376] systemd[1]: Listening on D-Bus System Message Bus Socket.861second # [ 1.495548] systemd[1]: Listening on Journal Socket (/dev/log).862second # [ 1.495692] systemd[1]: Listening on Journal Sockets.863second # [ 1.495812] systemd[1]: Listening on udev Control Socket.864second # [ 1.495960] systemd[1]: Listening on udev Kernel Socket.865second # [ 1.496001] systemd[1]: Reached target Socket Units.866second # [ 1.505710] systemd[1]: Starting Create List of Static Device Nodes...867second # [ 1.514427] systemd[1]: Starting Load Kernel Module 9pnet_virtio...868test # [ 1.499351] systemd[1]: Starting Journal Service...869second # [ 1.515407] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs870test # [ 1.500823] systemd[1]: Starting Load Kernel Modules...871test # [ 1.501336] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os872second # [ 1.522518] systemd[1]: Mounting Kernel Configuration File System...873test # [ 1.507061] systemd[1]: Starting Coldplug All udev Devices...874test # [ 1.519228] systemd[1]: Finished Create List of Static Device Nodes.875test # [ 1.520035] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.876test # [ 1.520239] systemd[1]: Finished Load Kernel Module 9pnet_virtio.877test # [ 1.520406] systemd[1]: Mounted Kernel Configuration File System.878second # [ 1.538590] systemd[1]: Starting Journal Service...879test # [ 1.527547] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...880second # [ 1.562088] systemd[1]: Starting Load Kernel Modules...881second # [ 1.562854] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os882second # [ 1.566656] systemd[1]: Starting Coldplug All udev Devices...883test # [ 1.557490] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.884second # [ 1.578511] systemd[1]: Finished Create List of Static Device Nodes.885second # [ 1.579359] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.886test # [ 1.567599] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.887second # [ 1.585748] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.888test # [ 1.569331] systemd[1]: Starting Create Static Device Nodes in /dev...889test # [ 1.573456] systemd-journald[73]: Collecting audit messages is disabled.890second # [ 1.590586] systemd[1]: Finished Load Kernel Module 9pnet_virtio.891test # [ 1.575242] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev892second # [ 1.590869] systemd[1]: Mounted Kernel Configuration File System.893second # [ 1.592915] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...894test # [ 1.583112] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0895second # [ 1.600579] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev896second # [ 1.606194] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0897test # [ 1.595393] [drm] features: -virgl +edid -resource_blob -host_visible898test # [ 1.595404] [drm] features: -context_init899test # [ 1.595888] [drm] number of scanouts: 1900test # [ 1.595902] [drm] number of cap sets: 0901second # [ 1.622537] [drm] features: -virgl +edid -resource_blob -host_visible902second # [ 1.622550] [drm] features: -context_init903test # [ 1.597845] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic904second # [ 1.623041] [drm] number of scanouts: 1905second # [ 1.623055] [drm] number of cap sets: 0906test # [ 1.597858] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0907second # [ 1.630569] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic908second # [ 1.630583] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0909test # [ 1.619624] systemd[1]: Finished Create Static Device Nodes in /dev.910test # [ 1.619813] systemd[1]: Reached target Preparation for Local File Systems.911test # [ 1.619843] systemd[1]: Reached target Local File Systems.912second # [ 1.637068] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.913test # [ 1.622497] Console: switching to colour frame buffer device 160x50914test # [ 1.626050] systemd[1]: Starting Rule-based Manager for Device Events and Files...915second # [ 1.638919] systemd[1]: Starting Create Static Device Nodes in /dev...916test # [ 1.639741] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device917second # [ 1.657041] Console: switching to colour frame buffer device 160x50918second # [ 1.657573] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device919second # [ 1.659996] systemd-journald[73]: Collecting audit messages is disabled.920second # [ 1.666656] systemd[1]: Finished Load Kernel Modules.921second # [ 1.668501] systemd[1]: Starting Apply Kernel Variables...922test # [ 1.647683] systemd-modules-load[74]: Inserted module 'dm_mod'923test # [ 1.648682] systemd-modules-load[74]: Module 'virtio_balloon' is built in924test # [ 1.656564] systemd[1]: Finished Load Kernel Modules.925test # [ 1.657725] systemd[1]: Started Journal Service.926test # [ 1.652236] systemd-modules-load[74]: Module 'virtio_console' is built in927test # [ 1.653825] systemd-modules-load[74]: Inserted module 'virtio_gpu'928second # [ 1.682434] systemd[1]: Finished Create Static Device Nodes in /dev.929second # [ 1.682746] systemd[1]: Reached target Preparation for Local File Systems.930second # [ 1.682791] systemd[1]: Reached target Local File Systems.931test # [ 1.661177] systemd-modules-load[74]: Module 'virtio_rng' is built in932test # [ 1.664191] systemd[1]: Starting Apply Kernel Variables...933second # [ 1.694542] systemd[1]: Starting Rule-based Manager for Device Events and Files...934test # [ 1.680890] systemd[1]: Starting Create System Files and Directories...935second # [ 1.711345] systemd[1]: Finished Apply Kernel Variables.936test # [ 1.692324] systemd-udevd[79]: Using default interface naming scheme 'v261'.937second # [ 1.727220] systemd[1]: Started Journal Service.938second # [ 1.727556] systemd-modules-load[74]: Inserted module 'dm_mod'939test # [ 1.715029] systemd[1]: Finished Apply Kernel Variables.940second # [ 1.732215] systemd-modules-load[74]: Module 'virtio_balloon' is built in941second # [ 1.733012] systemd-modules-load[74]: Module 'virtio_console' is built in942second # [ 1.733703] systemd-modules-load[74]: Inserted module 'virtio_gpu'943second # [ 1.734327] systemd-modules-load[74]: Module 'virtio_rng' is built in944second # [ 1.734977] systemd[1]: Starting Create System Files and Directories...945test # [ 1.720518] systemd[1]: Finished Create System Files and Directories.946test # [ 1.732094] systemd[1]: Started Rule-based Manager for Device Events and Files.947second # [ 1.761408] systemd[1]: Finished Create System Files and Directories.948second # [ 1.771540] systemd-udevd[81]: Using default interface naming scheme 'v261'.949second # [ 1.788341] systemd[1]: Started Rule-based Manager for Device Events and Files.950test # [ 1.783047] systemd[1]: Starting Virtual Console Setup...951second # [ 1.829555] systemd[1]: Starting Virtual Console Setup...952test # [ 1.824398] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.953test # [ 1.826511] systemd[1]: Finished Virtual Console Setup.954second # [ 1.864339] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.955second # [ 1.866608] systemd[1]: Finished Virtual Console Setup.956test # [ 2.073025] systemd[1]: Finished Coldplug All udev Devices.957test # [ 2.074828] systemd[1]: Reached target System Initialization.958test # [ 2.075448] systemd[1]: Reached target Basic System.959second # [ 2.118554] systemd[1]: Finished Coldplug All udev Devices.960second # [ 2.119782] systemd[1]: Reached target System Initialization.961second # [ 2.124453] systemd[1]: Reached target Basic System.962test # [ 2.182473] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.963test # [ 2.211932] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.964second # [ 2.231114] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.965second # [ 2.255308] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.966test # [ 2.264332] systemd[1]: Found device /dev/disk/by-label/nixos.967test # [ 2.266540] systemd[1]: Reached target Initrd Root Device.968test # [ 2.270214] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...969second # [ 2.338001] systemd[1]: Found device /dev/disk/by-label/nixos.970second # [ 2.339572] systemd[1]: Reached target Initrd Root Device.971second # [ 2.340960] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...972test # [ 2.324624] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks973test # [ 2.329938] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974second # [ 2.385912] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks975second # [ 2.391160] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.976test # [ 2.512235] systemd[1]: Mounting /sysroot...977test # [ 2.540558] EXT4-fs (vda): mounted filesystem f33491b6-5fa3-4aeb-a71d-8c591ce8428d r/w with ordered data mode. Quota mode: none.978test # [ 2.536079] systemd[1]: Mounted /sysroot.979test # [ 2.536935] systemd[1]: Reached target Initrd Root File System.980test # [ 2.540137] systemd[1]: Mounting /sysroot/nix/.ro-store...981second # [ 2.564499] systemd[1]: Mounting /sysroot...982test # [ 2.550765] systemd[1]: Mounting /sysroot/nix/.rw-store...983test # [ 2.562360] systemd[1]: Mounting /sysroot/run...984second # [ 2.597448] EXT4-fs (vda): mounted filesystem 53c27437-8307-4a34-93b6-404d6ef248d0 r/w with ordered data mode. Quota mode: none.985second # [ 2.595848] systemd[1]: Mounted /sysroot.986second # [ 2.597471] systemd[1]: Reached target Initrd Root File System.987second # [ 2.602014] systemd[1]: Mounting /sysroot/nix/.ro-store...988test # [ 2.586699] systemd[1]: Mounting /sysroot/tmp/shared...989second # [ 2.614463] systemd[1]: Mounting /sysroot/nix/.rw-store...990test # [ 2.602493] systemd[1]: Mounting /sysroot/tmp/xchg...991second # [ 2.626807] systemd[1]: Mounting /sysroot/run...992test # [ 2.614692] systemd[1]: Starting Mountpoints Configured in the Real Root...993test # [ 2.618853] systemd[1]: Mounted /sysroot/nix/.ro-store.994test # [ 2.620287] systemd[1]: Mounted /sysroot/nix/.rw-store.995test # [ 2.622274] systemd[1]: Mounted /sysroot/run.996second # [ 2.645201] systemd[1]: Mounting /sysroot/tmp/shared...997test # [ 2.644320] systemd[1]: Starting rw-sysroot-nix-store.service...998second # [ 2.661634] systemd[1]: Mounting /sysroot/tmp/xchg...999test # [ 2.646107] systemd-sysroot-fstab-check[125]: /sysroot should be mounted in the initrd, will request daemon-reload.1000second # [ 2.674278] systemd[1]: Starting Mountpoints Configured in the Real Root...1001second # [ 2.679352] systemd[1]: Mounted /sysroot/nix/.ro-store.1002test # [ 2.663242] systemd[1]: Mounted /sysroot/tmp/shared.1003second # [ 2.681401] systemd[1]: Mounted /sysroot/nix/.rw-store.1004test # [ 2.665628] systemd[1]: Mounted /sysroot/tmp/xchg.1005second # [ 2.683240] systemd[1]: Mounted /sysroot/run.1006test # [ 2.668443] systemd[1]: Reload requested from client PID 125 ('systemd-sysroot') (unit initrd-parse-etc.service)...1007test # [ 2.673038] systemd[1]: Reloading...1008second # [ 2.708123] systemd[1]: Starting rw-sysroot-nix-store.service...1009second # [ 2.712195] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1010second # [ 2.724292] systemd[1]: Mounted /sysroot/tmp/shared.1011second # [ 2.726151] systemd[1]: Mounted /sysroot/tmp/xchg.1012second # [ 2.728312] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1013second # [ 2.736060] systemd[1]: Reloading...1014test # [ 2.844500] systemd[1]: Reloading finished in 176 ms.1015test # [ 2.872649] systemd-sysroot-fstab-check[125]: Requesting initrd-fs.target/start/replace...1016test # [ 2.874286] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1017test # [ 2.876266] systemd[1]: Finished rw-sysroot-nix-store.service.1018test # [ 2.879088] systemd-sysroot-fstab-check[125]: Requesting swap.target/start/replace...1019test # [ 2.882620] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1020test # [ 2.884173] systemd[1]: Finished Mountpoints Configured in the Real Root.1021test # [ 2.887795] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1022second # [ 2.907038] systemd[1]: Reloading finished in 173 ms.1023test # [ 2.894240] systemd[1]: Starting rw-sysroot-nix-store.service...1024test # [ 2.911071] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1025test # [ 2.911983] systemd[1]: Finished rw-sysroot-nix-store.service.1026second # [ 2.934854] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1027second # [ 2.937304] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1028second # [ 2.938860] systemd[1]: Finished rw-sysroot-nix-store.service.1029second # [ 2.941483] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1030second # [ 2.950302] systemd[1]: Starting rw-sysroot-nix-store.service...1031second # [ 2.951784] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1032second # [ 2.953955] systemd[1]: Finished Mountpoints Configured in the Real Root.1033second # [ 2.955556] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1034second # [ 2.969974] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1035second # [ 2.970863] systemd[1]: Finished rw-sysroot-nix-store.service.1036test # [ 3.150998] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1037test # [ 3.157516] (udev-worker)[102]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1038test # [ 3.164266] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1039test # [ 3.164967] systemd[1]: Stopped Virtual Console Setup.1040test # [ 3.166660] systemd[1]: Stopping Virtual Console Setup...1041test # [ 3.168257] systemd[1]: Starting Virtual Console Setup...1042second # [ 3.186890] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1043second # [ 3.191813] (udev-worker)[100]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1044test # [ 3.195260] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.1045second # [ 3.214301] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1046test # [ 3.197707] systemd[1]: Finished Virtual Console Setup.1047second # [ 3.216845] systemd[1]: Stopped Virtual Console Setup.1048second # [ 3.217510] systemd[1]: Stopping Virtual Console Setup...1049second # [ 3.220831] systemd[1]: Starting Virtual Console Setup...1050second # [ 3.239456] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones.1051second # [ 3.241877] systemd[1]: Finished Virtual Console Setup.1052test # [ 3.511952] systemd[1]: Mounting /sysroot/nix/store...1053second # [ 3.563184] systemd[1]: Mounting /sysroot/nix/store...1054test # [ 3.558706] systemd[1]: Mounted /sysroot/nix/store.1055test # [ 3.560921] systemd[1]: Reached target Initrd File Systems.1056test # [ 3.564800] systemd[1]: Starting Find NixOS closure...1057test # [ 3.576305] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1058second # [ 3.597205] systemd[1]: Mounted /sysroot/nix/store.1059second # [ 3.598633] systemd[1]: Reached target Initrd File Systems.1060second # [ 3.600869] systemd[1]: Starting Find NixOS closure...1061second # [ 3.608205] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062test # [ 3.618029] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1063test # [ 3.619185] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1064second # [ 3.639699] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1065second # [ 3.642048] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1066test # [ 3.632434] systemd[1]: Finished Find NixOS closure.1067test # [ 3.634206] systemd[1]: Reached target Initrd Default Target.1068test # [ 3.635241] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1069second # [ 3.655968] systemd[1]: Finished Find NixOS closure.1070second # [ 3.657368] systemd[1]: Reached target Initrd Default Target.1071second # [ 3.659774] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1072test # [ 3.664961] systemd[1]: Stopped target Initrd Default Target.1073test # [ 3.666252] systemd[1]: Stopped target Basic System.1074test # [ 3.667218] systemd[1]: Stopped target Initrd Root Device.1075test # [ 3.668290] systemd[1]: Stopped target Path Units.1076test # [ 3.669195] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077second # [ 3.688907] systemd[1]: Stopped target Initrd Default Target.1078test # [ 3.672257] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1079test # [ 3.673299] systemd[1]: Stopped target Slice Units.1080test # [ 3.674811] systemd[1]: Stopped target Socket Units.1081second # [ 3.692328] systemd[1]: Stopped target Basic System.1082second # [ 3.693020] systemd[1]: Stopped target Initrd Root Device.1083test # [ 3.676104] systemd[1]: Stopped target System Initialization.1084second # [ 3.693993] systemd[1]: Stopped target Path Units.1085second # [ 3.696142] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1086test # [ 3.680131] systemd[1]: Stopped target Swaps.1087test # [ 3.680697] systemd[1]: Stopped target Timer Units.1088test # [ 3.681278] systemd[1]: dbus.socket: Deactivated successfully.1089test # [ 3.682002] systemd[1]: Closed D-Bus System Message Bus Socket.1090second # [ 3.700116] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1091test # [ 3.683229] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1092second # [ 3.701031] systemd[1]: Stopped target Slice Units.1093second # [ 3.701861] systemd[1]: Stopped target Socket Units.1094test # [ 3.684790] systemd[1]: Stopped Find NixOS closure.1095second # [ 3.702419] systemd[1]: Stopped target System Initialization.1096second # [ 3.703954] systemd[1]: Stopped target Swaps.1097test # [ 3.687152] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1098second # [ 3.705143] systemd[1]: Stopped target Timer Units.1099test # [ 3.688751] systemd[1]: Starting rw-sysroot-nix-store.service...1100second # [ 3.708168] systemd[1]: dbus.socket: Deactivated successfully.1101second # [ 3.708879] systemd[1]: Closed D-Bus System Message Bus Socket.1102test # [ 3.692093] systemd[1]: systemd-sysctl.service: Deactivated successfully.1103second # [ 3.709544] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1104second # [ 3.711369] systemd[1]: Stopped Find NixOS closure.1105test # [ 3.694608] systemd[1]: Stopped Apply Kernel Variables.1106test # [ 3.695167] systemd[1]: systemd-modules-load.service: Deactivated successfully.1107second # [ 3.713663] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1108second # [ 3.714365] systemd[1]: Starting rw-sysroot-nix-store.service...1109test # [ 3.697925] systemd[1]: Stopped Load Kernel Modules.1110test # [ 3.700105] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1111second # [ 3.715262] systemd[1]: systemd-sysctl.service: Deactivated successfully.1112second # [ 3.716734] systemd[1]: Stopped Apply Kernel Variables.1113second # [ 3.720109] systemd[1]: systemd-modules-load.service: Deactivated successfully.1114test # [ 3.703509] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1115test # [ 3.705398] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1116second # [ 3.724984] systemd[1]: Stopped Load Kernel Modules.1117test # [ 3.707406] systemd[1]: Stopped Create System Files and Directories.1118second # [ 3.725482] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1119test # [ 3.709809] systemd[1]: Stopped target Local File Systems.1120test # [ 3.710803] systemd[1]: Stopped target Preparation for Local File Systems.1121second # [ 3.726222] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1122second # [ 3.726938] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1123second # [ 3.727542] systemd[1]: Stopped Create System Files and Directories.1124second # [ 3.734794] systemd[1]: Stopped target Local File Systems.1125second # [ 3.735913] systemd[1]: Stopped target Preparation for Local File Systems.1126test # [ 3.711459] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1127second # [ 3.737200] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1128second # [ 3.737821] systemd[1]: Stopped Coldplug All udev Devices.1129test # [ 3.720748] systemd[1]: Stopped Coldplug All udev Devices.1130second # [ 3.738309] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1131second # [ 3.738982] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1132test # [ 3.721321] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1133second # [ 3.739651] systemd[1]: Stopped Virtual Console Setup.1134test # [ 3.723679] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1135test # [ 3.724850] systemd[1]: Stopped Virtual Console Setup.1136test # [ 3.726893] systemd[1]: systemd-udevd.service: Deactivated successfully.1137test # [ 3.727511] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1138second # [ 3.747470] systemd[1]: initrd-cleanup.service: Deactivated successfully.1139second # [ 3.748317] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1140test # [ 3.729897] systemd[1]: initrd-cleanup.service: Deactivated successfully.1141test # [ 3.730507] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1142second # [ 3.749004] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1143second # [ 3.749662] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1144test # [ 3.731623] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1145second # [ 3.752275] systemd[1]: systemd-udevd.service: Deactivated successfully.1146test # [ 3.735482] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1147test # [ 3.735994] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1148test # [ 3.736630] systemd[1]: Finished rw-sysroot-nix-store.service.1149second # [ 3.754095] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1150second # [ 3.754790] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1151second # [ 3.756237] systemd[1]: Closed udev Control Socket.1152test # [ 3.737169] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1153test # [ 3.737866] systemd[1]: Closed udev Control Socket.1154test # [ 3.738295] systemd[1]: Starting Cleanup udev Database...1155test # [ 3.738743] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1156test # [ 3.739359] systemd[1]: Stopped Create Static Device Nodes in /dev.1157test # [ 3.739869] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1158test # [ 3.740739] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1159test # [ 3.741366] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1160test # [ 3.742007] systemd[1]: Stopped Create List of Static Device Nodes.1161second # [ 3.759348] systemd[1]: Starting Cleanup udev Database...1162second # [ 3.759887] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1163second # [ 3.760964] systemd[1]: Stopped Create Static Device Nodes in /dev.1164second # [ 3.761549] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1165second # [ 3.762452] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1166second # [ 3.763155] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1167second # [ 3.763786] systemd[1]: Stopped Create List of Static Device Nodes.1168second # [ 3.764634] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1169second # [ 3.765290] systemd[1]: Finished rw-sysroot-nix-store.service.1170test # [ 3.759636] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1171test # [ 3.761932] systemd[1]: Finished Cleanup udev Database.1172test # [ 3.762570] systemd[1]: Reached target Switch Root.1173test # [ 3.763260] systemd[1]: Starting NixOS Activation...1174second # [ 3.784805] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1175second # [ 3.786435] systemd[1]: Finished Cleanup udev Database.1176second # [ 3.786976] systemd[1]: Reached target Switch Root.1177second # [ 3.788802] systemd[1]: Starting NixOS Activation...1178test # [ 3.940858] initrd-nixos-activation-start[181]: booting system configuration /nix/store/irbs3f64vgnzp87r2b3s2xc8ay5135q3-nixos-system-test-test1179second # [ 3.973192] initrd-nixos-activation-start[180]: booting system configuration /nix/store/29awshk1m2c8c67w17wv6fs5di5qdlf8-nixos-system-second-test1180test # [ 3.998454] initrd-nixos-activation-start[181]: running activation script...1181second # [ 4.024414] initrd-nixos-activation-start[180]: running activation script...1182test # [ 4.387988] initrd-nixos-activation-start[204]: setting up /etc...1183second # [ 4.427958] initrd-nixos-activation-start[203]: setting up /etc...1184test # [ 4.576683] initrd-nixos-activation-start[204]: setting up age secrets...1185second # [ 4.624446] initrd-nixos-activation-start[203]: setting up age secrets...1186second # [ 4.812499] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1187second # [ 4.814297] systemd[1]: Finished NixOS Activation.1188second # [ 4.815124] systemd[1]: Starting Switch Root...1189second # [ 4.829026] systemd[1]: Switching root.1190test # [ 4.830025] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191test # [ 4.831819] systemd[1]: Finished NixOS Activation.1192test # [ 4.835738] systemd[1]: Starting Switch Root...1193test # [ 4.848954] systemd[1]: Switching root.1194second # [ 5.005003] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195test # [ 5.025650] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1196second # [ 5.598914] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1197second # [ 5.605794] systemd[1]: Detected virtualization qemu.1198second # [ 5.607640] systemd[1]: Detected architecture arm64.1199second # [ 5.609970] systemd[1]: Detected first boot.1200second # [ 5.614989] systemd[1]: Initializing machine ID from random generator.1201test # [ 5.604604] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1202test # [ 5.611184] systemd[1]: Detected virtualization qemu.1203test # [ 5.613390] systemd[1]: Detected architecture arm64.1204test # [ 5.615237] systemd[1]: Detected first boot.1205test # [ 5.620361] systemd[1]: Initializing machine ID from random generator.1206test # [ 5.891379] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207second # [ 5.906882] systemd[1]: bpf-restrict-fs: LSM BPF program attached1208test # [ 6.042365] systemd[1]: Applying preset policy.1209second # [ 6.103636] systemd[1]: Applying preset policy.1210test # [ 6.457939] systemd[1]: Populated /etc with preset unit settings.1211second # [ 6.615797] systemd[1]: Populated /etc with preset unit settings.1212test # [ 6.925701] systemd[1]: initrd-switch-root.service: Deactivated successfully.1213test # [ 6.926635] systemd[1]: Stopped initrd-switch-root.service.1214test # [ 6.928961] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1215test # [ 6.931876] systemd[1]: Created slice Slice /system/getty.1216test # [ 6.932964] systemd[1]: Created slice User and Session Slice.1217test # [ 6.933621] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1218test # [ 6.934708] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1219test # [ 6.935961] systemd[1]: Expecting device /dev/hvc0...1220test # [ 6.935994] systemd[1]: Expecting device /dev/ttyAMA0...1221test # [ 6.937106] systemd[1]: Reached target Local Encrypted Volumes.1222test # [ 6.937415] systemd[1]: Stopped target initrd-fs.target.1223test # [ 6.937687] systemd[1]: Stopped target initrd-root-fs.target.1224test # [ 6.937960] systemd[1]: Stopped target initrd-switch-root.target.1225test # [ 6.938234] systemd[1]: Reached target Virtual Machines and Containers.1226test # [ 6.938502] systemd[1]: Reached target Path Units.1227test # [ 6.938756] systemd[1]: Reached target Remote File Systems.1228test # [ 6.939000] systemd[1]: Reached target Slice Units.1229test # [ 6.943733] systemd[1]: Reached target Swaps.1230test # [ 6.948116] systemd[1]: Listening on Query the User Interactively for a Password.1231test # [ 6.952786] systemd[1]: Listening on Process Core Dump Socket.1232test # [ 6.956606] systemd[1]: Listening on Credential Encryption/Decryption.1233test # [ 6.960480] systemd[1]: Listening on Factory Reset Management.1234test # [ 6.962077] systemd[1]: Listening on Hostname Service Socket.1235test # [ 6.966921] systemd[1]: Starting Journal Log Access Socket...1236test # [ 6.968734] systemd[1]: Listening on Journal Audit Socket.1237test # [ 6.972325] systemd[1]: Listening on Console Output Muting Service Socket.1238test # [ 6.973547] systemd[1]: Listening on Network Management Metrics Varlink Socket.1239test # [ 6.974781] systemd[1]: Listening on Network Management Varlink Socket.1240test # [ 6.976240] systemd[1]: Listening on Network Management Netlink Socket.1241test # [ 6.978151] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1242test # [ 6.979948] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1243test # [ 6.982307] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1244test # [ 6.992384] systemd[1]: Listening on Disk Repartitioning Service Socket.1245test # [ 6.994158] systemd[1]: Listening on Resolve Monitor Varlink Socket.1246test # [ 6.995922] systemd[1]: Listening on Resolve Service Varlink Socket.1247test # [ 6.997510] systemd[1]: Listening on udev Control Socket.1248test # [ 6.999014] systemd[1]: Listening on udev Varlink Socket.1249test # [ 7.002959] systemd[1]: Mounting Huge Pages File System...1250test # [ 7.007117] systemd[1]: Mounting POSIX Message Queue File System...1251test # [ 7.013339] systemd[1]: Mounting Kernel Debug File System...1252test # [ 7.022847] systemd[1]: Mounting Kernel Trace File System...1253test # [ 7.033261] systemd[1]: Starting Create List of Static Device Nodes...1254test # [ 7.041110] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1255test # [ 7.044241] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1256test # [ 7.055889] systemd[1]: Mounting Kernel Configuration File System...1257test # [ 7.057535] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1258test # [ 7.060796] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1259test # [ 7.072443] systemd[1]: Starting Load Kernel Module fuse...1260test # [ 7.074682] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671261second # [ 7.114626] systemd[1]: initrd-switch-root.service: Deactivated successfully.1262second # [ 7.115541] systemd[1]: Stopped initrd-switch-root.service.1263second # [ 7.117381] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1264second # [ 7.121181] systemd[1]: Created slice Slice /system/getty.1265second # [ 7.122752] systemd[1]: Created slice User and Session Slice.1266second # [ 7.124242] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1267second # [ 7.125939] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1268second # [ 7.127563] systemd[1]: Expecting device /dev/hvc0...1269second # [ 7.128906] systemd[1]: Expecting device /dev/ttyAMA0...1270second # [ 7.130233] systemd[1]: Reached target Local Encrypted Volumes.1271second # [ 7.131661] systemd[1]: Stopped target initrd-fs.target.1272second # [ 7.132587] systemd[1]: Stopped target initrd-root-fs.target.1273second # [ 7.133592] systemd[1]: Stopped target initrd-switch-root.target.1274second # [ 7.135113] systemd[1]: Reached target Virtual Machines and Containers.1275second # [ 7.136527] systemd[1]: Reached target Path Units.1276second # [ 7.137802] systemd[1]: Reached target Remote File Systems.1277second # [ 7.139360] systemd[1]: Reached target Slice Units.1278second # [ 7.140236] systemd[1]: Reached target Swaps.1279second # [ 7.144411] systemd[1]: Listening on Query the User Interactively for a Password.1280second # [ 7.149179] systemd[1]: Listening on Process Core Dump Socket.1281second # [ 7.153245] systemd[1]: Listening on Credential Encryption/Decryption.1282test # [ 7.138097] systemd[1]: Starting Journal Service...1283second # [ 7.156884] systemd[1]: Listening on Factory Reset Management.1284second # [ 7.157929] systemd[1]: Listening on Hostname Service Socket.1285second # [ 7.162793] systemd[1]: Starting Journal Log Access Socket...1286second # [ 7.164970] systemd[1]: Listening on Journal Audit Socket.1287second # [ 7.169003] systemd[1]: Listening on Console Output Muting Service Socket.1288second # [ 7.171051] systemd[1]: Listening on Network Management Metrics Varlink Socket.1289second # [ 7.172920] systemd[1]: Listening on Network Management Varlink Socket.1290second # [ 7.175287] systemd[1]: Listening on Network Management Netlink Socket.1291second # [ 7.176995] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1292second # [ 7.178706] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1293second # [ 7.180491] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1294second # [ 7.190984] systemd[1]: Listening on Disk Repartitioning Service Socket.1295test # [ 7.177494] systemd[1]: Starting Load Kernel Modules...1296second # [ 7.191515] systemd[1]: Listening on Resolve Monitor Varlink Socket.1297second # [ 7.191879] systemd[1]: Listening on Resolve Service Varlink Socket.1298second # [ 7.192230] systemd[1]: Listening on udev Control Socket.1299second # [ 7.192576] systemd[1]: Listening on udev Varlink Socket.1300second # [ 7.198520] systemd[1]: Mounting Huge Pages File System...1301second # [ 7.202136] systemd[1]: Mounting POSIX Message Queue File System...1302second # [ 7.209838] systemd[1]: Mounting Kernel Debug File System...1303second # [ 7.213929] systemd[1]: Mounting Kernel Trace File System...1304test # [ 7.206333] fuse: init (API version 7.45)1305test # [ 7.210200] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1306second # [ 7.226817] systemd[1]: Starting Create List of Static Device Nodes...1307test # [ 7.219017] systemd[1]: Starting Remount Root and Kernel File Systems...1308second # [ 7.237256] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1309second # [ 7.238924] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1310test # [ 7.223925] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1311second # [ 7.252255] systemd[1]: Mounting Kernel Configuration File System...1312second # [ 7.254228] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1313second # [ 7.258756] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1314test # [ 7.254455] systemd[1]: Starting Coldplug All udev Devices...1315second # [ 7.273965] systemd[1]: Starting Load Kernel Module fuse...1316second # [ 7.277311] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671317test # [ 7.278243] systemd-journald[330]: Collecting audit messages is enabled.1318test # [ 7.281822] systemd[1]: Listening on Journal Log Access Socket.1319test # [ 7.285173] systemd[1]: Mounted Huge Pages File System.1320test # [ 7.290623] systemd[1]: Mounted POSIX Message Queue File System.1321test # [ 7.292923] systemd[1]: Mounted Kernel Debug File System.1322test # [ 7.299084] systemd[1]: Mounted Kernel Trace File System.1323test # [ 7.294951] systemd[1]: Queued start job for default target Multi-User System.1324test # [ 7.303291] systemd[1]: Started Journal Service.1325second # [ 7.323675] systemd[1]: Starting Journal Service...1326test # [ 7.301918] systemd[1]: systemd-journald.service: Deactivated successfully.1327test # [ 7.302851] systemd-modules-load[331]: Module 'atkbd' is built in1328test # [ 7.303557] systemd-modules-load[331]: Module 'ctr' is built in1329test # [ 7.316742] systemd-modules-load[331]: Module 'loop' is built in1330test # [ 7.321141] systemd[1]: Finished Create List of Static Device Nodes.1331test # [ 7.323831] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1332test # [ 7.328470] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1333test # [ 7.338670] systemd[1]: Mounted Kernel Configuration File System.1334test # [ 7.339359] systemd[1]: modprobe@fuse.service: Deactivated successfully.1335test # [ 7.347266] EXT4-fs (vda): re-mounted f33491b6-5fa3-4aeb-a71d-8c591ce8428d.1336second # [ 7.366850] systemd[1]: Starting Load Kernel Modules...1337test # [ 7.344605] systemd[1]: Finished Load Kernel Module fuse.1338test # [ 7.345293] systemd[1]: Finished Load Kernel Modules.1339test # [ 7.347916] systemd[1]: Mounting FUSE Control File System...1340test # [ 7.357716] systemd[1]: Starting Firewall...1341test # [ 7.364139] systemd[1]: Starting Apply Kernel Variables...1342second # [ 7.396850] fuse: init (API version 7.45)1343second # [ 7.404702] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1344test # [ 7.386727] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1345test # [ 7.397454] systemd[1]: Finished Remount Root and Kernel File Systems.1346second # [ 7.423789] systemd[1]: Starting Remount Root and Kernel File Systems...1347second # [ 7.426493] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1348test # [ 7.406841] systemd-oomd[333]: No swap; memory pressure usage will be degraded1349second # [ 7.448496] systemd[1]: Starting Coldplug All udev Devices...1350test # [ 7.432267] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1351second # [ 7.466681] systemd-journald[308]: Collecting audit messages is enabled.1352test # [ 7.445834] systemd[1]: Listening on Disk Image Download Service Socket.1353second # [ 7.474818] systemd[1]: Listening on Journal Log Access Socket.1354second # [ 7.476222] systemd[1]: Mounted Huge Pages File System.1355second # [ 7.478892] systemd[1]: Mounted POSIX Message Queue File System.1356second # [ 7.481501] systemd[1]: Mounted Kernel Debug File System.1357second # [ 7.484900] systemd[1]: Mounted Kernel Trace File System.1358test # [ 7.462485] systemd[1]: Starting Flush Journal to Persistent Storage...1359second # [ 7.478482] systemd[1]: Queued start job for default target Multi-User System.1360second # [ 7.481844] systemd[1]: systemd-journald.service: Deactivated successfully.1361second # [ 7.490482] systemd[1]: Started Journal Service.1362test # [ 7.466241] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1363second # [ 7.488277] systemd-modules-load[309]: Module 'atkbd' is built in1364second # [ 7.491543] systemd-modules-load[309]: Module 'ctr' is built in1365test # [ 7.477110] systemd[1]: Starting Load/Save OS Random Seed...1366test # [ 7.478827] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1367second # [ 7.498427] systemd-modules-load[309]: Module 'loop' is built in1368second # [ 7.500705] systemd[1]: Finished Create List of Static Device Nodes.1369second # [ 7.507338] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1370second # [ 7.514090] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1371second # [ 7.520451] systemd[1]: Mounted Kernel Configuration File System.1372second # [ 7.521180] systemd[1]: modprobe@fuse.service: Deactivated successfully.1373second # [ 7.521908] systemd[1]: Finished Load Kernel Module fuse.1374second # [ 7.522506] systemd[1]: Finished Load Kernel Modules.1375test # [ 7.513509] systemd[1]: Finished Apply Kernel Variables.1376second # [ 7.547203] systemd[1]: Mounting FUSE Control File System...1377second # [ 7.550559] systemd[1]: Starting Firewall...1378test # [ 7.534836] systemd[1]: Starting Network Name Resolution...1379second # [ 7.564051] EXT4-fs (vda): re-mounted 53c27437-8307-4a34-93b6-404d6ef248d0.1380second # [ 7.561078] systemd[1]: Starting Apply Kernel Variables...1381test # [ 7.563111] systemd[1]: Mounted FUSE Control File System.1382second # [ 7.587053] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1383test # [ 7.581119] systemd-journald[330]: Received client request to flush runtime journal.1384second # [ 7.607020] systemd-oomd[310]: No swap; memory pressure usage will be degraded1385second # [ 7.611662] systemd[1]: Finished Remount Root and Kernel File Systems.1386second # [ 7.619530] systemd[1]: Listening on Disk Image Download Service Socket.1387second # [ 7.633978] systemd[1]: Starting Flush Journal to Persistent Storage...1388second # [ 7.636845] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1389second # [ 7.643666] systemd[1]: Starting Load/Save OS Random Seed...1390second # [ 7.652045] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1391second # [ 7.660706] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1392test # [ 7.664452] systemd[1]: Finished Load/Save OS Random Seed.1393test # [ 7.672642] systemd[1]: Reached target First Boot Complete.1394test # [ 7.674189] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1395test # [ 7.677571] systemd[1]: Starting Create Static Device Nodes in /dev...1396test # [ 7.684809] systemd[1]: Finished Flush Journal to Persistent Storage.1397second # [ 7.722187] systemd[1]: Mounted FUSE Control File System.1398second # [ 7.735917] systemd[1]: Finished Apply Kernel Variables.1399second # [ 7.751004] systemd-journald[308]: Received client request to flush runtime journal.1400test # [ 7.734308] systemd[1]: Finished Create Static Device Nodes in /dev.1401test # [ 7.735513] systemd[1]: Reached target Preparation for Local File Systems.1402test # [ 7.740139] systemd[1]: Starting Rule-based Manager for Device Events and Files...1403test # [ 7.763630] systemd-resolved[358]: Positive Trust Anchors:1404test # [ 7.765758] systemd-resolved[358]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1405test # [ 7.769127] systemd-resolved[358]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161406test # [ 7.773559] systemd-resolved[358]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1407second # [ 7.816403] systemd[1]: Starting Network Name Resolution...1408second # [ 7.819173] systemd[1]: Finished Load/Save OS Random Seed.1409second # [ 7.819823] systemd[1]: Reached target First Boot Complete.1410second # [ 7.829298] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1411second # [ 7.829985] systemd[1]: Starting Create Static Device Nodes in /dev...1412second # [ 7.835690] systemd[1]: Finished Flush Journal to Persistent Storage.1413test # [ 7.889772] systemd-udevd[374]: Using default interface naming scheme 'v261'.1414second # [ 7.934995] systemd-resolved[338]: Positive Trust Anchors:1415second # [ 7.935704] systemd-resolved[338]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1416second # [ 7.937621] systemd-resolved[338]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161417test # [ 7.924728] systemd[1]: Mounting /run/wrappers...1418second # [ 7.941351] systemd-resolved[338]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1419second # [ 7.971106] systemd[1]: Finished Create Static Device Nodes in /dev.1420second # [ 7.973143] systemd[1]: Reached target Preparation for Local File Systems.1421second # [ 7.976796] systemd[1]: Starting Rule-based Manager for Device Events and Files...1422test # [ 8.008709] systemd[1]: Mounted /run/wrappers.1423test # [ 8.011131] systemd[1]: Reached target Local File Systems.1424test # [ 8.014697] systemd[1]: Listening on Boot Loader Control Service Socket.1425test # [ 8.027661] systemd[1]: Starting register-nix-paths.service...1426test # [ 8.032127] systemd[1]: Starting Create SUID/SGID Wrappers...1427test # [ 8.033954] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1428test # [ 8.044166] systemd[1]: Starting Save Transient machine-id to Disk...1429test # [ 8.070490] systemd[1]: Starting Create System Files and Directories...1430test # [ 8.075274] systemd-resolved[358]: Using system hostname 'test'.1431second # [ 8.104057] systemd-udevd[353]: Using default interface naming scheme 'v261'.1432test # [ 8.088059] systemd[1]: Started Network Name Resolution.1433second # [ 8.113560] systemd[1]: Mounting /run/wrappers...1434second # [ 8.188575] systemd[1]: Mounted /run/wrappers.1435second # [ 8.194274] systemd[1]: Reached target Local File Systems.1436test # [ 8.182402] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1437second # [ 8.202730] systemd[1]: Listening on Boot Loader Control Service Socket.1438test # [ 8.192156] systemd[1]: Finished Save Transient machine-id to Disk.1439second # [ 8.212609] systemd[1]: Starting register-nix-paths.service...1440second # [ 8.225013] systemd[1]: Starting Create SUID/SGID Wrappers...1441second # [ 8.227123] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1442second # [ 8.234593] systemd[1]: Starting Save Transient machine-id to Disk...1443second # [ 8.262416] systemd-resolved[338]: Using system hostname 'second'.1444second # [ 8.285089] systemd[1]: Starting Create System Files and Directories...1445second # [ 8.289088] systemd[1]: Started Network Name Resolution.1446test # [ 8.281030] systemd[1]: Finished Create System Files and Directories.1447test # [ 8.293124] systemd[1]: Starting Rebuild Journal Catalog...1448test # [ 8.300241] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1449test # [ 8.342089] systemd[1]: Started Rule-based Manager for Device Events and Files.1450second # [ 8.405749] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1451second # [ 8.409686] systemd[1]: Finished Save Transient machine-id to Disk.1452test # [ 8.419076] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1453second # [ 8.475737] systemd[1]: Finished Create System Files and Directories.1454second # [ 8.486607] systemd[1]: Starting Rebuild Journal Catalog...1455second # [ 8.500819] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1456test # [ 8.490861] systemd[1]: Finished Rebuild Journal Catalog.1457test # [ 8.499826] systemd[1]: Starting Update is Completed...1458test # [ 8.506752] systemd[1]: Finished Coldplug All udev Devices.1459second # [ 8.544197] systemd[1]: Started Rule-based Manager for Device Events and Files.1460test # [ 8.567568] systemd[1]: Finished Update is Completed.1461second # [ 8.637914] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1462second # [ 8.686403] systemd[1]: Finished Rebuild Journal Catalog.1463second # [ 8.704241] systemd[1]: Starting Update is Completed...1464second # [ 8.768834] systemd[1]: Finished Coldplug All udev Devices.1465second # [ 8.774561] systemd[1]: Finished Update is Completed.1466test # [ 8.787910] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1467test # [ 8.975130] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1468test # [ 8.977636] systemd[1]: Finished Create SUID/SGID Wrappers.1469second # [ 9.001617] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1470test # [ 9.066504] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1471test # [ 9.156868] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1472second # [ 9.228146] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1473second # [ 9.235644] systemd[1]: Finished Create SUID/SGID Wrappers.1474second # [ 9.320136] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1475second # [ 9.372103] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1476test # [ 9.394976] systemd[1]: Finished register-nix-paths.service.1477test # [ 9.400564] systemd[1]: Reached target System Initialization.1478test # [ 9.401190] systemd[1]: Started Discard unused filesystem blocks once a week.1479test # [ 9.401886] systemd[1]: Started Daily Cleanup of Temporary Directories.1480test # [ 9.402539] systemd[1]: Reached target Timer Units.1481test # [ 9.406624] systemd[1]: Listening on D-Bus System Message Bus Socket.1482test # [ 9.410425] systemd[1]: Listening on Nix Daemon Socket.1483test # [ 9.419884] systemd[1]: Listening on Authorization Manager Agent Helper.1484test # [ 9.420815] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1485test # [ 9.421644] systemd[1]: Reached target Socket Units.1486test # [ 9.426258] systemd[1]: Starting D-Bus System Message Bus...1487test # [ 9.427480] systemd[1]: Finished Firewall.1488test # [ 9.430032] (udev-worker)[478]: Network interface NamePolicy= disabled on kernel command line.1489test # [ 9.440282] (udev-worker)[474]: Network interface NamePolicy= disabled on kernel command line.1490test # [ 9.444576] systemd[1]: Reached target Preparation for Network.1491test # [ 9.445237] systemd[1]: Listening on Network Management Resolve Hook Socket.1492test # [ 9.453183] systemd[1]: Starting Network Management...1493second # [ 9.607901] systemd[1]: Finished register-nix-paths.service.1494second # [ 9.610936] systemd[1]: Reached target System Initialization.1495second # [ 9.613213] systemd[1]: Started Discard unused filesystem blocks once a week.1496second # [ 9.617182] systemd[1]: Started Daily Cleanup of Temporary Directories.1497second # [ 9.619521] systemd[1]: Reached target Timer Units.1498second # [ 9.621840] systemd[1]: Listening on D-Bus System Message Bus Socket.1499second # [ 9.624994] systemd[1]: Listening on Nix Daemon Socket.1500second # [ 9.629712] systemd[1]: Listening on Authorization Manager Agent Helper.1501second # [ 9.630438] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1502second # [ 9.631306] systemd[1]: Reached target Socket Units.1503second # [ 9.636267] systemd[1]: Starting D-Bus System Message Bus...1504test # [ 9.677456] mousedev: PS/2 mouse device common for all mice1505second # [ 9.701985] (udev-worker)[452]: Network interface NamePolicy= disabled on kernel command line.1506test # [ 9.685000] dbus-broker-launch[551]: Looking up NSS user entry for 'systemd-timesync'...1507second # [ 9.716674] (udev-worker)[446]: Network interface NamePolicy= disabled on kernel command line.1508test # [ 9.705513] cfg80211: Loading compiled-in X.509 certificates for regulatory database1509test # [ 9.711112] dbus-broker-launch[551]: NSS returned no entry for 'systemd-timesync'1510test # [ 9.712512] dbus-broker-launch[551]: Invalid user-name in /nix/store/l2468ywqyiz2x0j8k8v3s5x8kydl70q1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1511second # [ 9.758687] systemd[1]: Finished Firewall.1512second # [ 9.762421] systemd[1]: Reached target Preparation for Network.1513second # [ 9.764182] systemd[1]: Listening on Network Management Resolve Hook Socket.1514second # [ 9.767521] systemd[1]: Starting Network Management...1515test # [ 9.761165] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1516test # [ 9.761703] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1517test # [ 9.787686] dbus-broker-launch[551]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1518test # [ 9.791538] dbus-broker-launch[551]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1519test # [ 9.797233] dbus-broker-launch[551]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/9prxmvwvdr9mfqbb38w7jq4kicc7zi18-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1520test # [ 9.802378] dbus-broker-launch[551]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/5cpm90flydbxw9km2kl7pk8i1i5sc3nk-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1521test # [ 9.810647] systemd[1]: Started D-Bus System Message Bus.1522test # [ 9.815313] systemd[1]: Reached target Basic System.1523test # [ 9.817099] systemd[1]: Starting Network Manager Script Dispatcher Service...1524test # [ 9.822849] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1525test # [ 9.828367] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1526second # [ 9.855088] dbus-broker-launch[521]: Looking up NSS user entry for 'systemd-timesync'...1527second # [ 9.869725] dbus-broker-launch[521]: NSS returned no entry for 'systemd-timesync'1528second # [ 9.871654] dbus-broker-launch[521]: Invalid user-name in /nix/store/l2468ywqyiz2x0j8k8v3s5x8kydl70q1-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1529test # [ 9.860417] systemd-networkd[555]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1530test # [ 9.870527] systemd-networkd[555]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1531test # [ 9.885294] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1532test # [ 9.885989] systemd-networkd[555]: lo: Link UP1533test # [ 9.886452] systemd-networkd[555]: lo: Gained carrier1534test # [ 9.896758] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1535test # [ 9.908169] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1536test # [ 9.909003] systemd-networkd[555]: eth1: Link UP1537test # [ 9.912640] systemd-networkd[555]: eth1: Gained carrier1538test # [ 9.914320] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1539second # [ 9.936594] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1540test # [ 9.923613] dbus-broker-launch[551]: Ready1541test # [ 9.926054] systemd[1]: Starting Network Manager...1542second # [ 9.942222] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1543second # [ 9.952840] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/9prxmvwvdr9mfqbb38w7jq4kicc7zi18-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1544second # [ 9.961838] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/5cpm90flydbxw9km2kl7pk8i1i5sc3nk-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1545test # [ 9.954396] systemd[1]: Started backdoor.service.1546second # [ 9.972209] systemd[1]: Started D-Bus System Message Bus.1547second # [ 9.976286] systemd[1]: Reached target Basic System.1548second # [ 9.981154] systemd[1]: Starting Network Manager Script Dispatcher Service...1549second # [ 9.984137] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1550test # [ 9.971771] systemd[1]: Starting Import lastlog data into lastlog2 database...1551second # [ 10.009892] mousedev: PS/2 mouse device common for all mice1552second # [ 10.011529] systemd[1]: Starting Network Manager...1553test # [ 10.000815] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1554second # [ 10.046172] systemd[1]: Started backdoor.service.1555test # [ 10.042213] hvvswg4az6varyhqcky7lvgpfvab186x-wifi-secrets[566]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open1556second # [ 10.063278] dbus-broker-launch[521]: Ready1557test # [ 10.056440] systemd[1]: Starting Post-Boot Actions...1558test # [ 10.057226] 13makxmba16zw6y4nx7vwhra29i4my7y-wifi-secrets[564]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1559second # [ 10.086908] cfg80211: Loading compiled-in X.509 certificates for regulatory database1560test # [ 10.068181] p9h802x1da87xck6kdhn8c5sql80djv2-wifi-secrets[565]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one1561second # [ 10.088659] systemd[1]: Starting Import lastlog data into lastlog2 database...1562test # [ 10.091730] systemd[1]: Started Reset console on configuration changes.1563test # [ 10.096814] systemd[1]: Started Network Management.1564second # [ 10.126083] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1565second # [ 10.126657] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1566second # [ 10.125043] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1567test # [ 10.125189] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1568second # [ 10.143251] systemd[1]: Starting Post-Boot Actions...1569test # [ 10.131980] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1570second # [ 10.155810] 13makxmba16zw6y4nx7vwhra29i4my7y-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1571test # [ 10.143508] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1572test # [ 10.157363] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573test # [ 10.158153] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1574test # [ 10.159059] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575second # [ 10.204940] systemd[1]: Started Reset console on configuration changes.1576test # [ 10.204128] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1577test # [ 10.229477] systemd[1]: Finished Post-Boot Actions.1578test # connecting to host...1579second # [ 10.262471] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1580second # connecting to host...1581second # [ 10.270074] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1582second # [ 10.279008] systemd-networkd[533]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1583second # [ 10.295587] systemd-networkd[533]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1584second # [ 10.313229] systemd-networkd[533]: lo: Link UP1585test # [ 10.298410] systemd[1]: Started Name Service Cache Daemon (nsncd).1586second # [ 10.317513] systemd-networkd[533]: lo: Gained carrier1587test # [ 10.300313] nsncd[571]: Aug 27 20:11:53.123 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1588test: Guest shell says: b'Spawning backdoor root shell...\n'1589second # [ 10.325957] systemd[1]: Started Network Management.1590second # [ 10.332165] systemd[1]: Finished Post-Boot Actions.1591second # [ 10.340733] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1592test # [ 10.309275] systemd[1]: Started Network Manager Script Dispatcher Service.1593second # [ 10.355723] systemd-networkd[533]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1594test # [ 10.310707] systemd[1]: Reached target Host and Network Name Lookups.1595second # [ 10.357269] systemd-networkd[533]: eth1: Link UP1596second # [ 10.360568] systemd-networkd[533]: eth1: Gained carrier1597test: connected to guest root shell1598second # [ 10.374654] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1599test: (connecting took 10.61 seconds)1600test # [ 10.311257] systemd[1]: Reached target User and Group Name Lookups.1601test: (finished: waiting for the VM to finish booting, in 10.61 seconds)1602second # [ 10.375901] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1603test # [ 10.326150] systemd[1]: Starting User Login Management...1604test # [ 10.329839] systemd[1]: Finished Import lastlog data into lastlog2 database.1605test # [ 10.358616] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1606second # [ 10.391058] systemd[1]: Started Name Service Cache Daemon (nsncd).1607second # [ 10.397641] nsncd[545]: Aug 27 20:11:53.229 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1608second # [ 10.409408] systemd[1]: Reached target Host and Network Name Lookups.1609second # [ 10.410106] systemd[1]: Reached target User and Group Name Lookups.1610test # [ 10.397142] systemd[1]: Starting Hostname Service...1611second # [ 10.428228] systemd[1]: Starting User Login Management...1612second # [ 10.444377] systemd[1]: Finished Import lastlog data into lastlog2 database.1613second # [ 10.469127] systemd[1]: Started Network Manager Script Dispatcher Service.1614test # [ 10.491672] systemd-logind[616]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1615test # [ 10.499538] systemd-logind[616]: Watching system buttons on /dev/input/event0 (gpio-keys)1616test # [ 10.501848] systemd-logind[616]: New seat seat0.1617second # [ 10.531465] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1618test # [ 10.516629] systemd[1]: Started User Login Management.1619test # [ 10.520750] systemd[1]: Starting linger-users.service...1620second # [ 10.572547] systemd[1]: Starting Hostname Service...1621test # [ 10.566974] systemd[1]: Started Hostname Service.1622test # [ 10.582342] systemd[1]: linger-users.service: Deactivated successfully.1623test # [ 10.585779] systemd[1]: Finished linger-users.service.1624second # [ 10.605569] systemd-logind[575]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1625second # [ 10.609610] systemd-logind[575]: Watching system buttons on /dev/input/event0 (gpio-keys)1626second # [ 10.613464] systemd-logind[575]: New seat seat0.1627second # [ 10.614232] systemd[1]: Started User Login Management.1628second # [ 10.623324] systemd[1]: Starting linger-users.service...1629test # [ 10.659604] systemd-networkd[555]: eth0: Link UP1630test # [ 10.660185] systemd-networkd[555]: eth0: Gained carrier1631second # [ 10.696076] systemd[1]: linger-users.service: Deactivated successfully.1632test # [ 10.679574] systemd[1]: Started Network Manager.1633test # [ 10.681142] systemd[1]: Reached target Network.1634second # [ 10.698283] systemd[1]: Finished linger-users.service.1635test # [ 10.688228] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1636second # [ 10.708904] systemd[1]: Started Hostname Service.1637test # [ 10.694063] systemd[1]: Starting Permit User Sessions...1638second # [ 10.796254] systemd-networkd[533]: eth0: Link UP1639second # [ 10.796742] systemd-networkd[533]: eth0: Gained carrier1640second # [ 10.816073] systemd[1]: Started Network Manager.1641second # [ 10.818091] systemd[1]: Reached target Network.1642second # [ 10.823605] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1643second # [ 10.829503] systemd[1]: Starting Permit User Sessions...1644test # [ 10.844748] systemd[1]: Finished Permit User Sessions.1645test # [ 10.864387] systemd[1]: Started Getty on tty1.1646test # [ 10.864879] systemd[1]: Reached target Login Prompts.1647test # [ 10.891887] systemd-resolved[358]: eth0: Bus client set default route setting: yes1648test # [ 10.898003] systemd-resolved[358]: eth0: Bus client set DNS server list to: 10.0.2.31649second # [ 10.983736] systemd[1]: Finished Permit User Sessions.1650test # [ 10.972412] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1651test # [ 10.976302] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1652second # [ 11.004196] systemd[1]: Started Getty on tty1.1653second # [ 11.004785] systemd[1]: Reached target Login Prompts.1654second # [ 11.036566] systemd-resolved[338]: eth0: Bus client set default route setting: yes1655second # [ 11.042288] systemd-resolved[338]: eth0: Bus client set DNS server list to: 10.0.2.31656test # [ 11.072490] systemd-networkd[555]: eth1: Gained IPv6LL1657second # [ 11.114745] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1658second # [ 11.116475] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1659test # [ 11.120951] systemd[1]: Reached target Multi-User System.1660test # [ 11.125008] systemd[1]: Startup finished in 733ms (kernel) + 4.376s (initrd) + 6.012s (userspace) = 11.122s.1661second # [ 11.252245] systemd[1]: Reached target Multi-User System.1662second # [ 11.258313] systemd[1]: Startup finished in 748ms (kernel) + 4.362s (initrd) + 6.146s (userspace) = 11.257s.1663test # [ 11.515030] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31664test: (finished: waiting for unit NetworkManager.service, in 11.83 seconds)1665test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1666test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1667test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open1668second # [ 11.653208] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31669test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.04 seconds)1670test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1671test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1672(finished: run the VM test script, in 12.42 seconds)1673test script finished in 12.48s1674cleanup1675kill QemuMachine (pid 47)1676second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1677test # [ 11.860963] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1678test # [ 11.894201] systemd[1]: Starting Virtual Console Setup...1679kill QemuMachine (pid 48)1680test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1681(finished: cleanup, in 0.27 seconds)