build01: building kza8jv93byqwf9gxhvalx565cayz1x2a-vm-test-run-wifi.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: second, test, vlan1, 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_ssh start all VMs second: starting vm test: starting vm second # Disk image does not exist, creating the virtualisation disk image... second: QEMU running (pid 47) second # Formatting '/build/vm-state-second/tmp.d9EqeFQYyM', fmt=raw size=1073741824 second # mke2fs 1.47.4 (6-Mar-2025) second # Discarding device blocks: 0/262144 done second # Creating filesystem with 262144 4k blocks and 65536 inodes second # Filesystem UUID: 4bb1824f-be15-43fc-948c-bdbeaa112076 second # Superblock backups stored on blocks: second # 32768, 98304, 163840, 229376 second # second # Allocating group tables: 0/8 done second # Writing inode tables: 0/8 done second # Creating journal (8192 blocks): done second # Writing superblocks and filesystem accounting information: 0/8 done second # second # Virtualisation disk image created. second # Starting virtiofs daemons... second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests test # Disk image does not exist, creating the virtualisation disk image... test # Formatting '/build/vm-state-test/tmp.cFFf4qzln8', fmt=raw size=1073741824 test # mke2fs 1.47.4 (6-Mar-2025) test # Discarding device blocks: 0/262144 done test # Creating filesystem with 262144 4k blocks and 65536 inodes test # Filesystem UUID: 80dda120-7136-485e-9bf4-6225ad27eebb test # Superblock backups stored on blocks: test # 32768, 98304, 163840, 229376 test # test # Allocating group tables: 0/8 done test # Writing inode tables: 0/8 done test: QEMU running (pid 48) test # Creating journal (8192 blocks): done test # Writing superblocks and filesystem accounting information: 0/8 done test # test # Virtualisation disk image created. test # Starting virtiofs daemons... test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-03T00:04:47Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-03T00:04:47Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests test # [2026-10-03T00:04:47Z INFO virtiofsd] Client connected, servicing requests (finished: start all VMs, in 0.42 seconds) test: waiting for unit NetworkManager.service test: waiting for the VM to finish booting second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 second # [ 0.000000] KASLR enabled second # [ 0.000000] random: crng init done second # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.000000] efi: UEFI not found. second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 second # [ 0.000000] Zone ranges: test # [ 0.000000] KASLR enabled test # [ 0.000000] random: crng init done second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] DMA32 empty test # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.000000] Normal empty test # [ 0.000000] efi: UEFI not found. second # [ 0.000000] Device empty second # [ 0.000000] Movable zone start for each node test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT second # [ 0.000000] Early memory node ranges second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] Zone ranges: second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] DMA32 empty second # [ 0.000000] psci: probing for conduit method from DT. test # [ 0.000000] Normal empty second # [ 0.000000] psci: PSCIv1.3 detected in firmware. test # [ 0.000000] Device empty test # [ 0.000000] Movable zone start for each node second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] Early memory node ranges second # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] psci: SMC Calling Convention v1.1 test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 second # [ 0.000000] Detected PIPT I-cache on CPU0 test # [ 0.000000] psci: probing for conduit method from DT. test # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: Spectre-v4 test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs second # [ 0.000000] CPU features: detected: Spectre-BHB test # [ 0.000000] psci: Trusted OS migration not required test # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] CPU features: detected: ARM erratum 1418040 test # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion test # [ 0.000000] Detected PIPT I-cache on CPU0 test # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] alternatives: applying boot alternatives test # [ 0.000000] CPU features: detected: Spectre-BHB test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] CPU features: detected: ARM erratum 1418040 test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 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/zc805yy98n4wgcl6blvgfrfzi3hamvv9-nixos-system-second-test/init regInfo=/nix/.ro-store/lri5knw7xgd7k2yzc6pm86vdxkmk46kn-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] alternatives: applying boot alternatives second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/lri5knw7xgd7k2yzc6pm86vdxkmk46kn-closure-info/registration", will be passed to user space. second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 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/nhzs5nq18r2wcww7qxwy0v9wknbxljdj-nixos-system-test-test/init regInfo=/nix/.ro-store/n0ri1yrhjalps8j5yrqkyj3x987ly7s4-closure-info/registration console=ttyAMA0,115200n8 console=tty0 second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB second # [ 0.000000] software IO TLB: area num 1. test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/n0ri1yrhjalps8j5yrqkyj3x987ly7s4-closure-info/registration", will be passed to user space. second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] Fallback order for Node 0: 0 test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) second # [ 0.000000] Policy zone: DMA second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB second # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.000000] software IO TLB: area num 1. second # [ 0.000000] ftrace: allocating 75026 entries in 294 pages test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] ftrace: allocated 294 pages with 4 groups test # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] rcu: RCU event tracing is enabled. test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000000] Policy zone: DMA second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] Rude variant of Tasks RCU enabled. test # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] Tracing variant of Tasks RCU enabled. test # [ 0.000000] ftrace: allocating 75026 entries in 294 pages second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.000000] ftrace: allocated 294 pages with 4 groups second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 test # [ 0.000000] rcu: Hierarchical RCU implementation. test # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. second # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 second # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 test # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.000000] GICv3: 0 Extended SPIs implemented second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) test # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.000040] arm-pv: using stolen time PV test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.000308] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000570] Console: colour dummy device 80x25 test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000581] printk: legacy console [tty0] enabled test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.000796] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.000802] pid_max: default: 32768 minimum: 301 second # [ 0.000877] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns second # [ 0.001000] landlock: Up and running. test # [ 0.000039] arm-pv: using stolen time PV second # [ 0.001002] Yama: becoming mindful. second # [ 0.001345] LSM support for eBPF active test # [ 0.000381] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.001488] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.000641] Console: colour dummy device 80x25 test # [ 0.000652] printk: legacy console [tty0] enabled second # [ 0.001508] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.004218] rcu: Hierarchical SRCU implementation. test # [ 0.000865] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.004224] rcu: Max phase no-delay instances is 1000. test # [ 0.000870] pid_max: default: 32768 minimum: 301 second # [ 0.005175] fsl-mc MSI: its@8080000 domain created second # [ 0.005366] EFI services will not be available. test # [ 0.000944] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.001112] landlock: Up and running. second # [ 0.005459] smp: Bringing up secondary CPUs ... test # [ 0.001115] Yama: becoming mindful. second # [ 0.005503] smp: Brought up 1 node, 1 CPU test # [ 0.001431] LSM support for eBPF active second # [ 0.005508] SMP: Total of 1 processors activated. second # [ 0.005510] CPU: All CPU(s) started at EL1 test # [ 0.001621] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.005520] CPU features: detected: 32-bit EL0 Support test # [ 0.001640] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.005523] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.004151] rcu: Hierarchical SRCU implementation. test # [ 0.004156] rcu: Max phase no-delay instances is 1000. second # [ 0.005526] CPU features: detected: Common not Private translations test # [ 0.005094] fsl-mc MSI: its@8080000 domain created second # [ 0.005528] CPU features: detected: CRC32 instructions test # [ 0.005273] EFI services will not be available. second # [ 0.005530] CPU features: detected: Data cache clean to Point of Persistence test # [ 0.005369] smp: Bringing up secondary CPUs ... test # [ 0.005409] smp: Brought up 1 node, 1 CPU second # [ 0.005532] CPU features: detected: Enhanced Virtualization Traps test # [ 0.005414] SMP: Total of 1 processors activated. second # [ 0.005541] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.005416] CPU: All CPU(s) started at EL1 second # [ 0.005544] CPU features: detected: LSE atomic instructions test # [ 0.005426] CPU features: detected: 32-bit EL0 Support second # [ 0.005546] CPU features: detected: Privileged Access Never second # [ 0.005547] CPU features: detected: PMUv3 test # [ 0.005428] CPU features: detected: Data cache clean to the PoU not required for I/D coherence second # [ 0.005549] CPU features: detected: RAS Extension Support test # [ 0.005431] CPU features: detected: Common not Private translations second # [ 0.005553] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.005433] CPU features: detected: CRC32 instructions second # [ 0.005573] alternatives: applying system-wide alternatives test # [ 0.005435] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.006952] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.005437] CPU features: detected: Enhanced Virtualization Traps test # [ 0.005447] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.005449] CPU features: detected: LSE atomic instructions second # [ 0.007174] Memory: 891196K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116068K reserved, 32768K cma-reserved) second # [ 0.007643] devtmpfs: initialized test # [ 0.005451] CPU features: detected: Privileged Access Never test # [ 0.005452] CPU features: detected: PMUv3 second # [ 0.009050] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.005454] CPU features: detected: RAS Extension Support second # [ 0.009073] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.005458] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.005477] alternatives: applying system-wide alternatives second # [ 0.009205] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.009208] 0 pages in range for non-PLT usage test # [ 0.006933] CPU features: detected: Hardware dirty bit management on CPU0 second # [ 0.009208] 508208 pages in range for PLT usage second # [ 0.009321] pinctrl core: initialized pinctrl subsystem second # [ 0.010077] DMI not present or invalid. test # [ 0.007130] Memory: 891232K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116040K reserved, 32768K cma-reserved) test # [ 0.007610] devtmpfs: initialized second # [ 0.013484] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.016119] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.009077] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.016249] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.009103] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.009228] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.016396] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.009231] 0 pages in range for non-PLT usage test # [ 0.009232] 508208 pages in range for PLT usage second # [ 0.016411] audit: initializing netlink subsys (disabled) test # [ 0.009349] pinctrl core: initialized pinctrl subsystem test # [ 0.010112] DMI not present or invalid. second # [ 0.016969] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.013481] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.016971] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.016076] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.016974] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.016976] thermal_sys: Registered thermal governor 'user_space' test # [ 0.016227] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations second # [ 0.016978] thermal_sys: Registered thermal governor 'power_allocator' test # [ 0.016373] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations second # [ 0.017021] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 test # [ 0.016389] audit: initializing netlink subsys (disabled) second # [ 0.017030] cpuidle: using governor ladder test # [ 0.016941] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.017035] cpuidle: using governor menu test # [ 0.016943] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.017156] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. test # [ 0.016946] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.017176] ASID allocator initialised with 32768 entries test # [ 0.016948] thermal_sys: Registered thermal governor 'user_space' second # [ 0.017801] Serial: AMBA PL011 UART driver test # [ 0.016950] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.021459] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.021620] printk: console [ttyAMA0] enabled test # [ 0.016982] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 test # [ 0.016991] cpuidle: using governor ladder test # [ 0.016996] cpuidle: using governor menu test # [ 0.017104] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. test # [ 0.017123] ASID allocator initialised with 32768 entries test # [ 0.017753] Serial: AMBA PL011 UART driver test # [ 0.021103] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 test # [ 0.021268] printk: console [ttyAMA0] enabled second # [ 0.093955] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages second # [ 0.093968] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page second # [ 0.093971] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.093976] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page second # [ 0.093978] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages second # [ 0.093980] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page second # [ 0.093982] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages second # [ 0.093984] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page second # [ 0.095016] fbcon: Taking over console second # [ 0.095033] ACPI: Interpreter disabled. test # [ 0.094761] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.094776] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page second # [ 0.095681] iommu: Default domain type: Translated second # [ 0.095685] iommu: DMA domain TLB invalidation policy: strict mode test # [ 0.094778] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.097903] SCSI subsystem initialized test # [ 0.094782] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.094785] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.094786] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.094788] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.094790] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.095814] fbcon: Taking over console second # [ 0.103909] usbcore: registered new interface driver usbfs test # [ 0.095830] ACPI: Interpreter disabled. test # [ 0.096492] iommu: Default domain type: Translated second # [ 0.103941] usbcore: registered new interface driver hub second # [ 0.103958] usbcore: registered new device driver usb test # [ 0.096496] iommu: DMA domain TLB invalidation policy: strict mode test # [ 0.096785] SCSI subsystem initialized second # [ 0.104176] pps_core: LinuxPPS API ver. 1 registered test # [ 0.098591] usbcore: registered new interface driver usbfs second # [ 0.104179] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.098621] usbcore: registered new interface driver hub second # [ 0.104183] PTP clock support registered test # [ 0.098637] usbcore: registered new device driver usb second # [ 0.104217] EDAC MC: Ver: 3.0.0 test # [ 0.098848] pps_core: LinuxPPS API ver. 1 registered second # [ 0.104381] scmi_core: SCMI protocol bus registered second # [ 0.104667] FPGA manager framework test # [ 0.098851] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.098856] PTP clock support registered second # [ 0.109473] vgaarb: loaded test # [ 0.098888] EDAC MC: Ver: 3.0.0 second # [ 0.109710] clocksource: Switched to clocksource arch_sys_counter test # [ 0.107459] scmi_core: SCMI protocol bus registered test # [ 0.107778] FPGA manager framework test # [ 0.111136] vgaarb: loaded test # [ 0.112201] clocksource: Switched to clocksource arch_sys_counter second # [ 0.121965] VFS: Disk quotas dquot_6.6.0 second # [ 0.121990] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.123398] netfs: FS-Cache loaded second # [ 0.123509] pnp: PnP ACPI: disabled second # [ 0.125307] NET: Registered PF_INET protocol family test # [ 0.122277] VFS: Disk quotas dquot_6.6.0 test # [ 0.122302] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.125477] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.123715] netfs: FS-Cache loaded test # [ 0.123841] pnp: PnP ACPI: disabled test # [ 0.127071] NET: Registered PF_INET protocol family test # [ 0.127249] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) second # [ 0.151184] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.151202] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.151213] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.151550] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.151247] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.151567] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.151313] TCP: Hash tables configured (established 8192 bind 8192) test # [ 0.151577] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.151399] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.151613] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.151428] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.151679] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.151450] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.151786] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.151568] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.151597] NET: Registered PF_XDP protocol family test # [ 0.151817] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151615] PCI: CLS 0 bytes, default 64 test # [ 0.151868] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151906] Trying to unpack rootfs image as initramfs... second # [ 0.153884] kvm [1]: HYP mode not available test # [ 0.151982] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.152008] NET: Registered PF_XDP protocol family test # [ 0.152027] PCI: CLS 0 bytes, default 64 test # [ 0.152283] Trying to unpack rootfs image as initramfs... test # [ 0.166237] kvm [1]: HYP mode not available second # [ 0.226047] Initialise system trusted keyrings second # [ 0.226173] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.226493] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.226526] 9p: Installing v9fs 9p2000 file system support test # [ 0.228622] Initialise system trusted keyrings test # [ 0.228762] workingset: timestamp_bits=42 max_order=18 bucket_order=0 test # [ 0.229060] squashfs: version 4.0 (2009/01/31) Phillip Lougher test # [ 0.229093] 9p: Installing v9fs 9p2000 file system support second # [ 0.245321] Key type asymmetric registered second # [ 0.245335] Asymmetric key parser 'x509' registered second # [ 0.245387] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.249699] Key type asymmetric registered test # [ 0.249713] Asymmetric key parser 'x509' registered test # [ 0.249760] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.249854] io scheduler mq-deadline registered test # [ 0.249858] io scheduler kyber registered second # [ 0.253773] io scheduler mq-deadline registered second # [ 0.253779] io scheduler kyber registered second # [ 0.256140] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.256456] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.256746] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.256763] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.256775] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.256780] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.256825] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.256845] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.256920] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.256936] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.256940] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.256942] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.256944] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.257016] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.257469] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.257597] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] test # [ 0.260306] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.257606] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.260635] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.257625] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.260927] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.257634] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.260950] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 test # [ 0.260963] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 test # [ 0.260967] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.260995] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.261016] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.261083] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 test # [ 0.261098] pci_bus 0000:00: root bus resource [bus 00-ff] test # [ 0.261102] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] test # [ 0.261104] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] test # [ 0.261107] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] test # [ 0.261178] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint test # [ 0.261630] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.261757] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] test # [ 0.261767] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.261786] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.261796] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.262112] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.274006] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.262236] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.274134] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] test # [ 0.262245] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.274144] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.274162] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.262263] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.274481] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.262573] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint second # [ 0.274606] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] test # [ 0.262698] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] second # [ 0.274615] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.262707] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.262725] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.274633] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.263035] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.275040] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.263160] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] second # [ 0.275167] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.263169] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.275176] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.275195] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.263187] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.275203] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.263196] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.275516] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint test # [ 0.263507] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.275643] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.263635] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.275661] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.263653] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.263987] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.275998] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.264114] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.276125] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.264133] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.276144] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.276412] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.276534] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.276715] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.276842] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.276860] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.277174] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.277303] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.284656] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.277321] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.284778] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.277628] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.284986] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.285111] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.285129] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.292576] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.285437] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.292596] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.285564] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.292931] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.285582] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.293090] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] test # [ 0.285888] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.293100] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.286012] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.293118] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.286030] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.293479] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.286357] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.293605] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.286520] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.293614] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.286530] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.293632] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.286548] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.286964] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.287086] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.287095] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.287113] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.287540] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.287547] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.287550] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.287581] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.287611] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.287640] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.287672] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.287702] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.287731] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.287774] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.287804] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.287833] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.287915] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.287951] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.287963] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned second # [ 0.306172] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.287975] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned second # [ 0.306180] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.287987] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.287999] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.306183] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.288011] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.306216] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.288024] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned second # [ 0.306246] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.288036] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.306277] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.288048] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.306308] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.288060] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned test # [ 0.288072] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.306338] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.288084] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.306368] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.288095] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.306398] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.288107] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.288118] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.306429] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.288130] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned second # [ 0.306459] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.288142] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.306516] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.288153] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned test # [ 0.288169] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.306545] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.288173] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.306677] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.288181] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.306690] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned second # [ 0.306703] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned second # [ 0.306715] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.306727] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.306748] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned second # [ 0.306762] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.306774] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.306785] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned second # [ 0.306798] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.306810] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.306822] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.306833] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.306845] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.306857] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned second # [ 0.306869] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.306881] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.306897] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.306901] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.306909] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.307446] pci 0000:00:07.0: enabling device (0000 -> 0002) test # [ 0.324826] pci 0000:00:07.0: enabling device (0000 -> 0002) second # [ 0.333005] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.331696] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.342604] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) second # [ 0.344304] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.341337] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.343093] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) second # [ 0.353850] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.357014] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) test # [ 0.352683] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.355858] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) second # [ 0.360096] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) second # [ 0.361595] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.358564] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) test # [ 0.360098] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) second # [ 0.370944] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.372605] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) test # [ 0.369423] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) test # [ 0.371070] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.375408] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.377258] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.373822] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) test # [ 0.375679] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) second # [ 0.388419] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.389350] msm_serial: driver initialized second # [ 0.389420] SuperH (H)SCI(F) driver initialized second # [ 0.389461] STM32 USART driver initialized test # [ 0.386867] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled test # [ 0.387807] msm_serial: driver initialized test # [ 0.387888] SuperH (H)SCI(F) driver initialized test # [ 0.387928] STM32 USART driver initialized second # [ 0.414852] loop: module loaded second # [ 0.414985] virtio_blk virtio2: 1/0/0 default/read/poll queues second # [ 0.415652] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) test # [ 0.412653] loop: module loaded test # [ 0.412774] virtio_blk virtio2: 1/0/0 default/read/poll queues test # [ 0.413370] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.419427] megasas: 07.734.00.00-rc1 test # [ 0.417872] megasas: 07.734.00.00-rc1 second # [ 0.419890] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.418295] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.422354] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.422376] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.424019] Using buffer write method test # [ 0.424067] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] second # [ 0.430609] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.430632] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.432404] Using buffer write method second # [ 0.432469] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] second # [ 0.436869] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.436881] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.434250] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.434289] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.436003] Using buffer write method test # [ 0.436015] Concatenating MTD devices: test # [ 0.436017] (0): "0.flash" test # [ 0.436019] (1): "0.flash" test # [ 0.436020] into device "0.flash" second # [ 0.447900] Using buffer write method second # [ 0.447912] Concatenating MTD devices: second # [ 0.447914] (0): "0.flash" second # [ 0.447916] (1): "0.flash" second # [ 0.447918] into device "0.flash" second # [ 0.650901] Freeing initrd memory: 26948K test # [ 0.650422] Freeing initrd memory: 26920K second # [ 0.658267] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.660518] thunder_xcv, ver 1.0 second # [ 0.660533] thunder_bgx, ver 1.0 second # [ 0.660541] nicpf, ver 1.0 second # [ 0.660865] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.657760] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.660869] e1000: Copyright (c) 1999-2006 Intel Corporation. test # [ 0.659963] thunder_xcv, ver 1.0 second # [ 0.660883] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.659980] thunder_bgx, ver 1.0 test # [ 0.659989] nicpf, ver 1.0 second # [ 0.660887] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.660898] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.660900] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.660909] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.660914] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.660976] sky2: driver version 1.30 test # [ 0.661476] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.661482] e1000: Copyright (c) 1999-2006 Intel Corporation. test # [ 0.661497] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.661502] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.661513] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.661515] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.661526] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.667251] ehci-pci 0000:00:07.0: EHCI Host Controller test # [ 0.661530] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.667284] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 test # [ 0.661598] sky2: driver version 1.30 second # [ 0.667494] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 test # [ 0.662409] usbcore: registered new interface driver usb-storage second # [ 0.667683] usbcore: registered new interface driver usb-storage test # [ 0.662450] usbcore: registered new interface driver usbserial_generic second # [ 0.667794] usbcore: registered new interface driver usbserial_generic test # [ 0.662468] usbserial: USB Serial support registered for generic second # [ 0.667816] usbserial: USB Serial support registered for generic test # [ 0.662758] hv_vmbus: registering driver hyperv_keyboard second # [ 0.668123] hv_vmbus: registering driver hyperv_keyboard test # [ 0.663721] ehci-pci 0000:00:07.0: EHCI Host Controller second # [ 0.668630] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.663765] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 second # [ 0.668656] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T00:04:48 UTC (1790985888) test # [ 0.664064] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 second # [ 0.668835] i2c_dev: i2c /dev entries driver second # [ 0.675223] sdhci: Secure Digital Host Controller Interface driver test # [ 0.671230] rtc-pl031 9010000.pl031: registered as rtc0 second # [ 0.675228] sdhci: Copyright(c) Pierre Ossman second # [ 0.675380] Synopsys Designware Multimedia Card Interface Driver second # [ 0.675559] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.671261] rtc-pl031 9010000.pl031: setting system clock to 2026-10-03T00:04:48 UTC (1790985888) second # [ 0.676415] hid: raw HID events driver (C) Jiri Kosina test # [ 0.671463] i2c_dev: i2c /dev entries driver second # [ 0.676560] usbcore: registered new interface driver usbhid second # [ 0.676563] usbhid: USB HID core driver test # [ 0.673032] sdhci: Secure Digital Host Controller Interface driver test # [ 0.673037] sdhci: Copyright(c) Pierre Ossman test # [ 0.673185] Synopsys Designware Multimedia Card Interface Driver test # [ 0.673358] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.674185] hid: raw HID events driver (C) Jiri Kosina test # [ 0.674318] usbcore: registered new interface driver usbhid test # [ 0.674320] usbhid: USB HID core driver test # [ 0.715877] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 second # [ 0.721768] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 second # [ 0.722121] hub 1-0:1.0: USB hub found second # [ 0.722144] hub 1-0:1.0: 6 ports detected second # [ 0.722428] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.723283] drop_monitor: Initializing network drop monitor service test # [ 0.722287] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.723388] NET: Registered PF_INET6 protocol family second # [ 0.724008] Segment Routing with IPv6 test # [ 0.723085] drop_monitor: Initializing network drop monitor service second # [ 0.724025] In-situ OAM (IOAM) with IPv6 test # [ 0.723193] NET: Registered PF_INET6 protocol family second # [ 0.724069] NET: Registered PF_PACKET protocol family test # [ 0.723864] hub 1-0:1.0: USB hub found second # [ 0.724125] 9pnet: Installing 9P2000 support test # [ 0.723889] hub 1-0:1.0: 6 ports detected test # [ 0.724396] Segment Routing with IPv6 second # [ 0.724156] Key type dns_resolver registered test # [ 0.724413] In-situ OAM (IOAM) with IPv6 test # [ 0.724479] NET: Registered PF_PACKET protocol family test # [ 0.727626] 9pnet: Installing 9P2000 support test # [ 0.727663] Key type dns_resolver registered second # [ 0.731196] registered taskstats version 1 second # [ 0.731349] Loading compiled-in X.509 certificates test # [ 0.731091] registered taskstats version 1 test # [ 0.731238] Loading compiled-in X.509 certificates second # [ 0.736608] Demotion targets for Node 0: null second # [ 0.736673] Key type .fscrypt registered second # [ 0.736679] Key type fscrypt-provisioning registered second # [ 0.736779] ima: No TPM chip found, activating TPM-bypass! second # [ 0.736789] ima: Allocated hash algorithm: sha1 second # [ 0.736843] ima: No architecture policies found second # [ 0.737280] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.736732] Demotion targets for Node 0: null test # [ 0.736807] Key type .fscrypt registered test # [ 0.736813] Key type fscrypt-provisioning registered test # [ 0.736911] ima: No TPM chip found, activating TPM-bypass! test # [ 0.736921] ima: Allocated hash algorithm: sha1 test # [ 0.736976] ima: No architecture policies found test # [ 0.737419] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.749540] clk: Disabling unused clocks second # [ 0.749548] PM: genpd: Disabling unused power domains second # [ 0.752483] Freeing unused kernel memory: 4736K second # [ 0.752556] Run /init as init process test # [ 0.749522] clk: Disabling unused clocks test # [ 0.749530] PM: genpd: Disabling unused power domains test # [ 0.751763] Freeing unused kernel memory: 4736K test # [ 0.751842] Run /init as init process second # [ 0.772285] systemd[1]: Successfully made /usr/ read-only. test # [ 0.774658] systemd[1]: Successfully made /usr/ read-only. second # [ 0.969773] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 0.972255] usb 1-1: new high-speed USB device number 2 using ehci-pci second # [ 1.106386] systemd[1]: systemd 261.3 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) second # [ 1.106416] systemd[1]: Detected virtualization qemu. second # [ 1.106455] systemd[1]: Detected architecture arm64. second # [ 1.106464] systemd[1]: Running in initrd. second # [ 1.107291] systemd[1]: Initializing machine ID from random generator. test # [ 1.108785] systemd[1]: systemd 261.3 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) second # [ 1.107562] systemd[1]: Hostname set to . test # [ 1.108815] systemd[1]: Detected virtualization qemu. test # [ 1.108854] systemd[1]: Detected architecture arm64. test # [ 1.108863] systemd[1]: Running in initrd. test # [ 1.109633] systemd[1]: Initializing machine ID from random generator. test # [ 1.109897] systemd[1]: Hostname set to . second # [ 1.127477] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 test # [ 1.138985] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 second # [ 1.217860] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 test # [ 1.224355] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 second # [ 1.241192] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 1.247885] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 1.290041] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.299190] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.299317] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.299347] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.299367] systemd[1]: Reached target Path Units. second # [ 1.299378] systemd[1]: Reached target Slice Units. second # [ 1.299388] systemd[1]: Reached target Swaps. second # [ 1.299398] systemd[1]: Reached target Timer Units. second # [ 1.299520] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.299623] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.299704] systemd[1]: Listening on Journal Sockets. test # [ 1.300731] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.299764] systemd[1]: Listening on udev Control Socket. second # [ 1.299835] systemd[1]: Listening on udev Kernel Socket. second # [ 1.299853] systemd[1]: Reached target Socket Units. second # [ 1.300775] systemd[1]: Starting Create List of Static Device Nodes... second # [ 1.300822] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 1.312406] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.309506] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.309637] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.309665] systemd[1]: Expecting device /dev/disk/by-label/nixos... test # [ 1.309684] systemd[1]: Reached target Path Units. test # [ 1.309695] systemd[1]: Reached target Slice Units. test # [ 1.309705] systemd[1]: Reached target Swaps. test # [ 1.309715] systemd[1]: Reached target Timer Units. test # [ 1.309837] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 1.309938] systemd[1]: Listening on Journal Socket (/dev/log). test # [ 1.310041] systemd[1]: Listening on Journal Sockets. test # [ 1.310104] systemd[1]: Listening on udev Control Socket. test # [ 1.310142] systemd[1]: Listening on udev Kernel Socket. test # [ 1.310155] systemd[1]: Reached target Socket Units. test # [ 1.311104] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.311151] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 1.321805] systemd[1]: Starting Journal Service... test # [ 1.323229] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.328438] systemd[1]: Starting Journal Service... second # [ 1.337726] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.338013] systemd[1]: Starting Load Kernel Modules... second # [ 1.338072] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 1.339003] systemd[1]: Starting Load Kernel Modules... test # [ 1.339063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 1.348226] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.348843] systemd[1]: Starting Coldplug All udev Devices... second # [ 1.355459] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.356282] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.365517] systemd-journald[71]: Collecting audit messages is disabled. test # [ 1.360909] systemd[1]: Finished Create List of Static Device Nodes. test # [ 1.361731] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.366560] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.367357] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.367583] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev test # [ 1.374880] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.375621] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.374982] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 second # [ 1.375189] [drm] features: -virgl +edid -resource_blob -host_visible second # [ 1.375190] [drm] features: -context_init second # [ 1.375761] [drm] number of scanouts: 1 second # [ 1.375769] [drm] number of cap sets: 0 second # [ 1.384286] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 1.382916] systemd-journald[71]: Collecting audit messages is disabled. test # [ 1.384855] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.397999] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic second # [ 1.398008] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 test # [ 1.397199] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.401181] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 1.415005] Console: switching to colour frame buffer device 160x50 test # [ 1.418617] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 test # [ 1.418821] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.418823] [drm] features: -context_init test # [ 1.419262] [drm] number of scanouts: 1 test # [ 1.419271] [drm] number of cap sets: 0 test # [ 1.425868] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic test # [ 1.425876] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 second # [ 1.430107] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.431096] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.432604] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.432728] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.432747] systemd[1]: Reached target Local File Systems. test # [ 1.433785] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 1.442317] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.450084] Console: switching to colour frame buffer device 160x50 second # [ 1.454711] systemd[1]: Finished Load Kernel Modules. second # [ 1.455672] systemd[1]: Starting Apply Kernel Variables... second # [ 1.450158] systemd-modules-load[72]: Inserted module 'dm_mod' second # [ 1.451049] systemd-modules-load[72]: Module 'virtio_balloon' is built in second # [ 1.451895] systemd-modules-load[72]: Module 'virtio_console' is built in second # [ 1.462488] systemd[1]: Started Journal Service. second # [ 1.456692] systemd-modules-load[72]: Inserted module 'virtio_gpu' second # [ 1.457394] systemd-modules-load[72]: Module 'virtio_rng' is built in test # [ 1.464757] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.468803] systemd-modules-load[72]: Inserted module 'dm_mod' test # [ 1.470683] systemd-modules-load[72]: Module 'virtio_balloon' is built in second # [ 1.472650] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.471443] systemd-modules-load[72]: Module 'virtio_console' is built in test # [ 1.477794] systemd[1]: Started Journal Service. second # [ 1.475651] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.476283] systemd-modules-load[72]: Inserted module 'virtio_gpu' test # [ 1.476952] systemd-modules-load[72]: Module 'virtio_rng' is built in second # [ 1.478453] systemd[1]: Reached target Local File Systems. test # [ 1.477621] systemd[1]: Finished Load Kernel Modules. test # [ 1.478272] systemd[1]: Starting Apply Kernel Variables... second # [ 1.491735] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 second # [ 1.491903] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 second # [ 1.486193] systemd[1]: Starting Create System Files and Directories... second # [ 1.496253] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.496113] systemd[1]: Starting Create System Files and Directories... second # [ 1.508465] systemd[1]: Finished Apply Kernel Variables. test # [ 1.512656] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 test # [ 1.517566] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 test # [ 1.516337] systemd-udevd[79]: Using default interface naming scheme 'v261'. second # [ 1.520588] systemd[1]: Finished Create System Files and Directories. test # [ 1.528643] systemd[1]: Finished Apply Kernel Variables. second # [ 1.537603] systemd-udevd[87]: Using default interface naming scheme 'v261'. test # [ 1.536665] systemd[1]: Finished Create System Files and Directories. test # [ 1.544958] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.556906] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.600152] systemd[1]: Starting Virtual Console Setup... test # [ 1.602403] systemd[1]: Starting Virtual Console Setup... test # [ 1.640330] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.642482] systemd[1]: Finished Virtual Console Setup. second # [ 1.644395] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.646690] systemd[1]: Finished Virtual Console Setup. second # [ 1.857161] systemd[1]: Finished Coldplug All udev Devices. second # [ 1.857775] systemd[1]: Reached target System Initialization. second # [ 1.858308] systemd[1]: Reached target Basic System. test # [ 1.868509] systemd[1]: Finished Coldplug All udev Devices. test # [ 1.869095] systemd[1]: Reached target System Initialization. test # [ 1.869618] systemd[1]: Reached target Basic System. second # [ 1.958709] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. test # [ 1.964263] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. test # [ 1.981966] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. second # [ 1.984303] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.036408] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.044092] systemd[1]: Found device /dev/disk/by-label/nixos. test # [ 2.044363] systemd[1]: Reached target Initrd Root Device. second # [ 2.046963] systemd[1]: Reached target Initrd Root Device. second # [ 2.048154] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.048181] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.080736] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.094777] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.093571] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.100189] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.342612] systemd[1]: Mounting /sysroot... second # [ 2.347410] systemd[1]: Mounting /sysroot... test # [ 2.375541] EXT4-fs (vda): mounted filesystem 80dda120-7136-485e-9bf4-6225ad27eebb r/w with ordered data mode. Quota mode: none. test # [ 2.371010] systemd[1]: Mounted /sysroot. second # [ 2.381213] EXT4-fs (vda): mounted filesystem 4bb1824f-be15-43fc-948c-bdbeaa112076 r/w with ordered data mode. Quota mode: none. test # [ 2.372469] systemd[1]: Reached target Initrd Root File System. second # [ 2.374286] systemd[1]: Mounted /sysroot. second # [ 2.375164] systemd[1]: Reached target Initrd Root File System. test # [ 2.377253] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 2.379487] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 2.390533] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 2.392524] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 2.400069] systemd[1]: Mounting /sysroot/run... test # [ 2.402070] systemd[1]: Mounting /sysroot/run... second # [ 2.413517] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 2.412803] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 2.415218] systemd[1]: Mounting /sysroot/tmp/xchg... second # [ 2.426379] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 2.457995] fuse: init (API version 7.45) test # [ 2.452488] systemd[1]: Starting Mountpoints Configured in the Real Root... second # [ 2.457362] systemd[1]: Starting Mountpoints Configured in the Real Root... second # [ 2.464250] fuse: init (API version 7.45) test # [ 2.459849] systemd[1]: Mounted /sysroot/nix/.rw-store.[ 2.466596] virtiofs virtio6: discovered new tag: nix-store test # [ 2.467086] virtiofs virtio6: virtio_fs_setup_dax: No cache capability test # second # [ 2.464629] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 2.477609] virtiofs virtio6: discovered new tag: nix-store second # [ 2.479980] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.481696] virtiofs virtio7: discovered new tag: shared test # [ 2.482201] virtiofs virtio7: virtio_fs_setup_dax: No cache capability second # [ 2.491067] virtiofs virtio6: virtio_fs_setup_dax: No cache capability test # [ 2.491299] virtiofs virtio8: discovered new tag: xchg second # [ 2.486455] systemd[1]: Mounted /sysroot/run. test # [ 2.491792] virtiofs virtio8: virtio_fs_setup_dax: No cache capability test # [ 2.488558] systemd-sysroot-fstab-check[123]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.494147] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 2.507195] virtiofs virtio7: discovered new tag: shared second # [ 2.507699] virtiofs virtio7: virtio_fs_setup_dax: No cache capability second # [ 2.509493] virtiofs virtio8: discovered new tag: xchg second # [ 2.504100] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... second # [ 2.505104] systemd[1]: Reloading... second # [ 2.516944] virtiofs virtio8: virtio_fs_setup_dax: No cache capability test # [ 2.499677] systemd[1]: Mounted /sysroot/run. test # [ 2.511872] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 2.513502] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 2.514976] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 2.521914] systemd[1]: Reload requested from client PID 123 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.525465] systemd[1]: Reloading... second # [ 2.679757] systemd[1]: Reloading finished in 176 ms. test # [ 2.694422] systemd[1]: Reloading finished in 170 ms. second # [ 2.708575] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... second # [ 2.710801] systemd[1]: Mounted /sysroot/nix/.ro-store. second # [ 2.712553] systemd[1]: Mounted /sysroot/tmp/shared. second # [ 2.714593] systemd[1]: Mounted /sysroot/tmp/xchg. second # [ 2.718667] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... second # [ 2.720866] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.724127] systemd[1]: Finished Mountpoints Configured in the Real Root. test # [ 2.722749] systemd-sysroot-fstab-check[123]: Requesting initrd-fs.target/start/replace... second # [ 2.726067] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.725002] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 2.728141] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 2.730159] systemd-sysroot-fstab-check[123]: Requesting swap.target/start/replace... second # [ 2.734389] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 2.738758] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 2.743453] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.746164] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 2.747037] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 2.749301] systemd[1]: Finished Mountpoints Configured in the Real Root. test # [ 2.749923] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.752945] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 2.753923] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 2.869143] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory second # [ 2.873666] (udev-worker)[92]: mtd0ro: /etc/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 directory second # [ 2.892631] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 2.894618] systemd[1]: Stopped Virtual Console Setup. second # [ 2.895661] systemd[1]: Stopping Virtual Console Setup... second # [ 2.897936] systemd[1]: Starting Virtual Console Setup... second # [ 2.908442] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 2.909960] systemd[1]: Stopped Virtual Console Setup. second # [ 2.910673] systemd[1]: Starting Virtual Console Setup... test # [ 2.909285] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory test # [ 2.913263] (udev-worker)[94]: mtd0ro: /etc/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 directory second # [ 2.924271] systemd-vconsole-setup[159]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 2.926070] systemd[1]: Finished Virtual Console Setup. test # [ 2.932418] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 2.934345] systemd[1]: Stopped Virtual Console Setup. test # [ 2.936112] systemd[1]: Stopping Virtual Console Setup... test # [ 2.937445] systemd[1]: Starting Virtual Console Setup... test # [ 2.952494] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 2.953435] systemd[1]: Stopped Virtual Console Setup. test # [ 2.954781] systemd[1]: Starting Virtual Console Setup... test # [ 2.968503] systemd-vconsole-setup[156]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 2.970406] systemd[1]: Finished Virtual Console Setup. test # [ 3.344608] systemd[1]: Mounting /sysroot/nix/store... second # [ 3.349858] systemd[1]: Mounting /sysroot/nix/store... test # [ 3.394979] systemd[1]: Mounted /sysroot/nix/store. second # [ 3.396660] systemd[1]: Mounted /sysroot/nix/store. second # [ 3.398984] systemd[1]: Reached target Initrd File Systems. test # [ 3.398225] systemd[1]: Reached target Initrd File Systems. test # [ 3.401287] systemd[1]: Starting Find NixOS closure... second # [ 3.402753] systemd[1]: Starting Find NixOS closure... second # [ 3.409751] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... test # [ 3.408314] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 3.444132] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 3.446700] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. test # [ 3.448775] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.451792] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. second # [ 3.459522] systemd[1]: Finished Find NixOS closure. second # [ 3.462160] systemd[1]: Reached target Initrd Default Target. second # [ 3.464468] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 3.463996] systemd[1]: Finished Find NixOS closure. test # [ 3.465459] systemd[1]: Reached target Initrd Default Target. test # [ 3.467849] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 3.496959] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 3.498514] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.499164] systemd[1]: Stopped target Initrd Default Target. test # [ 3.500858] systemd[1]: Stopped target Basic System. test # [ 3.502189] systemd[1]: Stopped target Initrd Root Device. second # [ 3.503703] systemd[1]: Stopped target Initrd Default Target. second # [ 3.504731] systemd[1]: Stopped target Basic System. second # [ 3.505583] systemd[1]: Stopped target Initrd Root Device. test # [ 3.504422] systemd[1]: Stopped target Path Units. second # [ 3.506347] systemd[1]: Stopped target Path Units. second # [ 3.507019] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.508311] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 3.510685] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.509446] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.510970] systemd[1]: Stopped target Slice Units. second # [ 3.516765] systemd[1]: Stopped target Slice Units. test # [ 3.516114] systemd[1]: Stopped target Socket Units. test # [ 3.516820] systemd[1]: Stopped target System Initialization. second # [ 3.518543] systemd[1]: Stopped target Socket Units. test # [ 3.517591] systemd[1]: Stopped target Swaps. second # [ 3.519200] systemd[1]: Stopped target System Initialization. test # [ 3.518208] systemd[1]: Stopped target Timer Units. second # [ 3.519897] systemd[1]: Stopped target Swaps. test # [ 3.518891] systemd[1]: dbus.socket: Deactivated successfully. test # [ 3.520560] systemd[1]: Closed D-Bus System Message Bus Socket. second # [ 3.523575] systemd[1]: Stopped target Timer Units. test # [ 3.522341] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 3.524554] systemd[1]: Stopped Find NixOS closure. test # [ 3.526410] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.528336] systemd[1]: dbus.socket: Deactivated successfully. second # [ 3.529074] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.528834] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 3.530978] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 3.533683] systemd[1]: Stopped Find NixOS closure. second # [ 3.534479] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.533448] systemd[1]: Stopped Apply Kernel Variables. test # [ 3.534126] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 3.535816] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 3.536976] systemd[1]: Stopped Load Kernel Modules. second # [ 3.540096] systemd[1]: Stopped Apply Kernel Variables. test # [ 3.539320] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 3.542088] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. second # [ 3.544220] systemd[1]: systemd-modules-load.service: Deactivated successfully. test # [ 3.543263] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 3.544957] systemd[1]: Stopped Load Kernel Modules. second # [ 3.545565] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 3.546304] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. second # [ 3.547040] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 3.547736] systemd[1]: Stopped Create System Files and Directories. second # [ 3.548874] systemd[1]: Stopped target Local File Systems. second # [ 3.549940] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 3.550676] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. second # [ 3.551287] systemd[1]: Stopped Coldplug All udev Devices. test # [ 3.551422] systemd[1]: Stopped Create System Files and Directories. second # [ 3.551769] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 3.552505] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.553434] systemd[1]: Stopped Virtual Console Setup. second # [ 3.553893] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.554503] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.553247] systemd[1]: Stopped target Local File Systems. second # [ 3.555015] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.553902] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 3.555596] systemd[1]: Stopped Rule-based Manager for Device Events and Files. test # [ 3.556216] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.556916] systemd[1]: Stopped Coldplug All udev Devices. second # [ 3.559004] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 3.559651] systemd[1]: Closed udev Control Socket. test # [ 3.560270] systemd[1]: Stopping Rule-based Manager for Device Events and Files... test # [ 3.561017] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.561683] systemd[1]: Stopped Virtual Console Setup. second # [ 3.564237] systemd[1]: Starting Cleanup udev Database... test # [ 3.562464] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 3.564765] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 3.565444] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 3.566012] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 3.566718] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. second # [ 3.567355] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 3.567970] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 3.563051] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.563713] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.564431] systemd[1]: Stopped Rule-based Manager for Device Events and Files. test # [ 3.565028] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 3.565619] systemd[1]: Closed udev Control Socket. test # [ 3.566052] systemd[1]: Starting Cleanup udev Database... test # [ 3.566515] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 3.567146] systemd[1]: Stopped Create Static Device Nodes in /dev. test # [ 3.567863] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. test # [ 3.576231] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 3.576887] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 3.578882] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.577672] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 3.579600] systemd[1]: Finished Cleanup udev Database. test # [ 3.578251] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.578863] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.580706] systemd[1]: Reached target Switch Root. second # [ 3.584692] systemd[1]: Starting NixOS Activation... test # [ 3.588188] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.588895] systemd[1]: Finished Cleanup udev Database. test # [ 3.589356] systemd[1]: Reached target Switch Root. test # [ 3.592705] systemd[1]: Starting NixOS Activation... second # [ 3.647206] initrd-nixos-activation-start[182]: booting system configuration /nix/store/zc805yy98n4wgcl6blvgfrfzi3hamvv9-nixos-system-second-test test # [ 3.665296] initrd-nixos-activation-start[180]: booting system configuration /nix/store/nhzs5nq18r2wcww7qxwy0v9wknbxljdj-nixos-system-test-test second # [ 3.671245] initrd-nixos-activation-start[182]: running activation script... test # [ 3.692759] initrd-nixos-activation-start[180]: running activation script... second # [ 3.889611] initrd-nixos-activation-start[205]: setting up /etc... test # [ 3.900653] initrd-nixos-activation-start[203]: setting up /etc... second # [ 3.982674] initrd-nixos-activation-start[205]: setting up age secrets... test # [ 3.994964] initrd-nixos-activation-start[203]: setting up age secrets... second # [ 4.068428] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 4.070202] systemd[1]: Finished NixOS Activation. second # [ 4.070932] systemd[1]: Starting Switch Root... second # [ 4.088165] systemd[1]: Switching root. test # [ 4.112692] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 4.114424] systemd[1]: Finished NixOS Activation. test # [ 4.115141] systemd[1]: Starting Switch Root... test # [ 4.131905] systemd[1]: Switching root. second # [ 4.270059] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). test # [ 4.300512] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). second # [ 4.784022] systemd[1]: systemd 261.3 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) second # [ 4.784118] systemd[1]: Detected virtualization qemu. second # [ 4.784178] systemd[1]: Detected architecture arm64. second # [ 4.784317] systemd[1]: Detected first boot. second # [ 4.797292] systemd[1]: Initializing machine ID from random generator. test # [ 4.820469] systemd[1]: systemd 261.3 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) test # [ 4.820565] systemd[1]: Detected virtualization qemu. test # [ 4.820624] systemd[1]: Detected architecture arm64. test # [ 4.820751] systemd[1]: Detected first boot. test # [ 4.823533] systemd[1]: Initializing machine ID from random generator. second # [ 4.926141] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 4.966105] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 5.009586] systemd[1]: Applying preset policy. test # [ 5.049888] systemd[1]: Applying preset policy. second # [ 5.197090] systemd[1]: Populated /etc with preset unit settings. test # [ 5.241229] systemd[1]: Populated /etc with preset unit settings. second # [ 5.420569] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 5.421222] systemd[1]: Stopped initrd-switch-root.service. second # [ 5.424628] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 5.426950] systemd[1]: Created slice Slice /system/getty. second # [ 5.428009] systemd[1]: Created slice User and Session Slice. second # [ 5.428609] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 5.429228] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 5.432532] systemd[1]: Expecting device /dev/hvc0... second # [ 5.433013] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 5.433553] systemd[1]: Reached target Local Encrypted Volumes. second # [ 5.435967] systemd[1]: Stopped target initrd-fs.target. second # [ 5.436445] systemd[1]: Stopped target initrd-root-fs.target. second # [ 5.436979] systemd[1]: Stopped target initrd-switch-root.target. second # [ 5.437537] systemd[1]: Reached target Virtual Machines and Containers. second # [ 5.440535] systemd[1]: Reached target Path Units. second # [ 5.441034] systemd[1]: Reached target Remote File Systems. second # [ 5.441581] systemd[1]: Reached target Slice Units. second # [ 5.443877] systemd[1]: Reached target Swaps. second # [ 5.446179] systemd[1]: Listening on Query the User Interactively for a Password. second # [ 5.448225] systemd[1]: Listening on Process Core Dump Socket. second # [ 5.450859] systemd[1]: Listening on Credential Encryption/Decryption. second # [ 5.452607] systemd[1]: Listening on Factory Reset Management. second # [ 5.453160] systemd[1]: Listening on Hostname Service Socket. second # [ 5.457373] systemd[1]: Starting Journal Log Access Socket... second # [ 5.459060] systemd[1]: Listening on Journal Audit Socket. second # [ 5.461327] systemd[1]: Listening on Console Output Muting Service Socket. second # [ 5.463528] systemd[1]: Listening on Network Management Metrics Varlink Socket. second # [ 5.464229] systemd[1]: Listening on Network Management Varlink Socket. second # [ 5.464895] systemd[1]: Listening on Network Management Netlink Socket. second # [ 5.465603] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 5.469078] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os second # [ 5.471346] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 5.475427] systemd[1]: Listening on Disk Repartitioning Service Socket. second # [ 5.475963] systemd[1]: Listening on Resolve Monitor Varlink Socket. second # [ 5.476508] systemd[1]: Listening on Resolve Service Varlink Socket. test # [ 5.475343] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 5.477059] systemd[1]: Listening on udev Control Socket. test # [ 5.475617] systemd[1]: Stopped initrd-switch-root.service. second # [ 5.477614] systemd[1]: Listening on udev Varlink Socket. test # [ 5.479145] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 5.482424] systemd[1]: Mounting Huge Pages File System... second # [ 5.484560] systemd[1]: Mounting POSIX Message Queue File System... test # [ 5.481337] systemd[1]: Created slice Slice /system/getty. test # [ 5.482269] systemd[1]: Created slice User and Session Slice. test # [ 5.482856] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 5.483439] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 5.483997] systemd[1]: Expecting device /dev/hvc0... test # [ 5.487576] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 5.488073] systemd[1]: Reached target Local Encrypted Volumes. test # [ 5.490048] systemd[1]: Stopped target initrd-fs.target. test # [ 5.490514] systemd[1]: Stopped target initrd-root-fs.target. test # [ 5.491059] systemd[1]: Stopped target initrd-switch-root.target. test # [ 5.491606] systemd[1]: Reached target Virtual Machines and Containers. second # [ 5.494277] systemd[1]: Mounting Kernel Debug File System... test # [ 5.492171] systemd[1]: Reached target Path Units. test # [ 5.495428] systemd[1]: Reached target Remote File Systems. test # [ 5.495912] systemd[1]: Reached target Slice Units. second # [ 5.500640] systemd[1]: Mounting Kernel Trace File System... test # [ 5.497852] systemd[1]: Reached target Swaps. test # [ 5.499932] systemd[1]: Listening on Query the User Interactively for a Password. test # [ 5.503062] systemd[1]: Listening on Process Core Dump Socket. test # [ 5.505202] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 5.507056] systemd[1]: Listening on Factory Reset Management. test # [ 5.507592] systemd[1]: Listening on Hostname Service Socket. second # [ 5.511149] systemd[1]: Starting Create List of Static Device Nodes... second # [ 5.512958] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 5.512089] systemd[1]: Starting Journal Log Access Socket... test # [ 5.513775] systemd[1]: Listening on Journal Audit Socket. test # [ 5.516046] systemd[1]: Listening on Console Output Muting Service Socket. test # [ 5.518220] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 5.518908] systemd[1]: Listening on Network Management Varlink Socket. test # [ 5.519566] systemd[1]: Listening on Network Management Netlink Socket. test # [ 5.522334] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 5.523656] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.524085] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 5.527106] systemd[1]: Mounting Kernel Configuration File System... second # [ 5.527462] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 5.527719] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 5.527964] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse test # [ 5.530217] systemd[1]: Listening on Disk Repartitioning Service Socket. test # [ 5.530721] systemd[1]: Listening on Resolve Monitor Varlink Socket. test # [ 5.531256] systemd[1]: Listening on Resolve Service Varlink Socket. test # [ 5.531798] systemd[1]: Listening on udev Control Socket. test # [ 5.534802] systemd[1]: Listening on udev Varlink Socket. test # [ 5.537391] systemd[1]: Mounting Huge Pages File System... test # [ 5.539699] systemd[1]: Mounting POSIX Message Queue File System... test # [ 5.546216] systemd[1]: Mounting Kernel Debug File System... second # [ 5.553976] systemd[1]: Mounting FUSE Control File System... second # [ 5.554361] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 5.556713] systemd[1]: Mounting Kernel Trace File System... test # [ 5.563928] systemd[1]: Starting Create List of Static Device Nodes... test # [ 5.567351] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 5.574933] systemd[1]: Starting Journal Service... test # [ 5.580097] systemd[1]: Mounting Kernel Configuration File System... second # [ 5.586782] systemd[1]: Starting Load Kernel Modules... test # [ 5.585841] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm test # [ 5.586199] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore test # [ 5.586460] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 5.595718] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 5.608354] systemd[1]: Mounting FUSE Control File System... test # [ 5.608851] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 second # [ 5.615133] systemd[1]: Starting Remount Root and Kernel File Systems... second # [ 5.615515] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 5.622488] systemd[1]: Starting Coldplug All udev Devices... second # [ 5.624388] systemd[1]: Listening on Journal Log Access Socket. second # [ 5.624774] systemd[1]: Mounted Huge Pages File System. second # [ 5.625132] systemd[1]: Mounted POSIX Message Queue File System. second # [ 5.625467] systemd[1]: Mounted Kernel Debug File System. test # [ 5.630135] systemd[1]: Starting Journal Service... second # [ 5.641893] systemd[1]: Mounted Kernel Trace File System. second # [ 5.644957] systemd[1]: Finished Create List of Static Device Nodes. second # [ 5.645521] systemd[1]: Mounted Kernel Configuration File System. test # [ 5.640955] systemd[1]: Starting Load Kernel Modules... second # [ 5.649904] systemd[1]: Mounted FUSE Control File System. second # [ 5.655225] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 5.652515] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 5.659919] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 5.660950] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 5.676569] systemd-journald[309]: Collecting audit messages is enabled. test # [ 5.675882] systemd[1]: Starting Coldplug All udev Devices... test # [ 5.678951] systemd[1]: Listening on Journal Log Access Socket. test # [ 5.679421] systemd[1]: Mounted Huge Pages File System. test # [ 5.679872] systemd[1]: Mounted POSIX Message Queue File System. second # [ 5.683130] systemd[1]: Queued start job for default target Multi-User System. second # [ 5.690757] systemd[1]: Finished Load Kernel Modules. test # [ 5.688657] systemd[1]: Mounted Kernel Debug File System. test # [ 5.689127] systemd[1]: Mounted Kernel Trace File System. test # [ 5.699011] systemd[1]: Finished Create List of Static Device Nodes. second # [ 5.700038] systemd[1]: Starting Firewall... second # [ 5.708054] systemd[1]: Starting Apply Kernel Variables... second # [ 5.708510] systemd[1]: Started Journal Service. test # [ 5.708401] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 5.723204] systemd-journald[328]: Collecting audit messages is enabled. second # [ 5.730314] EXT4-fs (vda): re-mounted 4bb1824f-be15-43fc-948c-bdbeaa112076. test # [ 5.728663] systemd[1]: Finished Load Kernel Modules. test # [ 5.735588] systemd[1]: Starting Firewall... test # [ 5.731295] systemd[1]: Queued start job for default target Multi-User System. test # [ 5.741308] EXT4-fs (vda): re-mounted 80dda120-7136-485e-9bf4-6225ad27eebb. test # [ 5.746014] systemd[1]: Starting Apply Kernel Variables... test # [ 5.746933] systemd[1]: Started Journal Service. test # [ 5.741606] systemd[1]: systemd-journald.service: Deactivated successfully. test # [ 5.742397] systemd-modules-load[329]: Module 'atkbd' is built in test # [ 5.743063] systemd-modules-load[329]: Module 'ctr' is built in test # [ 5.743687] systemd-modules-load[329]: Module 'loop' is built in test # [ 5.751320] systemd[1]: Mounted FUSE Control File System. test # [ 5.773261] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 5.778236] systemd[1]: Mounted Kernel Configuration File System. test # [ 5.789216] systemd[1]: Listening on Disk Image Download Service Socket. test # [ 5.790797] systemd-oomd[330]: No swap; memory pressure usage will be degraded test # [ 5.803511] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 5.804249] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 5.807877] systemd[1]: Starting Load/Save OS Random Seed... test # [ 5.811400] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.816384] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 5.869019] systemd-journald[328]: Received client request to flush runtime journal. test # [ 5.908824] systemd[1]: Finished Apply Kernel Variables. test # [ 5.909393] systemd[1]: Starting Network Name Resolution... test # [ 5.909895] systemd[1]: Finished Load/Save OS Random Seed. test # [ 5.910382] systemd[1]: Reached target First Boot Complete. test # [ 5.924629] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 5.941528] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 5.943181] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 6.064286] systemd-resolved[353]: Positive Trust Anchors: test # [ 6.064870] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 6.065715] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 6.066583] systemd-resolved[353]: 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 test test # [ 6.238527] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 6.239193] systemd[1]: Reached target Preparation for Local File Systems. test # [ 6.241168] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 6.242528] systemd[1]: Finished Coldplug All udev Devices. test # [ 6.244060] systemd-resolved[353]: Using system hostname 'test'. test # [ 6.247159] systemd[1]: Started Network Name Resolution. test # [ 6.297607] systemd-udevd[369]: Using default interface naming scheme 'v261'. second # [ 5.685270] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 6.318549] systemd-journald[309]: Received client request to flush runtime journal. second # [ 6.344918] systemd-modules-load[310]: Module 'atkbd' is built in second # [ 6.345661] systemd-modules-load[310]: Module 'ctr' is built in second # [ 6.346361] systemd-modules-load[310]: Module 'loop' is built in second # [ 6.347216] systemd-oomd[311]: No swap; memory pressure usage will be degraded second # [ 6.354258] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. second # [ 6.360206] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 6.360928] systemd[1]: Listening on Disk Image Download Service Socket. second # [ 6.361653] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 6.362351] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore second # [ 6.363446] systemd[1]: Starting Load/Save OS Random Seed... test # [ 6.364913] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 6.368362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 6.369258] systemd[1]: Finished Load/Save OS Random Seed. second # [ 6.369856] systemd[1]: Reached target First Boot Complete. second # [ 6.370480] systemd[1]: Finished Apply Kernel Variables. second # [ 6.371072] systemd[1]: Starting Network Name Resolution... second # [ 6.371686] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 6.380248] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 6.381139] systemd-resolved[325]: Positive Trust Anchors: second # [ 6.381658] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d second # [ 6.382529] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 6.383412] systemd-resolved[325]: 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 test second # [ 6.410595] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 6.412780] systemd[1]: Reached target Preparation for Local File Systems. second # [ 6.413428] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 6.414078] systemd[1]: Finished Coldplug All udev Devices. second # [ 6.414578] systemd-resolved[325]: Using system hostname 'second'. second # [ 6.415117] systemd[1]: Started Network Name Resolution. second # [ 6.415790] systemd-udevd[333]: Using default interface naming scheme 'v261'. second # [ 6.418438] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 6.419067] systemd[1]: Finished Flush Journal to Persistent Storage. second # [ 6.419633] systemd[1]: Mounting /run/wrappers... second # [ 6.450435] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 6.460616] systemd[1]: Mounted /run/wrappers. second # [ 6.461684] systemd[1]: Reached target Local File Systems. second # [ 6.466074] systemd[1]: Listening on Boot Loader Control Service Socket. second # [ 6.469474] systemd[1]: Starting register-nix-paths.service... test # [ 6.470770] systemd[1]: Mounting /run/wrappers... second # [ 6.472408] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 6.473015] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 6.477980] systemd[1]: Starting Save Transient machine-id to Disk... test # [ 6.481334] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 6.495003] systemd[1]: Starting Create System Files and Directories... second # [ 6.499774] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse test # [ 6.505579] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse test # [ 6.520473] systemd[1]: Mounted /run/wrappers. test # [ 6.520944] systemd[1]: Reached target Local File Systems. test # [ 6.524660] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 6.529156] systemd[1]: Starting register-nix-paths.service... test # [ 6.531934] systemd[1]: Starting Create SUID/SGID Wrappers... test # [ 6.532959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 6.536654] systemd[1]: Starting Save Transient machine-id to Disk... test # [ 6.550299] systemd[1]: Starting Create System Files and Directories... second # [ 6.601222] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. second # [ 6.607164] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 6.637593] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 6.643809] systemd[1]: Finished Save Transient machine-id to Disk. second # [ 6.651434] systemd[1]: Finished Create System Files and Directories. second # [ 6.659894] systemd[1]: Starting Rebuild Journal Catalog... second # [ 6.662974] systemd[1]: Starting Record System Boot/Shutdown in UTMP... test # [ 6.692292] systemd[1]: Finished Create System Files and Directories. test # [ 6.700357] systemd[1]: Starting Rebuild Journal Catalog... test # [ 6.706009] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 6.735045] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 6.745504] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 6.770793] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 6.785390] systemd[1]: Finished Record System Boot/Shutdown in UTMP. second # [ 6.823094] systemd[1]: Finished Rebuild Journal Catalog. test # [ 6.821529] systemd[1]: Finished Record System Boot/Shutdown in UTMP. second # [ 6.829965] systemd[1]: Starting Update is Completed... second # [ 6.848369] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 6.873174] systemd[1]: Finished Rebuild Journal Catalog. test # [ 6.880092] systemd[1]: Starting Update is Completed... second # [ 6.888120] systemd[1]: Finished Update is Completed. test # [ 6.936568] systemd[1]: Finished Update is Completed. test # [ 7.069931] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line. second # [ 7.081535] (udev-worker)[379]: Network interface NamePolicy= disabled on kernel command line. second # [ 7.089384] (udev-worker)[381]: Network interface NamePolicy= disabled on kernel command line. test # [ 7.087782] (udev-worker)[402]: Network interface NamePolicy= disabled on kernel command line. second # [ 7.296355] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 7.299800] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 7.331143] systemd[1]: Finished register-nix-paths.service. second # [ 7.333476] systemd[1]: Reached target System Initialization. second # [ 7.336427] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 7.337116] systemd[1]: Started Daily Cleanup of Temporary Directories. second # [ 7.337710] systemd[1]: Reached target Timer Units. second # [ 7.338179] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 7.338762] systemd[1]: Listening on Nix Daemon Socket. test # [ 7.346272] mousedev: PS/2 mouse device common for all mice second # [ 7.349503] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 7.350130] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. second # [ 7.350888] systemd[1]: Reached target Socket Units. test # [ 7.349553] systemd[1]: Finished register-nix-paths.service. second # [ 7.354624] systemd[1]: Starting D-Bus System Message Bus... test # [ 7.365003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 7.374193] mousedev: PS/2 mouse device common for all mice test # [ 7.367095] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 7.367646] systemd[1]: Reached target System Initialization. test # [ 7.370224] systemd[1]: Started Discard unused filesystem blocks once a week. test # [ 7.370842] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 7.371419] systemd[1]: Reached target Timer Units. test # [ 7.371879] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 7.377277] systemd[1]: Listening on Nix Daemon Socket. test # [ 7.382062] systemd[1]: Listening on Authorization Manager Agent Helper. test # [ 7.382813] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. test # [ 7.383564] systemd[1]: Reached target Socket Units. test # [ 7.388955] systemd[1]: Starting D-Bus System Message Bus... second # [ 7.483830] dbus-broker-launch[504]: Looking up NSS user entry for 'systemd-timesync'... second # [ 7.492774] dbus-broker-launch[504]: NSS returned no entry for 'systemd-timesync' second # [ 7.493453] dbus-broker-launch[504]: Invalid user-name in /nix/store/15l45ll9fvv42h2g8acw1xxsr83ypjcq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 7.496057] dbus-broker-launch[525]: Looking up NSS user entry for 'systemd-timesync'... test # [ 7.504451] dbus-broker-launch[525]: NSS returned no entry for 'systemd-timesync' test # [ 7.505107] dbus-broker-launch[525]: Invalid user-name in /nix/store/15l45ll9fvv42h2g8acw1xxsr83ypjcq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" second # [ 7.526381] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' second # [ 7.529701] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 7.534392] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 7.541517] dbus-broker-launch[504]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 7.543193] systemd[1]: Started D-Bus System Message Bus. second # [ 7.543691] systemd[1]: Reached target Basic System. test # [ 7.538175] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' test # [ 7.542812] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 7.551864] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 7.547166] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' test # [ 7.552331] dbus-broker-launch[525]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 7.556602] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 7.557259] systemd[1]: Started backdoor.service. test # [ 7.559116] systemd[1]: Started D-Bus System Message Bus. test # [ 7.559722] systemd[1]: Reached target Basic System. test # [ 7.563722] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 7.571375] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 7.573143] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 7.592386] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 7.601519] systemd[1]: Starting Name Service Cache Daemon (nsncd)... second # [ 7.604817] dbus-broker-launch[504]: Ready test # [ 7.609069] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 7.614610] dbus-broker-launch[525]: Ready test # [ 7.628677] systemd[1]: Started backdoor.service. second # [ 7.630546] systemd[1]: Starting Post-Boot Actions... second # [ 7.634772] systemd[1]: Started Reset console on configuration changes. test # [ 7.635757] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 7.655099] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 7.679595] systemd[1]: Starting Post-Boot Actions... second # [ 7.711291] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[522]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 7.730330] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[542]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 7.740697] systemd[1]: Started Reset console on configuration changes. second # [ 7.759583] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # connecting to host... test # [ 7.755194] adg9kzka51ddw5qgki73hk89404rrphj-wifi-secrets[544]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 7.768273] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 7.776290] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 7.781601] systemd[1]: Finished Post-Boot Actions. test # [ 7.783355] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 7.804691] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 7.811017] systemd[1]: Reached target Host and Network Name Lookups. second # [ 7.811807] nsncd[526]: Oct 03 00:04:55.651 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 7.811906] pqf2w5skr70322kcnwqj9n6c53wanj8f-wifi-secrets[546]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 7.819268] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. second # [ 7.823244] systemd[1]: Reached target User and Group Name Lookups. test # [ 7.823182] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 7.825289] systemd[1]: Starting User Login Management... test # [ 7.823841] systemd[1]: Finished Firewall. test # connecting to host... second # [ 7.849899] systemd[1]: Finished Firewall. test # [ 7.849260] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 7.853755] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 7.858231] systemd[1]: Reached target Preparation for Network. second # [ 7.858789] systemd[1]: Listening on Network Management Resolve Hook Socket. second # [ 7.874406] systemd[1]: Starting Network Manager... test # [ 7.877951] systemd[1]: Reached target Preparation for Network. test # [ 7.880680] nsncd[551]: Oct 03 00:04:55.714 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 7.891292] systemd[1]: Listening on Network Management Resolve Hook Socket. test # [ 7.895285] systemd[1]: Starting Network Manager... second # [ 7.901387] systemd[1]: Starting Network Management... test: Guest shell says: b'Spawning backdoor root shell...\n' test # [ 7.902559] systemd[1]: Starting Network Management... test # [ 7.903043] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 7.904703] systemd[1]: Finished Import lastlog data into lastlog2 database. test # [ 7.914673] systemd[1]: Finished Post-Boot Actions. second # [ 7.924067] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 7.915122] systemd[1]: Reached target Host and Network Name Lookups. test # [ 7.915652] systemd[1]: Reached target User and Group Name Lookups. test # [ 7.933182] systemd[1]: Starting User Login Management... test # [ 7.942958] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 7.954190] systemd[1]: Finished Import lastlog data into lastlog2 database. test: connected to guest root shell test: (connecting took 8.21 seconds) test: (finished: waiting for the VM to finish booting, in 8.21 seconds) second # [ 8.019041] systemd-logind[558]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 8.020358] systemd-logind[558]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 8.028973] systemd-logind[558]: New seat seat0. second # [ 8.029436] systemd[1]: Started User Login Management. second # [ 8.033116] systemd[1]: Starting linger-users.service... second # [ 8.093777] systemd[1]: linger-users.service: Deactivated successfully. second # [ 8.094422] systemd[1]: Finished linger-users.service. test # [ 8.114392] systemd-logind[599]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 8.117423] systemd-logind[599]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 8.133567] systemd-logind[599]: New seat seat0. test # [ 8.134019] systemd[1]: Started User Login Management. test # [ 8.138861] systemd[1]: Starting linger-users.service... second # [ 8.173268] cfg80211: Loading compiled-in X.509 certificates for regulatory database test # [ 8.214985] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 8.236152] systemd[1]: Starting Hostname Service... second # [ 8.248199] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 8.248715] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' test # [ 8.251512] systemd[1]: linger-users.service: Deactivated successfully. test # [ 8.252594] systemd[1]: Finished linger-users.service. test # [ 8.288524] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 8.289007] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 8.300780] systemd-networkd[576]: /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. second # [ 8.308318] systemd-networkd[576]: /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. second # [ 8.310003] systemd-networkd[576]: lo: Link UP second # [ 8.310428] systemd-networkd[576]: lo: Gained carrier test # [ 8.317090] systemd[1]: Starting Hostname Service... second # [ 8.321507] systemd[1]: Started Network Management. second # [ 8.324677] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 8.325363] systemd-networkd[576]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 8.326662] systemd-networkd[576]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 8.340071] systemd-networkd[576]: eth1: Link UP second # [ 8.340561] systemd-networkd[576]: eth1: Gained carrier second # [ 8.341040] systemd-networkd[576]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 8.344839] systemd-networkd[598]: /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. test # [ 8.350960] systemd-networkd[598]: /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. test # [ 8.357269] systemd-networkd[598]: lo: Link UP test # [ 8.357701] systemd-networkd[598]: lo: Gained carrier test # [ 8.358153] systemd[1]: Started Network Management. test # [ 8.366171] systemd-networkd[598]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 8.367712] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... test # [ 8.378994] systemd-networkd[598]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 8.382791] systemd[1]: Started Hostname Service. test # [ 8.379765] systemd-networkd[598]: eth1: Link UP test # [ 8.384167] systemd-networkd[598]: eth1: Gained carrier test # [ 8.384636] systemd-networkd[598]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 8.405629] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 8.459024] systemd-networkd[576]: eth0: Link UP second # [ 8.459524] systemd-networkd[576]: eth0: Gained carrier test # [ 8.462221] systemd[1]: Started Hostname Service. test # [ 8.474857] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 8.477841] systemd[1]: Started Network Manager. second # [ 8.480183] systemd[1]: Reached target Network. second # [ 8.486052] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 8.490559] systemd[1]: Starting Permit User Sessions... test # [ 8.530294] systemd-networkd[598]: eth0: Link UP test # [ 8.532085] systemd-networkd[598]: eth0: Gained carrier test # [ 8.549624] systemd[1]: Started Network Manager. test # [ 8.551962] systemd[1]: Reached target Network. test # [ 8.556170] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... test # [ 8.573416] systemd[1]: Starting Permit User Sessions... second # [ 8.620213] systemd[1]: Finished Permit User Sessions. second # [ 8.629540] systemd[1]: Started Getty on tty1. second # [ 8.637572] systemd[1]: Reached target Login Prompts. test # [ 8.678586] systemd[1]: Finished Permit User Sessions. second # [ 8.693465] systemd-resolved[325]: eth0: Bus client set default route setting: yes second # [ 8.697642] systemd-resolved[325]: eth0: Bus client set DNS server list to: 10.0.2.3 test # [ 8.698795] systemd[1]: Started Getty on tty1. test # [ 8.699273] systemd[1]: Reached target Login Prompts. test # [ 8.743516] NetworkManager[597]: [1790985896.5771] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty test # [ 8.749834] NetworkManager[597]: [1790985896.5797] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty second # [ 8.756827] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. second # [ 8.761404] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 8.763507] systemd-resolved[353]: eth0: Bus client set default route setting: yes test # [ 8.767186] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 8.769373] systemd[1]: Reached target Multi-User System. second # [ 8.772452] systemd[1]: Startup finished in 748ms (kernel) + 3.578s (initrd) + 4.444s (userspace) = 8.771s. test # [ 8.827518] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. test # [ 8.833101] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. second # [ 8.896024] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 test # [ 8.929419] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 test # [ 9.178923] systemd[1]: Reached target Multi-User System. test # [ 9.180153] systemd[1]: Startup finished in 750ms (kernel) + 3.611s (initrd) + 4.817s (userspace) = 9.179s. second # [ 9.182211] systemd[1]: Starting Virtual Console Setup... second # [ 9.212089] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. second # [ 9.222372] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test: (finished: waiting for unit NetworkManager.service, in 9.47 seconds) second # [ 9.224064] systemd[1]: Stopped Virtual Console Setup. test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection second # [ 9.230982] systemd[1]: Starting Virtual Console Setup... test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds) cleanup kill QemuMachine (pid 47) second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down second # [2026-10-03T00:04:56Z INFO virtiofsd] Client disconnected, shutting down test # [ 9.288216] systemd[1]: Starting Virtual Console Setup... test # [ 9.296603] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 9.298548] systemd[1]: Stopped Virtual Console Setup. test # [ 9.313295] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test # [ 9.317526] systemd[1]: Starting Virtual Console Setup... test # [ 9.402411] systemd-logind[599]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) kill QemuMachine (pid 48) test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14) test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down test # [2026-10-03T00:04:57Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.31 seconds) Traceback (most recent call last): File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in sys.exit(main()) ~~~~^^ File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main driver.run_tests() ~~~~~~~~~~~~~~~~^^ File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests self.test_script() ~~~~~~~~~~~~~~~~^^ File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script exec(self.tests, symbols, None) ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "", line 4, in AssertionError: Expected mock password in connection file: [connection] autoconnect=true id= type=wifi [wifi] mode=infrastructure ssid= [wifi-security] key-mgmt=wpa-psk psk=