build01: building b27d3p963xsanli4lfycliz8zdkqp6fj-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: QEMU running (pid 47) second # Disk image does not exist, creating the virtualisation disk image... test # Disk image does not exist, creating the virtualisation disk image... test: QEMU running (pid 48) test # Formatting '/build/vm-state-test/tmp.gcffrqzuLW', fmt=raw size=1073741824 second # Formatting '/build/vm-state-second/tmp.MwAWf58IrQ', fmt=raw size=1073741824 test # mke2fs 1.47.4 (6-Mar-2025) (finished: start all VMs, in 0.41 seconds) test # Discarding device blocks: 0/262144 done test: waiting for unit NetworkManager.service test # Creating filesystem with 262144 4k blocks and 65536 inodes test: waiting for the VM to finish booting test # Filesystem UUID: 401b84c3-d12b-4087-8e20-e606cb9d1b3f second # mke2fs 1.47.4 (6-Mar-2025) second # Discarding device blocks: 0/262144 done test # Superblock backups stored on blocks: second # Creating filesystem with 262144 4k blocks and 65536 inodes test # 32768, 98304, 163840, 229376 second # Filesystem UUID: bdd3a468-9ee5-439f-adf9-a2b8c8670015 test # second # Superblock backups stored on blocks: test # Allocating group tables: 0/8 done second # 32768, 98304, 163840, 229376 test # Writing inode tables: 0/8 done second # test # Creating journal (8192 blocks): done second # Allocating group tables: 0/8 done test # Writing superblocks and filesystem accounting information: 0/8 done second # Writing inode tables: 0/8 done test # second # Creating journal (8192 blocks): done test # Virtualisation disk image created. second # Writing superblocks and filesystem accounting information: 0/8 done test # Starting virtiofs daemons... second # test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # Virtualisation disk image created. test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # Starting virtiofs daemons... test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] test # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 test # [ 0.000000] KASLR enabled test # [ 0.000000] random: crng init done test # [ 0.000000] Machine model: linux,dummy-virt test # [ 0.000000] efi: UEFI not found. test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] test # [ 0.000000] Zone ranges: test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] DMA32 empty test # [ 0.000000] Normal empty second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] test # [ 0.000000] Device empty test # [ 0.000000] Movable zone start for each node test # [ 0.000000] Early memory node ranges second # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1791725172 test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] KASLR enabled second # [ 0.000000] random: crng init done test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] Machine model: linux,dummy-virt test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.000000] efi: UEFI not found. test # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] second # [ 0.000000] Zone ranges: test # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] DMA32 empty test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] Normal empty second # [ 0.000000] Device empty test # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 test # [ 0.000000] Detected PIPT I-cache on CPU0 test # [ 0.000000] CPU features: detected: GICv3 CPU interface test # [ 0.000000] CPU features: detected: Spectre-v4 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 second # [ 0.000000] Movable zone start for each node second # [ 0.000000] Early memory node ranges second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] alternatives: applying boot alternatives 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/azdvna4rsvl2gjx3dfghyhzs5wgiz9xl-nixos-system-test-test/init regInfo=/nix/.ro-store/wr5a7ys7gal7gbhpj2iqy00mp1pnp74l-closure-info/registration console=ttyAMA0,115200n8 console=tty0 second # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296 second # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] CPU features: detected: Spectre-BHB 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] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wr5a7ys7gal7gbhpj2iqy00mp1pnp74l-closure-info/registration", will be passed to user space. test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB test # [ 0.000000] software IO TLB: area num 1. second # [ 0.000000] CPU features: detected: ARM erratum 1418040 second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing second # [ 0.000000] alternatives: applying boot alternatives test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) test # [ 0.000000] Fallback order for Node 0: 0 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/q8s0zh41js3zp5lqqxwmmlfxn7c36mza-nixos-system-second-test/init regInfo=/nix/.ro-store/frwi3j7fvp1jyfj2b977hzsbs9fizhgw-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/frwi3j7fvp1jyfj2b977hzsbs9fizhgw-closure-info/registration", will be passed to user space. test # [ 0.000000] Policy zone: DMA test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.000000] ftrace: allocating 75049 entries in 294 pages 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) 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] ftrace: allocated 294 pages with 4 groups test # [ 0.000000] rcu: Hierarchical RCU implementation. test # [ 0.000000] rcu: RCU event tracing is enabled. test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. test # [ 0.000000] Trampoline variant of Tasks RCU enabled. second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.000000] Policy zone: DMA second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] ftrace: allocating 75049 entries in 294 pages second # [ 0.000000] ftrace: allocated 294 pages with 4 groups second # [ 0.000000] rcu: Hierarchical RCU implementation. test # [ 0.000000] Rude variant of Tasks RCU enabled. test # [ 0.000000] Tracing variant of Tasks RCU enabled. test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 test # [ 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 Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 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] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. second # [ 0.000000] Trampoline variant of Tasks RCU enabled. second # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] Root IRQ handler: gic_handle_irq test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 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] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 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] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 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] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.000000] Root IRQ handler: gic_handle_irq test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) test # [ 0.000046] arm-pv: using stolen time PV second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) test # [ 0.000330] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 test # [ 0.000611] Console: colour dummy device 80x25 test # [ 0.000622] printk: legacy console [tty0] enabled second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. test # [ 0.000830] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.000835] pid_max: default: 32768 minimum: 301 second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns test # [ 0.000934] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 test # [ 0.001060] landlock: Up and running. test # [ 0.001062] Yama: becoming mindful. second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). test # [ 0.001395] LSM support for eBPF active second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns test # [ 0.001548] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.001568] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns second # [ 0.000050] arm-pv: using stolen time PV test # [ 0.004276] rcu: Hierarchical SRCU implementation. test # [ 0.004282] rcu: Max phase no-delay instances is 1000. second # [ 0.000379] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) test # [ 0.005259] fsl-mc MSI: its@8080000 domain created second # [ 0.000659] Console: colour dummy device 80x25 test # [ 0.005444] EFI services will not be available. second # [ 0.000671] printk: legacy console [tty0] enabled test # [ 0.005540] smp: Bringing up secondary CPUs ... test # [ 0.005571] smp: Brought up 1 node, 1 CPU second # [ 0.000881] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.005574] SMP: Total of 1 processors activated. second # [ 0.000886] pid_max: default: 32768 minimum: 301 test # [ 0.005576] CPU: All CPU(s) started at EL1 second # [ 0.000961] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.005588] CPU features: detected: 32-bit EL0 Support second # [ 0.001107] landlock: Up and running. second # [ 0.001110] Yama: becoming mindful. test # [ 0.005592] CPU features: detected: Data cache clean to the PoU not required for I/D coherence second # [ 0.001561] LSM support for eBPF active test # [ 0.005595] CPU features: detected: Common not Private translations second # [ 0.001751] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.005597] CPU features: detected: CRC32 instructions second # [ 0.001771] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.005599] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.004489] rcu: Hierarchical SRCU implementation. test # [ 0.005602] CPU features: detected: Enhanced Virtualization Traps second # [ 0.004496] rcu: Max phase no-delay instances is 1000. test # [ 0.005613] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.005476] fsl-mc MSI: its@8080000 domain created test # [ 0.005615] CPU features: detected: LSE atomic instructions second # [ 0.005665] EFI services will not be available. second # [ 0.005763] smp: Bringing up secondary CPUs ... test # [ 0.005617] CPU features: detected: Privileged Access Never second # [ 0.005797] smp: Brought up 1 node, 1 CPU test # [ 0.005619] CPU features: detected: PMUv3 second # [ 0.005799] SMP: Total of 1 processors activated. test # [ 0.005621] CPU features: detected: RAS Extension Support second # [ 0.005801] CPU: All CPU(s) started at EL1 test # [ 0.005624] CPU features: detected: Speculative Store Bypassing Safe (SSBS) second # [ 0.005813] CPU features: detected: 32-bit EL0 Support test # [ 0.005646] alternatives: applying system-wide alternatives second # [ 0.005817] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.007048] CPU features: detected: Hardware dirty bit management on CPU0 second # [ 0.005820] CPU features: detected: Common not Private translations second # [ 0.005822] CPU features: detected: CRC32 instructions test # [ 0.007288] Memory: 891192K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116084K reserved, 32768K cma-reserved) second # [ 0.005824] CPU features: detected: Data cache clean to Point of Persistence test # [ 0.007781] devtmpfs: initialized second # [ 0.005827] CPU features: detected: Enhanced Virtualization Traps test # [ 0.009439] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.005837] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.005840] CPU features: detected: LSE atomic instructions test # [ 0.009462] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). second # [ 0.005842] CPU features: detected: Privileged Access Never test # [ 0.009590] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.005844] CPU features: detected: PMUv3 test # [ 0.009595] 0 pages in range for non-PLT usage second # [ 0.005845] CPU features: detected: RAS Extension Support test # [ 0.009596] 508192 pages in range for PLT usage second # [ 0.005848] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.009711] pinctrl core: initialized pinctrl subsystem test # [ 0.010490] DMI not present or invalid. second # [ 0.005868] alternatives: applying system-wide alternatives test # [ 0.013954] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.007333] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.016476] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.016607] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations second # [ 0.007567] Memory: 890952K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116088K reserved, 32768K cma-reserved) second # [ 0.008035] devtmpfs: initialized test # [ 0.016753] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations second # [ 0.009561] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.016773] audit: initializing netlink subsys (disabled) test # [ 0.017380] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.009583] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.017383] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.009698] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.009703] 0 pages in range for non-PLT usage test # [ 0.017386] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.009704] 508192 pages in range for PLT usage test # [ 0.017388] thermal_sys: Registered thermal governor 'user_space' second # [ 0.009811] pinctrl core: initialized pinctrl subsystem test # [ 0.017390] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.010569] DMI not present or invalid. second # [ 0.013895] NET: Registered PF_NETLINK/PF_ROUTE protocol family test # [ 0.017425] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 test # [ 0.017434] cpuidle: using governor ladder second # [ 0.016613] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.017438] cpuidle: using governor menu second # [ 0.016747] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.017556] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.016897] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.017578] ASID allocator initialised with 32768 entries test # [ 0.018227] Serial: AMBA PL011 UART driver second # [ 0.016917] audit: initializing netlink subsys (disabled) second # [ 0.017506] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.021587] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.017508] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.021729] printk: console [ttyAMA0] enabled second # [ 0.017511] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.017513] thermal_sys: Registered thermal governor 'user_space' second # [ 0.017515] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.017548] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.017557] cpuidle: using governor ladder second # [ 0.017561] cpuidle: using governor menu second # [ 0.017688] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.017708] ASID allocator initialised with 32768 entries second # [ 0.018391] Serial: AMBA PL011 UART driver second # [ 0.021886] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.022058] printk: console [ttyAMA0] enabled test # [ 0.093596] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.093612] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.093615] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.093617] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.093619] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.093621] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.093623] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.093625] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.094864] fbcon: Taking over console test # [ 0.094875] ACPI: Interpreter disabled. second # [ 0.094003] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.095523] iommu: Default domain type: Translated second # [ 0.094019] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.095527] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.094021] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.095802] SCSI subsystem initialized second # [ 0.094023] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.097403] usbcore: registered new interface driver usbfs test # [ 0.097441] usbcore: registered new interface driver hub second # [ 0.094025] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.097448] usbcore: registered new device driver usb second # [ 0.094027] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.097668] pps_core: LinuxPPS API ver. 1 registered second # [ 0.094029] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.097672] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.094031] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page second # [ 0.095122] fbcon: Taking over console test # [ 0.097676] PTP clock support registered test # [ 0.097708] EDAC MC: Ver: 3.0.0 second # [ 0.095133] ACPI: Interpreter disabled. second # [ 0.095785] iommu: Default domain type: Translated second # [ 0.095789] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.096106] SCSI subsystem initialized second # [ 0.096319] usbcore: registered new interface driver usbfs second # [ 0.096348] usbcore: registered new interface driver hub second # [ 0.096362] usbcore: registered new device driver usb second # [ 0.096569] pps_core: LinuxPPS API ver. 1 registered second # [ 0.096572] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.096576] PTP clock support registered second # [ 0.096609] EDAC MC: Ver: 3.0.0 test # [ 0.106261] scmi_core: SCMI protocol bus registered test # [ 0.106638] FPGA manager framework test # [ 0.110281] vgaarb: loaded test # [ 0.110512] clocksource: Switched to clocksource arch_sys_counter second # [ 0.105840] scmi_core: SCMI protocol bus registered second # [ 0.106228] FPGA manager framework second # [ 0.109769] vgaarb: loaded second # [ 0.110835] clocksource: Switched to clocksource arch_sys_counter test # [ 0.120569] VFS: Disk quotas dquot_6.6.0 test # [ 0.120597] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.122083] netfs: FS-Cache loaded test # [ 0.122202] pnp: PnP ACPI: disabled test # [ 0.125483] NET: Registered PF_INET protocol family second # [ 0.120771] VFS: Disk quotas dquot_6.6.0 test # [ 0.125679] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) second # [ 0.120800] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.122326] netfs: FS-Cache loaded second # [ 0.122449] pnp: PnP ACPI: disabled second # [ 0.125764] NET: Registered PF_INET protocol family second # [ 0.125957] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.150340] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.150362] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) test # [ 0.150375] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.150410] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.150487] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.151535] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.151559] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.151573] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.151609] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.151676] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.151759] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.153518] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.151784] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.153586] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151847] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.153609] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151952] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.153737] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.151984] NET: Registered PF_XDP protocol family second # [ 0.151999] PCI: CLS 0 bytes, default 64 test # [ 0.153766] NET: Registered PF_XDP protocol family test # [ 0.153781] PCI: CLS 0 bytes, default 64 second # [ 0.152315] Trying to unpack rootfs image as initramfs... test # [ 0.154017] Trying to unpack rootfs image as initramfs... second # [ 0.161001] kvm [1]: HYP mode not available test # [ 0.165534] kvm [1]: HYP mode not available test # [ 0.231118] Initialise system trusted keyrings test # [ 0.231236] workingset: timestamp_bits=42 max_order=18 bucket_order=0 test # [ 0.231549] squashfs: version 4.0 (2009/01/31) Phillip Lougher test # [ 0.231585] 9p: Installing v9fs 9p2000 file system support second # [ 0.235212] Initialise system trusted keyrings second # [ 0.235329] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.235659] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.235695] 9p: Installing v9fs 9p2000 file system support test # [ 0.252137] Key type asymmetric registered test # [ 0.252155] Asymmetric key parser 'x509' registered test # [ 0.252214] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.252313] io scheduler mq-deadline registered test # [ 0.252317] io scheduler kyber registered second # [ 0.256186] Key type asymmetric registered second # [ 0.256204] Asymmetric key parser 'x509' registered second # [ 0.256259] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.256367] io scheduler mq-deadline registered second # [ 0.256370] io scheduler kyber registered test # [ 0.262653] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.263025] ledtrig-cpu: registered to indicate activity on CPUs test # [ 0.263359] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: test # [ 0.263375] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 test # [ 0.263389] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 test # [ 0.263393] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.263425] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.263447] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.263530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 test # [ 0.263546] pci_bus 0000:00: root bus resource [bus 00-ff] test # [ 0.263549] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] test # [ 0.263552] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] test # [ 0.263554] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] test # [ 0.263632] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint test # [ 0.264080] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.264207] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.266975] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.264216] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.267356] ledtrig-cpu: registered to indicate activity on CPUs test # [ 0.264234] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.267691] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: test # [ 0.264246] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.267708] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 test # [ 0.264554] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.267721] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 test # [ 0.264676] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] test # [ 0.264685] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.267725] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.264704] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.267752] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.265013] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.265133] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] second # [ 0.267780] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.265142] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.267865] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 test # [ 0.265161] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.267881] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.267884] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] test # [ 0.265458] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.265578] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] second # [ 0.267887] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] test # [ 0.265587] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.267889] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] test # [ 0.265605] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.267966] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint test # [ 0.265613] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.268423] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.265914] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.268548] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] test # [ 0.266033] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.268557] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.266050] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.268576] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.266350] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.268588] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.266482] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.268901] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.266499] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.269026] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.269035] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.269054] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.269368] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint second # [ 0.269490] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] second # [ 0.269499] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.269517] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.269821] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.269943] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] second # [ 0.269952] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.269970] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.269979] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.270303] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.270428] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.270446] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.270775] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.290906] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.289561] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.291036] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.289585] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.291218] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.289931] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint test # [ 0.291343] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.290053] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] test # [ 0.291361] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.290245] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.291672] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.290368] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.291796] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.290387] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.291814] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.290709] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.292116] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.292246] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.292266] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.292573] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.292821] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] test # [ 0.292831] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.292850] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.293160] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.293282] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.294968] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.293290] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.295002] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.293309] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.295334] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.293722] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned second # [ 0.295458] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.293729] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned second # [ 0.295476] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.293732] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned second # [ 0.295781] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.293765] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned second # [ 0.295941] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.295951] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.293796] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned second # [ 0.295969] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.293827] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned second # [ 0.296345] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.293859] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned second # [ 0.296468] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.293890] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned second # [ 0.296477] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.293925] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned second # [ 0.296495] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.296932] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.293956] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned second # [ 0.296938] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.293986] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned second # [ 0.296942] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.294016] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned second # [ 0.296976] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.294089] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.294119] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned second # [ 0.297007] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.294131] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned second # [ 0.297038] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.294143] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned second # [ 0.297071] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.294155] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned second # [ 0.297102] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.294167] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.297136] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.294180] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.297167] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.294193] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned test # [ 0.294206] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.297198] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.294221] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.297228] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.294234] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned second # [ 0.297298] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.294245] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.297328] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.294257] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.297341] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.294269] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.297353] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.294281] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.297366] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.294294] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.297377] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned test # [ 0.294306] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned test # [ 0.294318] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.297389] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned test # [ 0.294329] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.297402] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned test # [ 0.294346] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.297415] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned test # [ 0.294351] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.297430] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned test # [ 0.294353] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.297443] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned second # [ 0.297455] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.297467] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.297478] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.297490] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.297502] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.297514] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned second # [ 0.297526] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.297538] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.297555] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.297559] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.297562] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.298092] pci 0000:00:07.0: enabling device (0000 -> 0002) test # [ 0.327007] pci 0000:00:07.0: enabling device (0000 -> 0002) test # [ 0.341724] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.336720] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.338296] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.343861] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.345248] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.347580] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.348977] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) second # [ 0.348503] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) second # [ 0.350253] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.358657] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) test # [ 0.360206] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.361772] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) test # [ 0.364807] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.360537] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) test # [ 0.366102] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.362245] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) second # [ 0.364795] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) second # [ 0.366451] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) test # [ 0.375771] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) second # [ 0.376098] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.377779] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.380757] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.387310] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled test # [ 0.388302] msm_serial: driver initialized test # [ 0.388379] SuperH (H)SCI(F) driver initialized test # [ 0.388417] STM32 USART driver initialized second # [ 0.392451] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.393531] msm_serial: driver initialized second # [ 0.393604] SuperH (H)SCI(F) driver initialized second # [ 0.393644] STM32 USART driver initialized test # [ 0.411684] loop: module loaded test # [ 0.411829] virtio_blk virtio2: 1/0/0 default/read/poll queues test # [ 0.412478] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) test # [ 0.416230] megasas: 07.734.00.00-rc1 test # [ 0.416709] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] second # [ 0.419027] loop: module loaded second # [ 0.419165] virtio_blk virtio2: 1/0/0 default/read/poll queues second # [ 0.419793] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.424581] megasas: 07.734.00.00-rc1 test # [ 0.427543] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.427578] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.425069] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.429255] Using buffer write method test # [ 0.429320] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.433983] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.433998] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.428855] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.428887] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.430576] Using buffer write method second # [ 0.430637] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.436808] Using buffer write method test # [ 0.436821] Concatenating MTD devices: test # [ 0.436823] (0): "0.flash" test # [ 0.436825] (1): "0.flash" test # [ 0.436826] into device "0.flash" second # [ 0.441285] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.441308] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.450861] Using buffer write method second # [ 0.450879] Concatenating MTD devices: second # [ 0.450881] (0): "0.flash" second # [ 0.450883] (1): "0.flash" second # [ 0.450884] into device "0.flash" test # [ 0.668484] Freeing initrd memory: 26900K test # [ 0.675375] tun: Universal TUN/TAP device driver, 1.6 test # [ 0.677586] thunder_xcv, ver 1.0 test # [ 0.677601] thunder_bgx, ver 1.0 test # [ 0.677609] nicpf, ver 1.0 test # [ 0.677930] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.677934] e1000: Copyright (c) 1999-2006 Intel Corporation. test # [ 0.677948] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.677954] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.677965] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.677967] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.677977] igbvf: Intel(R) Gigabit Virtual Function Network Driver test # [ 0.677979] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.678045] sky2: driver version 1.30 test # [ 0.683851] usbcore: registered new interface driver usb-storage second # [ 0.681276] Freeing initrd memory: 26904K test # [ 0.683941] usbcore: registered new interface driver usbserial_generic test # [ 0.683961] usbserial: USB Serial support registered for generic test # [ 0.684259] hv_vmbus: registering driver hyperv_keyboard test # [ 0.685340] ehci-pci 0000:00:07.0: EHCI Host Controller test # [ 0.685365] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 test # [ 0.685644] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 test # [ 0.685818] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.685888] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:14:26 UTC (1792077266) test # [ 0.686079] i2c_dev: i2c /dev entries driver second # [ 0.688471] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.690689] thunder_xcv, ver 1.0 second # [ 0.690705] thunder_bgx, ver 1.0 second # [ 0.690714] nicpf, ver 1.0 second # [ 0.692238] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.692244] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.692257] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.692262] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.692275] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.692277] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.692286] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.692289] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.692355] sky2: driver version 1.30 second # [ 0.693203] usbcore: registered new interface driver usb-storage second # [ 0.693293] usbcore: registered new interface driver usbserial_generic second # [ 0.693311] usbserial: USB Serial support registered for generic second # [ 0.693624] hv_vmbus: registering driver hyperv_keyboard second # [ 0.694675] ehci-pci 0000:00:07.0: EHCI Host Controller second # [ 0.694727] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 test # [ 0.692389] sdhci: Secure Digital Host Controller Interface driver test # [ 0.692394] sdhci: Copyright(c) Pierre Ossman second # [ 0.701421] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 test # [ 0.692541] Synopsys Designware Multimedia Card Interface Driver second # [ 0.701623] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.692717] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.693583] hid: raw HID events driver (C) Jiri Kosina second # [ 0.701657] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:14:26 UTC (1792077266) test # [ 0.693715] usbcore: registered new interface driver usbhid second # [ 0.701863] i2c_dev: i2c /dev entries driver test # [ 0.693718] usbhid: USB HID core driver second # [ 0.703591] sdhci: Secure Digital Host Controller Interface driver second # [ 0.703597] sdhci: Copyright(c) Pierre Ossman second # [ 0.703750] Synopsys Designware Multimedia Card Interface Driver second # [ 0.703933] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.737403] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 second # [ 0.704827] hid: raw HID events driver (C) Jiri Kosina test # [ 0.737837] hub 1-0:1.0: USB hub found test # [ 0.737877] hub 1-0:1.0: 6 ports detected second # [ 0.704963] usbcore: registered new interface driver usbhid second # [ 0.704966] usbhid: USB HID core driver test # [ 0.738164] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.730365] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 second # [ 0.730846] hub 1-0:1.0: USB hub found second # [ 0.730876] hub 1-0:1.0: 6 ports detected test # [ 0.740804] drop_monitor: Initializing network drop monitor service test # [ 0.740925] NET: Registered PF_INET6 protocol family test # [ 0.741572] Segment Routing with IPv6 test # [ 0.741583] In-situ OAM (IOAM) with IPv6 test # [ 0.741631] NET: Registered PF_PACKET protocol family test # [ 0.741692] 9pnet: Installing 9P2000 support test # [ 0.741725] Key type dns_resolver registered second # [ 0.736796] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.737779] drop_monitor: Initializing network drop monitor service second # [ 0.737900] NET: Registered PF_INET6 protocol family second # [ 0.738585] Segment Routing with IPv6 second # [ 0.738595] In-situ OAM (IOAM) with IPv6 second # [ 0.738663] NET: Registered PF_PACKET protocol family second # [ 0.738722] 9pnet: Installing 9P2000 support second # [ 0.738755] Key type dns_resolver registered test # [ 0.747221] registered taskstats version 1 test # [ 0.747374] Loading compiled-in X.509 certificates second # [ 0.745236] registered taskstats version 1 second # [ 0.745406] Loading compiled-in X.509 certificates test # [ 0.752735] Demotion targets for Node 0: null test # [ 0.752815] Key type .fscrypt registered test # [ 0.752818] Key type fscrypt-provisioning registered test # [ 0.752935] ima: No TPM chip found, activating TPM-bypass! test # [ 0.752955] ima: Allocated hash algorithm: sha1 test # [ 0.753024] ima: No architecture policies found test # [ 0.753589] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.751282] Demotion targets for Node 0: null second # [ 0.751393] Key type .fscrypt registered second # [ 0.751396] Key type fscrypt-provisioning registered second # [ 0.751507] ima: No TPM chip found, activating TPM-bypass! second # [ 0.751517] ima: Allocated hash algorithm: sha1 second # [ 0.751590] ima: No architecture policies found second # [ 0.752183] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.766567] clk: Disabling unused clocks test # [ 0.766579] PM: genpd: Disabling unused power domains second # [ 0.765360] clk: Disabling unused clocks test # [ 0.768873] Freeing unused kernel memory: 4736K second # [ 0.765373] PM: genpd: Disabling unused power domains test # [ 0.768945] Run /init as init process second # [ 0.768643] Freeing unused kernel memory: 4736K second # [ 0.768716] Run /init as init process test # [ 0.789129] systemd[1]: Successfully made /usr/ read-only. second # [ 0.789528] systemd[1]: Successfully made /usr/ read-only. second # [ 0.978896] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 0.986562] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 1.123237] 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 # [ 1.123267] systemd[1]: Detected virtualization qemu. test # [ 1.123308] systemd[1]: Detected architecture arm64. test # [ 1.123317] systemd[1]: Running in initrd. second # [ 1.123822] 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 # [ 1.124109] systemd[1]: Initializing machine ID from random generator. second # [ 1.123853] systemd[1]: Detected virtualization qemu. test # [ 1.124386] systemd[1]: Hostname set to . second # [ 1.123897] systemd[1]: Detected architecture arm64. second # [ 1.124053] systemd[1]: Running in initrd. second # [ 1.125457] systemd[1]: Initializing machine ID from random generator. second # [ 1.125791] systemd[1]: Hostname set to . test # [ 1.146290] 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.145592] 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.230812] 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.231128] 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.270083] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 1.275780] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 1.329897] systemd[1]: Queued start job for default target Initrd Default Target. test # [ 1.338148] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.336526] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.336697] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.336732] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.336754] systemd[1]: Reached target Path Units. second # [ 1.336766] systemd[1]: Reached target Slice Units. second # [ 1.336778] systemd[1]: Reached target Swaps. second # [ 1.336796] systemd[1]: Reached target Timer Units. test # [ 1.344133] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.336942] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.337068] systemd[1]: Listening on Journal Socket (/dev/log). test # [ 1.344308] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.337198] systemd[1]: Listening on Journal Sockets. test # [ 1.344343] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.337271] systemd[1]: Listening on udev Control Socket. test # [ 1.344369] systemd[1]: Reached target Path Units. test # [ 1.344383] systemd[1]: Reached target Slice Units. second # [ 1.337317] systemd[1]: Listening on udev Kernel Socket. test # [ 1.344396] systemd[1]: Reached target Swaps. second # [ 1.337332] systemd[1]: Reached target Socket Units. test # [ 1.344416] systemd[1]: Reached target Timer Units. second # [ 1.338556] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.344564] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 1.344687] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.338613] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.344771] systemd[1]: Listening on Journal Sockets. test # [ 1.344836] systemd[1]: Listening on udev Control Socket. test # [ 1.344927] systemd[1]: Listening on udev Kernel Socket. test # [ 1.344944] systemd[1]: Reached target Socket Units. test # [ 1.346199] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.346254] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.350745] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.350906] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.354068] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.359629] systemd[1]: Mounting Kernel Configuration File System... second # [ 1.360300] systemd[1]: Starting Journal Service... test # [ 1.366776] systemd[1]: Starting Journal Service... test # [ 1.375036] systemd[1]: Starting Load Kernel Modules... test # [ 1.375136] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 1.377842] systemd[1]: Starting Load Kernel Modules... second # [ 1.377937] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 1.380331] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.400109] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.403387] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.399168] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.415349] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.415806] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.434609] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.440321] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.442815] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.442473] systemd-journald[71]: Collecting audit messages is disabled. second # [ 1.449872] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev test # [ 1.455989] systemd-journald[71]: Collecting audit messages is disabled. test # [ 1.460000] systemd[1]: Mounted Kernel Configuration File System. test # [ 1.460179] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.457062] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 second # [ 1.457309] [drm] features: -virgl +edid -resource_blob -host_visible second # [ 1.457311] [drm] features: -context_init second # [ 1.457807] [drm] number of scanouts: 1 second # [ 1.457816] [drm] number of cap sets: 0 test # [ 1.467111] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.464969] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.468418] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.477474] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 test # [ 1.477827] [drm] features: -virgl +edid -resource_blob -host_visible second # [ 1.467784] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.477830] [drm] features: -context_init test # [ 1.478312] [drm] number of scanouts: 1 test # [ 1.478321] [drm] number of cap sets: 0 second # [ 1.479269] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic second # [ 1.479281] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 test # [ 1.500911] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic test # [ 1.500924] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 second # [ 1.499641] Console: switching to colour frame buffer device 160x50 test # [ 1.509757] 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.509863] 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.518963] Console: switching to colour frame buffer device 160x50 second # [ 1.523630] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device second # [ 1.525138] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.525322] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.525356] systemd[1]: Reached target Local File Systems. second # [ 1.527054] 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.527304] 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.538733] systemd[1]: Started Journal Service. second # [ 1.535864] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.544968] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device second # [ 1.539355] systemd-modules-load[72]: Inserted module 'dm_mod' second # [ 1.543162] systemd-modules-load[72]: Module 'virtio_balloon' is built in test # [ 1.539015] systemd-modules-load[72]: Inserted module 'dm_mod' second # [ 1.547121] systemd[1]: Finished Load Kernel Modules. second # [ 1.547429] systemd[1]: Started Journal Service. test # [ 1.548939] systemd-modules-load[72]: Module 'virtio_balloon' is built in test # [ 1.549999] systemd-modules-load[72]: Module 'virtio_console' is built in test # [ 1.550710] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.551393] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.543977] systemd-modules-load[72]: Module 'virtio_console' is built in second # [ 1.552783] systemd-modules-load[72]: Inserted module 'virtio_gpu' second # [ 1.553664] systemd-modules-load[72]: Module 'virtio_rng' is built in second # [ 1.554341] systemd[1]: Starting Apply Kernel Variables... test # [ 1.560313] systemd[1]: Reached target Local File Systems. test # [ 1.561024] systemd[1]: Starting Create System Files and Directories... test # [ 1.561661] systemd-modules-load[72]: Inserted module 'virtio_gpu' test # [ 1.562225] systemd-modules-load[72]: Module 'virtio_rng' is built in test # [ 1.564144] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.569010] systemd[1]: Finished Load Kernel Modules. second # [ 1.573939] systemd[1]: Starting Create System Files and Directories... test # [ 1.584576] systemd[1]: Starting Apply Kernel Variables... test # [ 1.612968] systemd[1]: Finished Create System Files and Directories. second # [ 1.611935] systemd[1]: Finished Apply Kernel Variables. second # [ 1.613161] systemd[1]: Finished Create System Files and Directories. test # [ 1.625887] systemd[1]: Finished Apply Kernel Variables. second # [ 1.633298] systemd-udevd[79]: Using default interface naming scheme 'v261'. test # [ 1.645458] systemd-udevd[79]: Using default interface naming scheme 'v261'. second # [ 1.658097] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 1.668946] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.716990] systemd[1]: Starting Virtual Console Setup... test # [ 1.731706] systemd[1]: Starting Virtual Console Setup... second # [ 1.777180] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.779216] systemd[1]: Finished Virtual Console Setup. test # [ 1.784577] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.787180] systemd[1]: Finished Virtual Console Setup. test # [ 1.992563] systemd[1]: Finished Coldplug All udev Devices. second # [ 1.990041] systemd[1]: Finished Coldplug All udev Devices. second # [ 1.990649] systemd[1]: Reached target System Initialization. second # [ 1.991155] systemd[1]: Reached target Basic System. test # [ 1.996066] systemd[1]: Reached target System Initialization. test # [ 1.996977] systemd[1]: Reached target Basic System. test # [ 2.098678] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.102897] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.127608] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.125732] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.200480] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.202718] systemd[1]: Reached target Initrd Root Device. test # [ 2.208409] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.205096] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.210711] systemd[1]: Reached target Initrd Root Device. test # [ 2.212425] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.243245] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.251222] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.271208] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.275709] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. second # [ 2.382859] systemd[1]: Mounting /sysroot... test # [ 2.396529] systemd[1]: Mounting /sysroot... second # [ 2.417144] EXT4-fs (vda): mounted filesystem bdd3a468-9ee5-439f-adf9-a2b8c8670015 r/w with ordered data mode. Quota mode: none. second # [ 2.414733] systemd[1]: Mounted /sysroot. second # [ 2.416533] systemd[1]: Reached target Initrd Root File System. test # [ 2.428805] EXT4-fs (vda): mounted filesystem 401b84c3-d12b-4087-8e20-e606cb9d1b3f r/w with ordered data mode. Quota mode: none. second # [ 2.419851] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.425166] systemd[1]: Mounted /sysroot. test # [ 2.426095] systemd[1]: Reached target Initrd Root File System. test # [ 2.429932] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 2.433965] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.441873] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 2.438629] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... second # [ 2.440087] systemd[1]: Reloading... test # [ 2.447694] systemd[1]: Mounting /sysroot/run... test # [ 2.484701] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 2.498968] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 2.502798] fuse: init (API version 7.45) test # [ 2.513785] virtiofs virtio6: discovered new tag: nix-store test # [ 2.514277] virtiofs virtio6: virtio_fs_setup_dax: No cache capability test # [ 2.512109] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.528111] virtiofs virtio7: discovered new tag: shared test # [ 2.528632] virtiofs virtio7: virtio_fs_setup_dax: No cache capability test # [ 2.526510] systemd[1]: Mounted /sysroot/nix/.rw-store. test # [ 2.534462] virtiofs virtio8: discovered new tag: xchg test # [ 2.537259] virtiofs virtio8: virtio_fs_setup_dax: No cache capability test # [ 2.561710] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 2.563232] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 2.564667] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.570323] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 2.575977] systemd[1]: Mounted /sysroot/run. test # [ 2.583336] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 2.592090] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.595226] systemd[1]: Reloading... second # [ 2.625112] systemd[1]: Reloading finished in 183 ms. second # [ 2.653429] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... second # [ 2.655832] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... second # [ 2.660888] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.662854] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.663796] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.779600] systemd[1]: Reloading finished in 185 ms. test # [ 2.809253] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace... test # [ 2.810856] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 2.812830] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 2.815295] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace... test # [ 2.819888] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 2.824567] systemd[1]: initrd-parse-etc.service: Deactivated successfully. test # [ 2.827640] systemd[1]: Finished Mountpoints Configured in the Real Root. test # [ 2.828583] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.847217] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 2.848274] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 2.992735] fuse: init (API version 7.45) second # [ 3.000870] virtiofs virtio6: discovered new tag: nix-store second # [ 3.001365] virtiofs virtio6: virtio_fs_setup_dax: No cache capability second # [ 3.011045] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory second # [ 3.015174] (udev-worker)[91]: 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 # [ 3.034009] virtiofs virtio7: discovered new tag: shared second # [ 3.034528] virtiofs virtio7: virtio_fs_setup_dax: No cache capability second # [ 3.041455] virtiofs virtio8: discovered new tag: xchg second # [ 3.041954] virtiofs virtio8: virtio_fs_setup_dax: No cache capability second # [ 3.040143] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.040832] systemd[1]: Stopped Virtual Console Setup. second # [ 3.041833] systemd[1]: Stopping Virtual Console Setup... second # [ 3.044489] systemd[1]: Starting Virtual Console Setup... second # [ 3.061429] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.062136] systemd[1]: Stopped Virtual Console Setup. second # [ 3.064130] systemd[1]: Starting Virtual Console Setup... second # [ 3.077570] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 3.079170] systemd[1]: Finished Virtual Console Setup. test # [ 3.117225] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory test # [ 3.121993] (udev-worker)[99]: 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 test # [ 3.134597] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.136075] systemd[1]: Stopped Virtual Console Setup. test # [ 3.137306] systemd[1]: Stopping Virtual Console Setup... test # [ 3.139324] systemd[1]: Starting Virtual Console Setup... test # [ 3.167112] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 3.169596] systemd[1]: Finished Virtual Console Setup. second # [ 3.386756] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 3.397534] systemd[1]: Mounting /sysroot/nix/store... second # [ 3.396347] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 3.402963] systemd[1]: Mounting /sysroot/run... second # [ 3.417764] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.447300] systemd[1]: Mounted /sysroot/nix/store. second # [ 3.444300] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.449669] systemd[1]: Reached target Initrd File Systems. test # [ 3.454592] systemd[1]: Starting Find NixOS closure... second # [ 3.453778] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.464570] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 3.465224] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.471222] systemd[1]: Mounted /sysroot/run. second # [ 3.484251] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.486388] systemd[1]: Mounted /sysroot/tmp/shared. second # [ 3.491124] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.502934] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.509184] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. second # [ 3.506593] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.507693] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.521037] systemd[1]: Finished Find NixOS closure. test # [ 3.523115] systemd[1]: Reached target Initrd Default Target. test # [ 3.524411] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 3.555716] systemd[1]: initrd-cleanup.service: Deactivated successfully. test # [ 3.560208] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.562103] systemd[1]: Stopped target Initrd Default Target. test # [ 3.564197] systemd[1]: Stopped target Basic System. test # [ 3.566426] systemd[1]: Stopped target Initrd Root Device. test # [ 3.568487] systemd[1]: Stopped target Path Units. test # [ 3.572359] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.573445] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.574552] systemd[1]: Stopped target Slice Units. test # [ 3.576866] systemd[1]: Stopped target Socket Units. test # [ 3.578941] systemd[1]: Stopped target System Initialization. test # [ 3.582817] systemd[1]: Stopped target Swaps. test # [ 3.583553] systemd[1]: Stopped target Timer Units. test # [ 3.586075] systemd[1]: dbus.socket: Deactivated successfully. test # [ 3.592650] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.593553] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 3.597925] systemd[1]: Stopped Find NixOS closure. test # [ 3.599144] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.601415] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 3.605419] systemd[1]: Stopped Apply Kernel Variables. test # [ 3.606068] systemd[1]: systemd-modules-load.service: Deactivated successfully. test # [ 3.607531] systemd[1]: Stopped Load Kernel Modules. test # [ 3.612418] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 3.613224] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 3.614040] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. test # [ 3.614746] systemd[1]: Stopped Create System Files and Directories. test # [ 3.615347] systemd[1]: Stopped target Local File Systems. test # [ 3.615883] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 3.616841] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.617517] systemd[1]: Stopped Coldplug All udev Devices. test # [ 3.618038] systemd[1]: Stopping Rule-based Manager for Device Events and Files... test # [ 3.618708] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.619381] systemd[1]: Stopped Virtual Console Setup. test # [ 3.619873] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.623816] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.628171] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.629011] systemd[1]: Stopped Rule-based Manager for Device Events and Files. test # [ 3.629660] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 3.630300] systemd[1]: Closed udev Control Socket. test # [ 3.630756] systemd[1]: Starting Cleanup udev Database... test # [ 3.631249] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 3.631930] systemd[1]: Stopped Create Static Device Nodes in /dev. test # [ 3.636369] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. test # [ 3.637097] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 3.637722] systemd[1]: kmod-static-nodes.service: Deactivated successfully. test # [ 3.638318] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 3.647346] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.651958] systemd[1]: Finished Cleanup udev Database. test # [ 3.653536] systemd[1]: Reached target Switch Root. test # [ 3.653974] systemd[1]: Starting NixOS Activation... test # [ 3.712233] initrd-nixos-activation-start[177]: booting system configuration /nix/store/azdvna4rsvl2gjx3dfghyhzs5wgiz9xl-nixos-system-test-test test # [ 3.737039] initrd-nixos-activation-start[177]: running activation script... test # [ 3.967839] initrd-nixos-activation-start[200]: setting up /etc... test # [ 4.062257] initrd-nixos-activation-start[200]: setting up age secrets... test # [ 4.180059] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 4.180763] systemd[1]: Finished NixOS Activation. test # [ 4.181671] systemd[1]: Starting Switch Root... test # [ 4.201255] systemd[1]: Switching root. test # [ 4.366040] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). second # [ 4.383131] systemd[1]: Mounting /sysroot/nix/store... second # [ 4.414575] systemd[1]: Mounted /sysroot/nix/store. second # [ 4.420229] systemd[1]: Reached target Initrd File Systems. second # [ 4.422610] systemd[1]: Starting Find NixOS closure... second # [ 4.431974] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 4.462164] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 4.463534] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. second # [ 4.473982] systemd[1]: Finished Find NixOS closure. second # [ 4.475106] systemd[1]: Reached target Initrd Default Target. second # [ 4.476982] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 4.501259] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 4.502249] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. second # [ 4.505046] systemd[1]: Stopped target Initrd Default Target. second # [ 4.505711] systemd[1]: Stopped target Basic System. second # [ 4.507802] systemd[1]: Stopped target Initrd Root Device. second # [ 4.508459] systemd[1]: Stopped target Path Units. second # [ 4.508893] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 4.510060] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. second # [ 4.513745] systemd[1]: Stopped target Slice Units. second # [ 4.514917] systemd[1]: Stopped target Socket Units. second # [ 4.515998] systemd[1]: Stopped target System Initialization. second # [ 4.520885] systemd[1]: Stopped target Swaps. second # [ 4.521302] systemd[1]: Stopped target Timer Units. second # [ 4.521744] systemd[1]: dbus.socket: Deactivated successfully. second # [ 4.522247] systemd[1]: Closed D-Bus System Message Bus Socket. second # [ 4.522755] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 4.523407] systemd[1]: Stopped Find NixOS closure. second # [ 4.523840] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 4.526617] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 4.527239] systemd[1]: Stopped Apply Kernel Variables. second # [ 4.527734] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 4.528515] systemd[1]: Stopped Load Kernel Modules. second # [ 4.528977] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 4.529659] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. second # [ 4.530337] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 4.530964] systemd[1]: Stopped Create System Files and Directories. second # [ 4.531531] systemd[1]: Stopped target Local File Systems. second # [ 4.544255] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 4.545741] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. second # [ 4.546599] systemd[1]: Stopped Coldplug All udev Devices. second # [ 4.547131] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 4.547821] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 4.549332] systemd[1]: Stopped Virtual Console Setup. second # [ 4.549790] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 4.550327] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 4.550908] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 4.551514] systemd[1]: Closed udev Control Socket. second # [ 4.551944] systemd[1]: Starting Cleanup udev Database... second # [ 4.557956] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 4.560177] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 4.564226] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 4.565017] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. second # [ 4.565622] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 4.566208] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 4.566755] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 4.568261] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 4.581678] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. second # [ 4.583006] systemd[1]: Finished Cleanup udev Database. second # [ 4.584146] systemd[1]: Reached target Switch Root. second # [ 4.587959] systemd[1]: Starting NixOS Activation... second # [ 4.660536] initrd-nixos-activation-start[174]: booting system configuration /nix/store/q8s0zh41js3zp5lqqxwmmlfxn7c36mza-nixos-system-second-test second # [ 4.691016] initrd-nixos-activation-start[174]: running activation script... second # [ 4.925221] initrd-nixos-activation-start[197]: setting up /etc... test # [ 4.926720] 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.926824] systemd[1]: Detected virtualization qemu. test # [ 4.926884] systemd[1]: Detected architecture arm64. test # [ 4.927017] systemd[1]: Detected first boot. test # [ 4.929815] systemd[1]: Initializing machine ID from random generator. second # [ 5.025791] initrd-nixos-activation-start[197]: setting up age secrets... test # [ 5.077623] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 5.119847] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 5.121874] systemd[1]: Finished NixOS Activation. second # [ 5.122647] systemd[1]: Starting Switch Root... second # [ 5.137402] systemd[1]: Switching root. test # [ 5.180366] systemd[1]: Applying preset policy. second # [ 5.304106] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). test # [ 5.356694] systemd[1]: Populated /etc with preset unit settings. test # [ 5.581808] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 5.582104] systemd[1]: Stopped initrd-switch-root.service. test # [ 5.584551] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 5.585257] systemd[1]: Created slice Slice /system/getty. test # [ 5.586213] systemd[1]: Created slice User and Session Slice. test # [ 5.588831] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 5.589412] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 5.589966] systemd[1]: Expecting device /dev/hvc0... test # [ 5.590492] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 5.593402] systemd[1]: Reached target Local Encrypted Volumes. test # [ 5.593918] systemd[1]: Stopped target initrd-fs.target. test # [ 5.594440] systemd[1]: Stopped target initrd-root-fs.target. test # [ 5.596688] systemd[1]: Stopped target initrd-switch-root.target. test # [ 5.597167] systemd[1]: Reached target Virtual Machines and Containers. test # [ 5.597673] systemd[1]: Reached target Path Units. test # [ 5.598164] systemd[1]: Reached target Remote File Systems. test # [ 5.600787] systemd[1]: Reached target Slice Units. test # [ 5.601262] systemd[1]: Reached target Swaps. test # [ 5.604110] systemd[1]: Listening on Query the User Interactively for a Password. test # [ 5.606058] systemd[1]: Listening on Process Core Dump Socket. test # [ 5.608970] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 5.611252] systemd[1]: Listening on Factory Reset Management. test # [ 5.611802] systemd[1]: Listening on Hostname Service Socket. test # [ 5.614393] systemd[1]: Starting Journal Log Access Socket... test # [ 5.617035] systemd[1]: Listening on Journal Audit Socket. test # [ 5.619687] systemd[1]: Listening on Console Output Muting Service Socket. test # [ 5.620359] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 5.621050] systemd[1]: Listening on Network Management Varlink Socket. test # [ 5.621715] systemd[1]: Listening on Network Management Netlink Socket. test # [ 5.622475] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 5.625903] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.627671] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki test # [ 5.632851] systemd[1]: Listening on Disk Repartitioning Service Socket. test # [ 5.633394] systemd[1]: Listening on Resolve Monitor Varlink Socket. test # [ 5.633918] systemd[1]: Listening on Resolve Service Varlink Socket. test # [ 5.634466] systemd[1]: Listening on udev Control Socket. test # [ 5.636893] systemd[1]: Listening on udev Varlink Socket. test # [ 5.639548] systemd[1]: Mounting Huge Pages File System... test # [ 5.641745] systemd[1]: Mounting POSIX Message Queue File System... test # [ 5.650740] systemd[1]: Mounting Kernel Debug File System... test # [ 5.663729] systemd[1]: Mounting Kernel Trace File System... test # [ 5.669079] systemd[1]: Starting Create List of Static Device Nodes... test # [ 5.670465] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 5.686638] systemd[1]: Mounting Kernel Configuration File System... test # [ 5.687087] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm test # [ 5.688950] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore test # [ 5.689594] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse test # [ 5.717822] systemd[1]: Mounting FUSE Control File System... test # [ 5.718213] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 5.744801] systemd[1]: Starting Journal Service... test # [ 5.754010] systemd[1]: Starting Load Kernel Modules... test # [ 5.774629] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 5.784899] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 5.785559] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.799664] systemd-journald[325]: Collecting audit messages is enabled. test # [ 5.804975] systemd[1]: Starting Coldplug All udev Devices... test # [ 5.810428] systemd[1]: Listening on Journal Log Access Socket. test # [ 5.805596] systemd[1]: Queued start job for default target Multi-User System. test # [ 5.807737] systemd[1]: systemd-journald.service: Deactivated successfully. test # [ 5.811440] systemd[1]: Mounted Huge Pages File System. test # [ 5.815056] systemd[1]: Started Journal Service. test # [ 5.814860] systemd[1]: Mounted POSIX Message Queue File System. test # [ 5.815560] systemd[1]: Mounted Kernel Debug File System. test # [ 5.819929] systemd[1]: Mounted Kernel Trace File System. test # [ 5.823224] systemd[1]: Finished Create List of Static Device Nodes. test # [ 5.825942] systemd[1]: Mounted Kernel Configuration File System. test # [ 5.852218] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 5.870869] systemd-modules-load[326]: Module 'atkbd' is built in test # [ 5.871486] systemd-modules-load[326]: Module 'ctr' is built in test # [ 5.878386] systemd-modules-load[326]: Module 'loop' is built in second # [ 5.876043] 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 # [ 5.876121] systemd[1]: Detected virtualization qemu. second # [ 5.876172] systemd[1]: Detected architecture arm64. second # [ 5.876273] systemd[1]: Detected first boot. test # [ 5.886967] EXT4-fs (vda): re-mounted 401b84c3-d12b-4087-8e20-e606cb9d1b3f. test # [ 5.885150] systemd[1]: Mounted FUSE Control File System. second # [ 5.886955] systemd[1]: Initializing machine ID from random generator. test # [ 5.892059] systemd[1]: Finished Load Kernel Modules. test # [ 5.897423] systemd[1]: Starting Firewall... test # [ 5.900216] systemd[1]: Starting Apply Kernel Variables... test # [ 5.900770] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 5.905427] systemd[1]: Listening on Disk Image Download Service Socket. test # [ 5.907991] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 5.908622] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 5.921025] systemd[1]: Starting Load/Save OS Random Seed... test # [ 5.921681] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.931674] systemd-oomd[328]: No swap; memory pressure usage will be degraded test # [ 5.941971] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 6.007119] systemd-journald[325]: Received client request to flush runtime journal. second # [ 6.027242] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 6.056493] systemd[1]: Finished Load/Save OS Random Seed. test # [ 6.057101] systemd[1]: Reached target First Boot Complete. test # [ 6.060859] systemd[1]: Finished Apply Kernel Variables. test # [ 6.069623] systemd[1]: Starting Network Name Resolution... test # [ 6.070179] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 6.108975] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 6.117795] systemd[1]: Applying preset policy. test # [ 6.121683] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 6.225276] systemd-resolved[353]: Positive Trust Anchors: test # [ 6.225875] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 6.226761] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 6.227644] 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 second # [ 6.276242] systemd[1]: Populated /etc with preset unit settings. test # [ 6.364902] systemd[1]: Finished Coldplug All udev Devices. test # [ 6.391729] systemd-resolved[353]: Using system hostname 'test'. test # [ 6.394375] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 6.395011] systemd[1]: Reached target Preparation for Local File Systems. test # [ 6.399150] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 6.399899] systemd[1]: Started Network Name Resolution. test # [ 6.452670] systemd-udevd[366]: Using default interface naming scheme 'v261'. second # [ 6.482087] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 6.482805] systemd[1]: Stopped initrd-switch-root.service. second # [ 6.486392] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 6.489288] systemd[1]: Created slice Slice /system/getty. second # [ 6.490372] systemd[1]: Created slice User and Session Slice. second # [ 6.492479] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 6.493081] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 6.493657] systemd[1]: Expecting device /dev/hvc0... second # [ 6.494248] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 6.494880] systemd[1]: Reached target Local Encrypted Volumes. second # [ 6.498475] systemd[1]: Stopped target initrd-fs.target. second # [ 6.500062] systemd[1]: Stopped target initrd-root-fs.target. second # [ 6.500560] systemd[1]: Stopped target initrd-switch-root.target. second # [ 6.501154] systemd[1]: Reached target Virtual Machines and Containers. second # [ 6.501735] systemd[1]: Reached target Path Units. second # [ 6.502311] systemd[1]: Reached target Remote File Systems. second # [ 6.505903] systemd[1]: Reached target Slice Units. second # [ 6.506418] systemd[1]: Reached target Swaps. second # [ 6.509873] systemd[1]: Listening on Query the User Interactively for a Password. second # [ 6.512735] systemd[1]: Listening on Process Core Dump Socket. second # [ 6.514362] systemd[1]: Listening on Credential Encryption/Decryption. second # [ 6.517755] systemd[1]: Listening on Factory Reset Management. second # [ 6.518347] systemd[1]: Listening on Hostname Service Socket. second # [ 6.522599] systemd[1]: Starting Journal Log Access Socket... test # [ 6.524117] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 6.524432] systemd[1]: Listening on Journal Audit Socket. second # [ 6.527436] systemd[1]: Listening on Console Output Muting Service Socket. second # [ 6.528114] systemd[1]: Listening on Network Management Metrics Varlink Socket. second # [ 6.528859] systemd[1]: Listening on Network Management Varlink Socket. second # [ 6.529525] systemd[1]: Listening on Network Management Netlink Socket. second # [ 6.530257] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 6.530808] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os second # [ 6.535650] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 6.541054] systemd[1]: Listening on Disk Repartitioning Service Socket. second # [ 6.541596] systemd[1]: Listening on Resolve Monitor Varlink Socket. second # [ 6.542185] systemd[1]: Listening on Resolve Service Varlink Socket. second # [ 6.542779] systemd[1]: Listening on udev Control Socket. second # [ 6.545288] systemd[1]: Listening on udev Varlink Socket. second # [ 6.548064] systemd[1]: Mounting Huge Pages File System... second # [ 6.550275] systemd[1]: Mounting POSIX Message Queue File System... second # [ 6.559244] systemd[1]: Mounting Kernel Debug File System... second # [ 6.568241] systemd[1]: Mounting Kernel Trace File System... second # [ 6.577674] systemd[1]: Starting Create List of Static Device Nodes... second # [ 6.578136] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 6.582301] systemd[1]: Mounting /run/wrappers... second # [ 6.588907] systemd[1]: Mounting Kernel Configuration File System... second # [ 6.589378] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 6.596656] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 6.597122] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 6.607541] systemd[1]: Mounting FUSE Control File System... second # [ 6.611428] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 second # [ 6.636831] systemd[1]: Starting Journal Service... test # [ 6.647929] systemd[1]: Mounted /run/wrappers. test # [ 6.649229] systemd[1]: Reached target Local File Systems. second # [ 6.652399] systemd[1]: Starting Load Kernel Modules... test # [ 6.652550] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 6.657858] systemd[1]: Starting register-nix-paths.service... test # [ 6.660491] systemd[1]: Starting Create SUID/SGID Wrappers... test # [ 6.661052] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 6.665813] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 6.669631] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... second # [ 6.685161] systemd[1]: Starting Remount Root and Kernel File Systems... second # [ 6.685558] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 6.686057] systemd[1]: Starting Create System Files and Directories... test # [ 6.687305] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 6.691881] systemd[1]: Starting Coldplug All udev Devices... second # [ 6.693821] systemd[1]: Listening on Journal Log Access Socket. second # [ 6.694244] systemd[1]: Mounted Huge Pages File System. second # [ 6.694624] systemd[1]: Mounted POSIX Message Queue File System. test # [ 6.693689] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 6.700762] systemd[1]: Mounted Kernel Debug File System. second # [ 6.701228] systemd[1]: Mounted Kernel Trace File System. second # [ 6.720743] systemd[1]: Finished Create List of Static Device Nodes. second # [ 6.741458] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 6.755737] systemd-journald[301]: Collecting audit messages is enabled. second # [ 6.762552] systemd[1]: Finished Load Kernel Modules. second # [ 6.760955] systemd[1]: Queued start job for default target Multi-User System. second # [ 6.761816] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 6.762594] systemd-modules-load[302]: Module 'atkbd' is built in second # [ 6.763256] systemd-modules-load[302]: Module 'ctr' is built in second # [ 6.763923] systemd-modules-load[302]: Module 'loop' is built in second # [ 6.777210] systemd[1]: Starting Firewall... second # [ 6.780296] systemd[1]: Starting Apply Kernel Variables... second # [ 6.780791] systemd[1]: Started Journal Service. second # [ 6.802016] systemd[1]: Mounted Kernel Configuration File System. second # [ 6.814230] systemd-oomd[303]: No swap; memory pressure usage will be degraded second # [ 6.822639] EXT4-fs (vda): re-mounted bdd3a468-9ee5-439f-adf9-a2b8c8670015. second # [ 6.828765] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. second # [ 6.837396] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 6.851260] systemd[1]: Finished Create System Files and Directories. second # [ 6.847409] systemd[1]: Listening on Disk Image Download Service Socket. test # [ 6.855479] systemd[1]: Starting Rebuild Journal Catalog... second # [ 6.853943] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 6.854607] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 6.864374] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 6.862939] systemd[1]: Starting Load/Save OS Random Seed... second # [ 6.863556] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 6.873468] systemd[1]: Mounted FUSE Control File System. test # [ 6.902805] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 6.908156] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 6.943940] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. second # [ 6.947560] systemd-journald[301]: Received client request to flush runtime journal. second # [ 6.998810] systemd[1]: Finished Load/Save OS Random Seed. second # [ 6.999461] systemd[1]: Reached target First Boot Complete. second # [ 6.999986] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 7.005680] systemd[1]: Finished Apply Kernel Variables. second # [ 7.007868] systemd[1]: Starting Network Name Resolution... second # [ 7.009660] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 7.010277] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 7.039967] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 7.050339] systemd[1]: Finished Rebuild Journal Catalog. test # [ 7.055128] systemd[1]: Starting Update is Completed... test # [ 7.068713] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 7.122817] systemd[1]: Finished Update is Completed. second # [ 7.164812] systemd-resolved[332]: Positive Trust Anchors: second # [ 7.165459] systemd-resolved[332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d second # [ 7.166336] systemd-resolved[332]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 7.167203] systemd-resolved[332]: 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 # [ 7.280594] systemd[1]: Finished Coldplug All udev Devices. second # [ 7.297536] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 7.298240] systemd[1]: Reached target Preparation for Local File Systems. second # [ 7.300660] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 7.326495] systemd-resolved[332]: Using system hostname 'second'. second # [ 7.331372] systemd[1]: Started Network Name Resolution. second # [ 7.368328] systemd-udevd[344]: Using default interface naming scheme 'v261'. test # [ 7.381175] (udev-worker)[398]: Network interface NamePolicy= disabled on kernel command line. test # [ 7.392434] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line. second # [ 7.439415] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 7.480494] systemd[1]: Mounting /run/wrappers... second # [ 7.561378] systemd[1]: Mounted /run/wrappers. second # [ 7.562399] systemd[1]: Reached target Local File Systems. second # [ 7.566905] systemd[1]: Listening on Boot Loader Control Service Socket. second # [ 7.571554] systemd[1]: Starting register-nix-paths.service... second # [ 7.575459] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 7.576217] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 7.579989] systemd[1]: Starting Save Transient machine-id to Disk... test # [ 7.587619] systemd[1]: Finished register-nix-paths.service. test # [ 7.594627] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 7.591192] systemd[1]: Starting Create System Files and Directories... second # [ 7.591888] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 7.597394] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 7.599680] systemd[1]: Reached target System Initialization. test # [ 7.601690] systemd[1]: Started Discard unused filesystem blocks once a week. test # [ 7.602372] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 7.602990] systemd[1]: Reached target Timer Units. test # [ 7.603471] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 7.609964] systemd[1]: Listening on Nix Daemon Socket. test # [ 7.615020] systemd[1]: Listening on Authorization Manager Agent Helper. test # [ 7.615710] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. test # [ 7.618690] systemd[1]: Reached target Socket Units. second # [ 7.614062] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse test # [ 7.620645] systemd[1]: Starting D-Bus System Message Bus... test # [ 7.664601] mousedev: PS/2 mouse device common for all mice test # [ 7.747756] dbus-broker-launch[520]: Looking up NSS user entry for 'systemd-timesync'... test # [ 7.756104] dbus-broker-launch[520]: NSS returned no entry for 'systemd-timesync' test # [ 7.756821] dbus-broker-launch[520]: Invalid user-name in /nix/store/1635m3fbdc464bxfy61d5x8mp5003m10-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" second # [ 7.760899] systemd[1]: Finished Create System Files and Directories. second # [ 7.770496] systemd[1]: Starting Rebuild Journal Catalog... second # [ 7.780934] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 7.784909] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 7.792209] dbus-broker-launch[520]: 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.789614] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 7.796778] dbus-broker-launch[520]: 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' test # [ 7.802062] dbus-broker-launch[520]: 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.803857] dbus-broker-launch[520]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' test # [ 7.814206] systemd[1]: Started D-Bus System Message Bus. test # [ 7.814738] systemd[1]: Reached target Basic System. test # [ 7.819743] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 7.823441] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 7.829351] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 7.866661] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 7.868939] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 7.874738] systemd[1]: Started backdoor.service. test # [ 7.888660] dbus-broker-launch[520]: Ready test # [ 7.920905] systemd[1]: Starting Import lastlog data into lastlog2 database... second # [ 7.945332] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 7.949929] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 7.965294] systemd[1]: Starting Post-Boot Actions... second # [ 7.982432] systemd[1]: Finished Rebuild Journal Catalog. second # [ 7.990803] systemd[1]: Starting Update is Completed... second # [ 7.996209] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 8.016248] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all test # [ 8.027540] systemd[1]: Started Reset console on configuration changes. test # [ 8.060136] w20vy84jlc9q97pm5z31jcyz4xpvlr4j-wifi-secrets[541]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open second # [ 8.071697] systemd[1]: Finished Update is Completed. test # [ 8.076077] nj6y3zgv8ppidbs8s4xlaglnzsav95yb-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one test # connecting to host... test # [ 8.105485] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. test # [ 8.109154] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 8.115370] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 8.120441] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 8.146048] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. test # [ 8.152333] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 8.155887] systemd[1]: Finished Post-Boot Actions. test: Guest shell says: b'Spawning backdoor root shell...\n' test # [ 8.180769] systemd[1]: Finished Firewall. test # [ 8.199645] systemd[1]: Reached target Preparation for Network. test # [ 8.200618] systemd[1]: Listening on Network Management Resolve Hook Socket. test # [ 8.211075] systemd[1]: Starting Network Manager... test # [ 8.217323] systemd[1]: Starting Network Management... test # [ 8.217819] systemd[1]: Started Name Service Cache Daemon (nsncd). test: connected to guest root shell test: (connecting took 8.48 seconds) test: (finished: waiting for the VM to finish booting, in 8.48 seconds) test # [ 8.227315] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 8.227991] systemd[1]: Reached target Host and Network Name Lookups. test # [ 8.230102] systemd[1]: Reached target User and Group Name Lookups. test # [ 8.256533] systemd[1]: Starting User Login Management... test # [ 8.272058] nsncd[547]: Oct 15 15:14:34.090 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 8.292150] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 8.300886] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line. second # [ 8.352879] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line. second # [ 8.395948] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 8.398694] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 8.457463] systemd-logind[599]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 8.459963] systemd-logind[599]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 8.464519] systemd-logind[599]: New seat seat0. test # [ 8.465004] systemd[1]: Started User Login Management. test # [ 8.473582] systemd[1]: Starting linger-users.service... second # [ 8.539628] mousedev: PS/2 mouse device common for all mice second # [ 8.558527] systemd[1]: Finished register-nix-paths.service. second # [ 8.559159] systemd[1]: Reached target System Initialization. second # [ 8.559700] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 8.563629] systemd[1]: Started Daily Cleanup of Temporary Directories. second # [ 8.566814] systemd[1]: Reached target Timer Units. second # [ 8.567313] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 8.577964] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 8.567885] systemd[1]: Listening on Nix Daemon Socket. second # [ 8.572254] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 8.572877] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. second # [ 8.573611] systemd[1]: Reached target Socket Units. second # [ 8.578719] systemd[1]: Starting D-Bus System Message Bus... test # [ 8.609697] systemd[1]: linger-users.service: Deactivated successfully. test # [ 8.612933] systemd[1]: Finished linger-users.service. test # [ 8.656363] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 8.656854] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' test # [ 8.670371] systemd[1]: Starting Hostname Service... test # [ 8.703032] systemd-networkd[588]: /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.703444] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'... test # [ 8.712104] systemd-networkd[588]: /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.713802] systemd-networkd[588]: lo: Link UP test # [ 8.714219] systemd-networkd[588]: lo: Gained carrier second # [ 8.711640] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync' second # [ 8.713720] dbus-broker-launch[501]: Invalid user-name in /nix/store/1635m3fbdc464bxfy61d5x8mp5003m10-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 8.724608] systemd[1]: Started Network Management. test # [ 8.727880] systemd-networkd[588]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 8.737789] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... test # [ 8.738465] systemd-networkd[588]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 8.739172] systemd-networkd[588]: eth1: Link UP test # [ 8.739577] systemd-networkd[588]: eth1: Gained carrier test # [ 8.748775] systemd-networkd[588]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 8.747811] dbus-broker-launch[501]: 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 # [ 8.753225] dbus-broker-launch[501]: 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 # [ 8.754987] dbus-broker-launch[501]: 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 # [ 8.764764] dbus-broker-launch[501]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 8.766635] systemd[1]: Started D-Bus System Message Bus. second # [ 8.767141] systemd[1]: Reached target Basic System. second # [ 8.778589] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 8.779315] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 8.779930] systemd[1]: Started backdoor.service. second # [ 8.805230] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 8.825560] systemd[1]: Started Hostname Service. test # [ 8.839051] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 8.835363] dbus-broker-launch[501]: Ready second # [ 8.844612] systemd[1]: Starting Name Service Cache Daemon (nsncd)... second # [ 8.852288] systemd[1]: Starting Post-Boot Actions... second # [ 8.887254] systemd[1]: Started Reset console on configuration changes. test # [ 8.899964] systemd-networkd[588]: eth0: Link UP test # [ 8.901858] systemd-networkd[588]: eth0: Gained carrier test # [ 8.919840] systemd[1]: Started Network Manager. test # [ 8.922351] systemd[1]: Reached target Network. test # [ 8.927607] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... test # [ 8.936219] systemd[1]: Starting Permit User Sessions... second # [ 8.935416] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[519]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all second # [ 8.972508] systemd[1]: Finished Firewall. second # [ 8.978456] systemd[1]: Reached target Preparation for Network. second # [ 8.979102] systemd[1]: Listening on Network Management Resolve Hook Socket. second # connecting to host... second # [ 8.992181] systemd[1]: Starting Network Manager... second # [ 9.005689] systemd[1]: Starting Network Management... second # [ 9.022917] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 9.030141] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 9.047211] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 9.053833] nsncd[523]: Oct 15 15:14:34.855 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 9.059379] systemd[1]: Finished Permit User Sessions. second # [ 9.062260] systemd[1]: Finished Post-Boot Actions. test # [ 9.072892] systemd[1]: Started Getty on tty1. test # [ 9.082634] systemd[1]: Reached target Login Prompts. test # [ 9.099476] systemd-resolved[353]: eth0: Bus client set default route setting: yes test # [ 9.104481] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 9.110466] systemd[1]: Reached target Host and Network Name Lookups. second # [ 9.116682] systemd[1]: Reached target User and Group Name Lookups. second # [ 9.135454] systemd[1]: Starting User Login Management... second # [ 9.167969] systemd[1]: Started Network Manager Script Dispatcher Service. second # [ 9.196788] systemd[1]: Finished Import lastlog data into lastlog2 database. test # [ 9.210386] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. test # [ 9.213564] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 9.223693] systemd[1]: Reached target Multi-User System. test # [ 9.228107] systemd[1]: Startup finished in 769ms (kernel) + 3.694s (initrd) + 4.761s (userspace) = 9.225s. second # [ 9.281597] systemd-logind[563]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 9.285044] systemd-logind[563]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 9.286076] systemd-logind[563]: New seat seat0. second # [ 9.291489] systemd[1]: Started User Login Management. second # [ 9.297152] systemd[1]: Starting linger-users.service... test # [ 9.368876] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 second # [ 9.402106] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.435456] systemd[1]: linger-users.service: Deactivated successfully. second # [ 9.436587] systemd[1]: Finished linger-users.service. second # [ 9.479375] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 9.479912] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 9.481339] systemd[1]: Starting Hostname Service... test: (finished: waiting for unit NetworkManager.service, in 9.76 seconds) test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds) test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open second # [ 9.528238] systemd-networkd[541]: /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 # [ 9.536234] systemd-networkd[541]: /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 # [ 9.537925] systemd-networkd[541]: lo: Link UP second # [ 9.538326] systemd-networkd[541]: lo: Gained carrier test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.02 seconds) test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection second # [ 9.548043] systemd[1]: Started Network Management. second # [ 9.552197] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.561109] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 9.561932] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 9.562555] systemd-networkd[541]: eth1: Link UP second # [ 9.562957] systemd-networkd[541]: eth1: Gained carrier second # [ 9.563415] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.633005] systemd[1]: Started Hostname Service. second # [ 9.648914] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.12 seconds) (finished: run the VM test script, in 10.34 seconds) second # [ 9.704743] systemd-networkd[541]: eth0: Link UP second # [ 9.706715] systemd-networkd[541]: eth0: Gained carrier second # [ 9.726038] systemd[1]: Started Network Manager. second # [ 9.727856] systemd[1]: Reached target Network. second # [ 9.734097] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 9.739127] systemd[1]: Starting Permit User Sessions... test # [ 9.803619] systemd[1]: Starting Virtual Console Setup... test # [ 9.836390] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test # [ 9.850599] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 9.854249] systemd[1]: Stopped Virtual Console Setup. test # [ 9.857722] systemd[1]: Starting Virtual Console Setup... test # [ 9.862478] systemd-logind[599]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) test script finished in 10.54s 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-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down second # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down second # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down test # [ 9.969012] systemd-vconsole-setup[701]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 9.971952] systemd[1]: Finished Virtual Console Setup. 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-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down test # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down test # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.32 seconds)