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 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 test # Disk image does not exist, creating the virtualisation disk image... second # Disk image does not exist, creating the virtualisation disk image... test # Formatting '/build/vm-state-test/tmp.kz1NzY3b7w', fmt=raw size=1073741824 test: QEMU running (pid 15) second: QEMU running (pid 14) second # Formatting '/build/vm-state-second/tmp.Cv6xThZyjL', fmt=raw size=1073741824 test # mke2fs 1.47.4 (6-Mar-2025) second # mke2fs 1.47.4 (6-Mar-2025) test # Discarding device blocks: 0/262144 done second # Discarding device blocks: 0/262144 done test # Creating filesystem with 262144 4k blocks and 65536 inodes (finished: start all VMs, in 0.53 seconds) test # Filesystem UUID: b463b6ff-c664-4f93-b9e6-f8c293004dec test # Superblock backups stored on blocks: test: waiting for unit NetworkManager.service second # Creating filesystem with 262144 4k blocks and 65536 inodes test # 32768, 98304, 163840, 229376 test: waiting for the VM to finish booting test # second # Filesystem UUID: f872a561-5625-4c36-b824-b8c12c9e6a9b test # Allocating group tables: 0/8 done second # Superblock backups stored on blocks: test # Writing inode tables: 0/8 done second # 32768, 98304, 163840, 229376 test # Creating journal (8192 blocks): done second # test # Writing superblocks and filesystem accounting information: 0/8 done second # Allocating group tables: 0/8 done test # second # Writing inode tables: 0/8 done test # Virtualisation disk image created. second # Creating journal (8192 blocks): done second # Writing superblocks and filesystem accounting information: 0/8 done second # second # Virtualisation disk image created. test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] test # [ 0.000000] Linux version 6.18.43 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 6 17:27:09 UTC 2026 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 0x7fc07740-0x7fc0aebf] test # [ 0.000000] Zone ranges: test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] DMA32 empty test # [ 0.000000] Normal empty test # [ 0.000000] Device empty test # [ 0.000000] Movable zone start for each node test # [ 0.000000] Early memory node ranges test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 test # [ 0.000000] psci: probing for conduit method from DT. test # [ 0.000000] psci: PSCIv1.3 detected in firmware. test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] psci: Trusted OS migration not required test # [ 0.000000] psci: SMC Calling Convention v1.1 test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 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 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/b6nxbs0rjlfiq2hy90qarypf3gl02bhm-nixos-system-test-test/init regInfo=/nix/store/hc2zgcc8b2vm7h4axsqj6ig7kfj7g3rd-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/hc2zgcc8b2vm7h4axsqj6ig7kfj7g3rd-closure-info/registration", will be passed to user space. second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] 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] Linux version 6.18.43 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 6 17:27:09 UTC 2026 second # [ 0.000000] KASLR enabled second # [ 0.000000] random: crng init done second # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.000000] efi: UEFI not found. second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.000000] Zone ranges: test # [ 0.000000] Policy zone: DMA second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] DMA32 empty test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] Normal empty second # [ 0.000000] Device empty test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] Movable zone start for each node test # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] Early memory node ranges test # [ 0.000000] ftrace: allocating 74743 entries in 292 pages second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] ftrace: allocated 292 pages with 3 groups test # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. second # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.000000] psci: PSCIv1.3 detected in firmware. test # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] psci: SMC Calling Convention v1.1 test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] CPU features: detected: GICv3 CPU interface test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] CPU features: detected: Spectre-BHB test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 test # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] GICv3: 0 Extended SPIs implemented second # [ 0.000000] CPU features: detected: ARM erratum 1418040 test # [ 0.000000] Root IRQ handler: gic_handle_irq test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.000000] alternatives: applying boot alternatives test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 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] 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/k9zwrlaml4d69mrzm8vpvvll1hmwm4y7-nixos-system-second-test/init regInfo=/nix/store/4rq1xswbrn2pqwb4xnr887cfjlp5g1xf-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4rq1xswbrn2pqwb4xnr887cfjlp5g1xf-closure-info/registration", will be passed to user space. test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns 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] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.000059] arm-pv: using stolen time PV second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] Fallback order for Node 0: 0 test # [ 0.000417] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000693] Console: colour dummy device 80x25 second # [ 0.000000] Policy zone: DMA test # [ 0.000705] printk: legacy console [tty0] enabled second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off test # [ 0.000922] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 test # [ 0.000928] pid_max: default: 32768 minimum: 301 second # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.001009] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.000000] ftrace: allocating 74743 entries in 292 pages test # [ 0.001149] landlock: Up and running. second # [ 0.000000] ftrace: allocated 292 pages with 3 groups test # [ 0.001151] Yama: becoming mindful. second # [ 0.000000] rcu: Hierarchical RCU implementation. test # [ 0.001564] LSM support for eBPF active second # [ 0.000000] rcu: RCU event tracing is enabled. test # [ 0.001719] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. test # [ 0.001739] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.000000] Trampoline variant of Tasks RCU enabled. second # [ 0.000000] Rude variant of Tasks RCU enabled. test # [ 0.003572] cacheinfo: Unable to detect cache hierarchy for CPU 0 second # [ 0.000000] Tracing variant of Tasks RCU enabled. test # [ 0.004411] rcu: Hierarchical SRCU implementation. test # [ 0.004416] rcu: Max phase no-delay instances is 1000. second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.005349] fsl-mc MSI: its@8080000 domain created second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 test # [ 0.005544] EFI services will not be available. test # [ 0.005643] smp: Bringing up secondary CPUs ... 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.005676] smp: Brought up 1 node, 1 CPU test # [ 0.005680] SMP: Total of 1 processors activated. 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.005682] CPU: All CPU(s) started at EL1 test # [ 0.005695] CPU features: detected: 32-bit EL0 Support 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.005698] CPU features: detected: Data cache clean to the PoU not required for I/D coherence second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.005701] CPU features: detected: Common not Private translations second # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.005703] CPU features: detected: CRC32 instructions second # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.005705] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 test # [ 0.005707] CPU features: detected: Enhanced Virtualization Traps second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 test # [ 0.005717] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.005719] CPU features: detected: LSE atomic instructions second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) test # [ 0.005721] CPU features: detected: Privileged Access Never test # [ 0.005723] CPU features: detected: PMUv3 test # [ 0.005725] CPU features: detected: RAS Extension Support second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) test # [ 0.005728] CPU features: detected: Speculative Store Bypassing Safe (SSBS) second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 test # [ 0.005751] alternatives: applying system-wide alternatives second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 test # [ 0.007249] CPU features: detected: Hardware dirty bit management on CPU0 second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns test # [ 0.007486] Memory: 893144K/1048576K available (24320K kernel code, 7086K rwdata, 26332K rodata, 4736K init, 1102K bss, 114152K reserved, 32768K cma-reserved) test # [ 0.007997] devtmpfs: initialized second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). test # [ 0.009507] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.009531] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns test # [ 0.009666] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.009669] 0 pages in range for non-PLT usage second # [ 0.000057] arm-pv: using stolen time PV test # [ 0.009670] 508336 pages in range for PLT usage test # [ 0.009815] pinctrl core: initialized pinctrl subsystem second # [ 0.000422] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) test # [ 0.010646] DMI not present or invalid. second # [ 0.000704] Console: colour dummy device 80x25 test # [ 0.014025] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.000716] printk: legacy console [tty0] enabled test # [ 0.016429] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.000925] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.016582] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations second # [ 0.000931] pid_max: default: 32768 minimum: 301 test # [ 0.016752] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations second # [ 0.001007] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.001141] landlock: Up and running. test # [ 0.016778] audit: initializing netlink subsys (disabled) second # [ 0.001144] Yama: becoming mindful. test # [ 0.017415] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.001580] LSM support for eBPF active test # [ 0.017417] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.001730] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.017421] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.001749] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.017423] thermal_sys: Registered thermal governor 'user_space' second # [ 0.003524] cacheinfo: Unable to detect cache hierarchy for CPU 0 test # [ 0.017425] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.004381] rcu: Hierarchical SRCU implementation. test # [ 0.017461] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.004388] rcu: Max phase no-delay instances is 1000. test # [ 0.017477] cpuidle: using governor ladder second # [ 0.005361] fsl-mc MSI: its@8080000 domain created test # [ 0.017481] cpuidle: using governor menu second # [ 0.005556] EFI services will not be available. test # [ 0.017613] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.005658] smp: Bringing up secondary CPUs ... second # [ 0.005689] smp: Brought up 1 node, 1 CPU test # [ 0.017637] ASID allocator initialised with 32768 entries second # [ 0.005694] SMP: Total of 1 processors activated. test # [ 0.018314] Serial: AMBA PL011 UART driver second # [ 0.005696] CPU: All CPU(s) started at EL1 test # [ 0.022131] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.005709] CPU features: detected: 32-bit EL0 Support test # [ 0.022306] printk: console [ttyAMA0] enabled second # [ 0.005711] CPU features: detected: Data cache clean to the PoU not required for I/D coherence second # [ 0.005714] CPU features: detected: Common not Private translations second # [ 0.005716] CPU features: detected: CRC32 instructions second # [ 0.005718] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.005721] CPU features: detected: Enhanced Virtualization Traps second # [ 0.005730] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.005733] CPU features: detected: LSE atomic instructions second # [ 0.005735] CPU features: detected: Privileged Access Never second # [ 0.005737] CPU features: detected: PMUv3 second # [ 0.005739] CPU features: detected: RAS Extension Support second # [ 0.005742] CPU features: detected: Speculative Store Bypassing Safe (SSBS) second # [ 0.005764] alternatives: applying system-wide alternatives second # [ 0.007320] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.095335] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages second # [ 0.007582] Memory: 893136K/1048576K available (24320K kernel code, 7086K rwdata, 26332K rodata, 4736K init, 1102K bss, 114152K reserved, 32768K cma-reserved) test # [ 0.095367] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page second # [ 0.008043] devtmpfs: initialized test # [ 0.095371] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.009585] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.095377] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page second # [ 0.009607] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.095381] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages second # [ 0.009743] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL test # [ 0.095384] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page second # [ 0.009747] 0 pages in range for non-PLT usage second # [ 0.009748] 508336 pages in range for PLT usage test # [ 0.095387] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages second # [ 0.009870] pinctrl core: initialized pinctrl subsystem test # [ 0.095390] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page second # [ 0.010656] DMI not present or invalid. second # [ 0.013980] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.016379] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.016534] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.100591] fbcon: Taking over console test # [ 0.100615] ACPI: Interpreter disabled. second # [ 0.016708] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations second # [ 0.016731] audit: initializing netlink subsys (disabled) second # [ 0.017347] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.017350] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.017353] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.017355] thermal_sys: Registered thermal governor 'user_space' second # [ 0.017357] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.017397] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.017411] cpuidle: using governor ladder second # [ 0.017416] cpuidle: using governor menu second # [ 0.017558] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.017581] ASID allocator initialised with 32768 entries second # [ 0.018241] Serial: AMBA PL011 UART driver test # [ 0.107107] iommu: Default domain type: Translated second # [ 0.021781] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.021968] printk: console [ttyAMA0] enabled test # [ 0.107125] iommu: DMA domain TLB invalidation policy: strict mode test # [ 0.107478] SCSI subsystem initialized test # [ 0.109164] usbcore: registered new interface driver usbfs test # [ 0.109203] usbcore: registered new interface driver hub test # [ 0.109215] usbcore: registered new device driver usb test # [ 0.109481] pps_core: LinuxPPS API ver. 1 registered test # [ 0.109488] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.109494] PTP clock support registered test # [ 0.109538] EDAC MC: Ver: 3.0.0 test # [ 0.112593] scmi_core: SCMI protocol bus registered test # [ 0.113377] FPGA manager framework test # [ 0.114406] vgaarb: loaded second # [ 0.096151] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages second # [ 0.096189] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.114846] clocksource: Switched to clocksource arch_sys_counter second # [ 0.096194] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.096199] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.116262] VFS: Disk quotas dquot_6.6.0 second # [ 0.096202] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.116301] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.096205] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page second # [ 0.096209] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages second # [ 0.096212] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page second # [ 0.101476] fbcon: Taking over console second # [ 0.101500] ACPI: Interpreter disabled. test # [ 0.123521] netfs: FS-Cache loaded test # [ 0.123689] pnp: PnP ACPI: disabled second # [ 0.107468] iommu: Default domain type: Translated second # [ 0.107483] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.108445] SCSI subsystem initialized second # [ 0.109936] usbcore: registered new interface driver usbfs second # [ 0.109976] usbcore: registered new interface driver hub second # [ 0.109990] usbcore: registered new device driver usb second # [ 0.110236] pps_core: LinuxPPS API ver. 1 registered second # [ 0.110243] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.110257] PTP clock support registered test # [ 0.127303] NET: Registered PF_INET protocol family second # [ 0.110288] EDAC MC: Ver: 3.0.0 test # [ 0.127506] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) second # [ 0.113456] scmi_core: SCMI protocol bus registered second # [ 0.114256] FPGA manager framework second # [ 0.115311] vgaarb: loaded second # [ 0.123772] clocksource: Switched to clocksource arch_sys_counter second # [ 0.124541] VFS: Disk quotas dquot_6.6.0 second # [ 0.124572] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.126273] netfs: FS-Cache loaded second # [ 0.126410] pnp: PnP ACPI: disabled second # [ 0.129569] NET: Registered PF_INET protocol family second # [ 0.129773] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.158166] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.158201] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) test # [ 0.158216] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.158254] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.158322] TCP: Hash tables configured (established 8192 bind 8192) test # [ 0.158417] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.158483] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.158536] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.158656] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.158687] NET: Registered PF_XDP protocol family test # [ 0.158711] PCI: CLS 0 bytes, default 64 test # [ 0.158954] Trying to unpack rootfs image as initramfs... test # [ 0.173396] kvm [1]: HYP mode not available second # [ 0.157580] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.157616] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.157632] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.157669] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.157737] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.157842] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.157913] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.157963] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.158093] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.158168] NET: Registered PF_XDP protocol family second # [ 0.158194] PCI: CLS 0 bytes, default 64 second # [ 0.158426] Trying to unpack rootfs image as initramfs... second # [ 0.169985] kvm [1]: HYP mode not available test # [ 0.247257] Initialise system trusted keyrings test # [ 0.247838] workingset: timestamp_bits=42 max_order=18 bucket_order=0 test # [ 0.248843] squashfs: version 4.0 (2009/01/31) Phillip Lougher test # [ 0.249389] 9p: Installing v9fs 9p2000 file system support second # [ 0.244175] Initialise system trusted keyrings second # [ 0.244745] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.245786] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.246323] 9p: Installing v9fs 9p2000 file system support test # [ 0.268755] Key type asymmetric registered test # [ 0.268787] Asymmetric key parser 'x509' registered test # [ 0.268858] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.274945] io scheduler mq-deadline registered test # [ 0.274968] io scheduler kyber registered test # [ 0.278473] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.279424] ledtrig-cpu: registered to indicate activity on CPUs test # [ 0.279880] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.265762] Key type asymmetric registered test # [ 0.279918] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.265793] Asymmetric key parser 'x509' registered test # [ 0.279935] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.265882] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.279942] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.279981] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.280008] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.280090] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 test # [ 0.280110] pci_bus 0000:00: root bus resource [bus 00-ff] test # [ 0.280116] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] test # [ 0.280120] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] test # [ 0.280124] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.271860] io scheduler mq-deadline registered second # [ 0.271882] io scheduler kyber registered test # [ 0.280224] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint test # [ 0.280672] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.280790] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] test # [ 0.280801] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.280822] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.280835] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.275183] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.281125] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.281239] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] test # [ 0.281251] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.281271] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.281549] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.281660] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] test # [ 0.281670] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.281690] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.276165] ledtrig-cpu: registered to indicate activity on CPUs test # [ 0.281977] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.276533] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: test # [ 0.282087] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.282098] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.276558] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 test # [ 0.282117] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.276574] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 test # [ 0.282411] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.276580] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.282523] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] test # [ 0.282534] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.276615] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.282553] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.276639] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.282834] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint second # [ 0.276741] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.276760] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.276765] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.276769] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.276773] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.276862] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.277295] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.277411] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.277423] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.277453] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.277465] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.277756] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.277873] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.277885] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.277905] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.278186] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.307032] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] second # [ 0.278299] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] test # [ 0.307059] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.278309] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.307080] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.278329] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.307468] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.278608] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.307585] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] second # [ 0.278720] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.307596] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.278731] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.307616] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.278751] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.307631] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.279037] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.307934] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.279149] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] test # [ 0.308050] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.279161] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.308069] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.279180] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.308348] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.279459] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.308463] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.279572] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] test # [ 0.308482] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.279582] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.279602] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.308739] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint test # [ 0.308850] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] test # [ 0.309017] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.309242] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] test # [ 0.309256] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.309277] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.309578] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.309693] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.309704] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.309725] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.310151] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.310162] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.310167] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.310199] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.310231] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.310262] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.310292] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.310322] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.310354] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.310386] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.310417] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.310447] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.310508] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.310575] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.310590] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.310604] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.310619] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.310635] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned test # [ 0.310651] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.312088] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.310666] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned second # [ 0.312259] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] test # [ 0.310682] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.312272] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.310698] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.312293] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.310713] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned second # [ 0.312307] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.310730] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.312641] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.310746] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.312757] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.310761] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.312776] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.310777] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.310792] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned second # [ 0.313058] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint test # [ 0.310807] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.313171] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.310822] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.313191] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.313451] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.313562] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.313723] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.313934] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.313948] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.313968] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.314334] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.314446] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.314457] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.314477] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.314887] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned second # [ 0.314899] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned second # [ 0.314904] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.346941] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned second # [ 0.314937] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.347023] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned second # [ 0.314969] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.347039] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned second # [ 0.314999] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.347056] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned test # [ 0.347085] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.315030] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.347092] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] test # [ 0.347096] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] test # [ 0.347815] pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.315061] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned second # [ 0.315093] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned second # [ 0.315125] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned second # [ 0.315156] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned second # [ 0.315188] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned second # [ 0.315263] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned second # [ 0.315295] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned second # [ 0.315309] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned second # [ 0.315324] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned second # [ 0.315339] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned second # [ 0.315354] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.315371] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.315387] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned second # [ 0.315402] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.315417] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.315432] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned second # [ 0.315449] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.315465] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.315482] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.315498] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.315514] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned second # [ 0.315529] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.315544] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.315566] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned second # [ 0.315581] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned second # [ 0.315596] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned second # [ 0.315611] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned second # [ 0.315632] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.315638] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] test # [ 0.365280] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.315642] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.316365] pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.355594] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.375113] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.378334] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) second # [ 0.365831] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.388378] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.372329] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) second # [ 0.374290] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.391435] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) test # [ 0.393461] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) test # [ 0.403381] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) test # [ 0.405610] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.408447] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) test # [ 0.410816] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.388262] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) second # [ 0.390415] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) second # [ 0.392362] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) second # [ 0.394504] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.420255] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.428663] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.404310] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.406365] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.408932] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.430344] msm_serial: driver initialized test # [ 0.430478] SuperH (H)SCI(F) driver initialized test # [ 0.430529] STM32 USART driver initialized second # [ 0.421225] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.422925] msm_serial: driver initialized second # [ 0.423061] SuperH (H)SCI(F) driver initialized second # [ 0.423107] STM32 USART driver initialized test # [ 0.458747] loop: module loaded test # [ 0.458912] virtio_blk virtio5: 1/0/0 default/read/poll queues test # [ 0.459510] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.450146] loop: module loaded second # [ 0.450299] virtio_blk virtio5: 1/0/0 default/read/poll queues second # [ 0.450953] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.456370] megasas: 07.734.00.00-rc1 second # [ 0.456922] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] second # [ 0.468878] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.471388] megasas: 07.734.00.00-rc1 second # [ 0.468914] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.470617] Using buffer write method test # [ 0.472098] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] second # [ 0.470667] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.489441] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.489516] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.481351] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.481390] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.483293] Using buffer write method second # [ 0.483334] Concatenating MTD devices: second # [ 0.483338] (0): "0.flash" second # [ 0.483341] (1): "0.flash" second # [ 0.483343] into device "0.flash" test # [ 0.507488] Using buffer write method test # [ 0.507548] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.509332] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.509359] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.518899] Using buffer write method test # [ 0.518938] Concatenating MTD devices: test # [ 0.518941] (0): "0.flash" test # [ 0.518944] (1): "0.flash" test # [ 0.518947] into device "0.flash" second # [ 0.716864] Freeing initrd memory: 25544K test # [ 0.738766] Freeing initrd memory: 25544K second # [ 0.724946] tun: Universal TUN/TAP device driver, 1.6 test # [ 0.746891] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.727882] thunder_xcv, ver 1.0 second # [ 0.727917] thunder_bgx, ver 1.0 second # [ 0.727932] nicpf, ver 1.0 second # [ 0.728324] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.728333] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.728352] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.749855] thunder_xcv, ver 1.0 second # [ 0.728358] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.749890] thunder_bgx, ver 1.0 test # [ 0.749903] nicpf, ver 1.0 second # [ 0.728373] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.750278] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.728376] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.750288] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.728387] igbvf: Intel(R) Gigabit Virtual Function Network Driver test # [ 0.750310] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.728390] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.750315] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.728565] sky2: driver version 1.30 test # [ 0.750331] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.729470] usbcore: registered new interface driver usb-storage test # [ 0.750334] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.729593] usbcore: registered new interface driver usbserial_generic test # [ 0.750347] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.729602] usbserial: USB Serial support registered for generic test # [ 0.750352] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.729927] hv_vmbus: registering driver hyperv_keyboard test # [ 0.750427] sky2: driver version 1.30 second # [ 0.738008] ehci-pci 0000:00:0a.0: EHCI Host Controller second # [ 0.738065] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 second # [ 0.738247] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 test # [ 0.756445] usbcore: registered new interface driver usb-storage test # [ 0.756595] usbcore: registered new interface driver usbserial_generic second # [ 0.740077] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.756606] usbserial: USB Serial support registered for generic second # [ 0.740118] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T15:05:28 UTC (1786374328) test # [ 0.756967] hv_vmbus: registering driver hyperv_keyboard second # [ 0.740354] i2c_dev: i2c /dev entries driver test # [ 0.758033] ehci-pci 0000:00:0a.0: EHCI Host Controller test # [ 0.758074] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 test # [ 0.758281] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 second # [ 0.743490] sdhci: Secure Digital Host Controller Interface driver second # [ 0.743513] sdhci: Copyright(c) Pierre Ossman second # [ 0.743668] Synopsys Designware Multimedia Card Interface Driver test # [ 0.762126] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.762174] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T15:05:28 UTC (1786374328) test # [ 0.762449] i2c_dev: i2c /dev entries driver test # [ 0.765633] sdhci: Secure Digital Host Controller Interface driver test # [ 0.765660] sdhci: Copyright(c) Pierre Ossman test # [ 0.765831] Synopsys Designware Multimedia Card Interface Driver test # [ 0.766036] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.768812] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 test # [ 0.769781] hub 1-0:1.0: USB hub found test # [ 0.770158] hub 1-0:1.0: 6 ports detected second # [ 0.745328] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.771196] hid: raw HID events driver (C) Jiri Kosina second # [ 0.746540] hid: raw HID events driver (C) Jiri Kosina test # [ 0.771362] usbcore: registered new interface driver usbhid test # [ 0.771368] usbhid: USB HID core driver second # [ 0.746701] usbcore: registered new interface driver usbhid second # [ 0.746706] usbhid: USB HID core driver test # [ 0.837205] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.819270] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 test # [ 0.838814] drop_monitor: Initializing network drop monitor service second # [ 0.819520] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available test # [ 0.838997] NET: Registered PF_INET6 protocol family second # [ 0.820480] drop_monitor: Initializing network drop monitor service second # [ 0.820680] NET: Registered PF_INET6 protocol family test # [ 0.841618] Segment Routing with IPv6 second # [ 0.821214] hub 1-0:1.0: USB hub found test # [ 0.841652] In-situ OAM (IOAM) with IPv6 second # [ 0.821262] hub 1-0:1.0: 6 ports detected test # [ 0.841709] NET: Registered PF_PACKET protocol family second # [ 0.825056] Segment Routing with IPv6 second # [ 0.825087] In-situ OAM (IOAM) with IPv6 second # [ 0.825143] NET: Registered PF_PACKET protocol family second # [ 0.826343] 9pnet: Installing 9P2000 support test # [ 0.842925] 9pnet: Installing 9P2000 support test # [ 0.844655] Key type dns_resolver registered second # [ 0.828389] Key type dns_resolver registered test # [ 0.848450] registered taskstats version 1 test # [ 0.848626] Loading compiled-in X.509 certificates second # [ 0.831826] registered taskstats version 1 second # [ 0.832019] Loading compiled-in X.509 certificates test # [ 0.854288] Demotion targets for Node 0: null test # [ 0.854389] Key type .fscrypt registered test # [ 0.854394] Key type fscrypt-provisioning registered test # [ 0.854516] ima: No TPM chip found, activating TPM-bypass! test # [ 0.854530] ima: Allocated hash algorithm: sha1 second # [ 0.837876] Demotion targets for Node 0: null test # [ 0.854585] ima: No architecture policies found second # [ 0.837978] Key type .fscrypt registered second # [ 0.837984] Key type fscrypt-provisioning registered second # [ 0.838106] ima: No TPM chip found, activating TPM-bypass! second # [ 0.838126] ima: Allocated hash algorithm: sha1 test # [ 0.857996] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.838185] ima: No architecture policies found second # [ 0.841172] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.869106] clk: Disabling unused clocks test # [ 0.869137] PM: genpd: Disabling unused power domains second # [ 0.852929] clk: Disabling unused clocks test # [ 0.872705] Freeing unused kernel memory: 4736K second # [ 0.852955] PM: genpd: Disabling unused power domains test # [ 0.872810] Run /init as init process second # [ 0.856574] Freeing unused kernel memory: 4736K second # [ 0.856703] Run /init as init process second # [ 0.878472] systemd[1]: Successfully made /usr/ read-only. test # [ 0.897999] systemd[1]: Successfully made /usr/ read-only. test # [ 1.062870] usb 1-1: new high-speed USB device number 2 using ehci-pci second # [ 1.067838] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 1.212858] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 second # [ 1.213365] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) second # [ 1.218076] systemd[1]: Detected virtualization qemu. second # [ 1.218786] systemd[1]: Detected architecture arm64. second # [ 1.219634] systemd[1]: Running in initrd. test # [ 1.232515] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) second # [ 1.220956] systemd[1]: Initializing machine ID from random generator. test # [ 1.239766] systemd[1]: Detected virtualization qemu. second # [ 1.222335] systemd[1]: Hostname set to . test # [ 1.240970] systemd[1]: Detected architecture arm64. test # [ 1.242259] systemd[1]: Running in initrd. test # [ 1.244375] systemd[1]: Initializing machine ID from random generator. second # [ 1.226430] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 test # [ 1.246283] systemd[1]: Hostname set to . test # [ 1.299097] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 second # [ 1.307493] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.312020] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0 second # [ 1.317302] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.318120] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.318922] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.319616] systemd[1]: Reached target Path Units. second # [ 1.319645] systemd[1]: Reached target Slice Units. second # [ 1.319664] systemd[1]: Reached target Swaps. second # [ 1.319683] systemd[1]: Reached target Timer Units. second # [ 1.319868] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.320025] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.320155] systemd[1]: Listening on Journal Sockets. second # [ 1.320263] systemd[1]: Listening on udev Control Socket. second # [ 1.320363] systemd[1]: Listening on udev Kernel Socket. second # [ 1.320389] systemd[1]: Reached target Socket Units. second # [ 1.326404] systemd[1]: Starting Create List of Static Device Nodes... second # [ 1.331897] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 1.332572] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.352484] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.340357] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.360337] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.361096] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.361912] systemd[1]: Expecting device /dev/disk/by-label/nixos... test # [ 1.362519] systemd[1]: Reached target Path Units. test # [ 1.363164] systemd[1]: Reached target Slice Units. test # [ 1.363738] systemd[1]: Reached target Swaps. test # [ 1.364169] systemd[1]: Reached target Timer Units. test # [ 1.364835] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 1.365641] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.348052] systemd[1]: Starting Journal Service... test # [ 1.366361] systemd[1]: Listening on Journal Sockets. test # [ 1.366989] systemd[1]: Listening on udev Control Socket. test # [ 1.367620] systemd[1]: Listening on udev Kernel Socket. test # [ 1.368163] systemd[1]: Reached target Socket Units. test # [ 1.369845] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.374585] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 1.375311] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.383027] systemd[1]: Mounting Kernel Configuration File System... second # [ 1.368670] systemd[1]: Starting Load Kernel Modules... second # [ 1.369290] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 1.391101] systemd[1]: Starting Journal Service... second # [ 1.377092] systemd[1]: Starting Coldplug All udev Devices... second # [ 1.385500] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.386426] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 1.386616] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 1.386833] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.391351] systemd-journald[73]: Collecting audit messages is disabled. test # [ 1.415554] systemd[1]: Starting Load Kernel Modules... test # [ 1.415655] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 1.419050] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.404462] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 1.427202] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.434999] systemd[1]: Finished Create List of Static Device Nodes. test # [ 1.436941] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 1.439344] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 1.440578] systemd-journald[73]: Collecting audit messages is disabled. test # [ 1.441458] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.424517] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.431896] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.432326] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.455513] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.454653] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.483685] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.487225] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 1.472174] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.468809] systemd-modules-load[75]: Inserted module 'dm_mod' second # [ 1.477379] systemd[1]: Started Journal Service. second # [ 1.472338] systemd-modules-load[75]: Module 'virtio_balloon' is built in second # [ 1.475515] systemd-modules-load[75]: Module 'virtio_console' is built in test # [ 1.502075] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. second # [ 1.485975] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 second # [ 1.486212] [drm] features: -virgl +edid -resource_blob -host_visible second # [ 1.486218] [drm] features: -context_init second # [ 1.486712] [drm] number of scanouts: 1 second # [ 1.486727] [drm] number of cap sets: 0 second # [ 1.485925] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.486715] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.487432] systemd[1]: Reached target Local File Systems. second # [ 1.501754] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic second # [ 1.501777] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 second # [ 1.497626] systemd[1]: Starting Create System Files and Directories... test # [ 1.518971] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev test # [ 1.523931] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 test # [ 1.524150] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.524155] [drm] features: -context_init test # [ 1.524641] [drm] number of scanouts: 1 test # [ 1.524657] [drm] number of cap sets: 0 second # [ 1.512879] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.540346] systemd[1]: Started Journal Service. test # [ 1.531877] systemd-modules-load[74]: Inserted module 'dm_mod' test # [ 1.532847] systemd-modules-load[74]: Module 'virtio_balloon' is built in second # [ 1.521792] Console: switching to colour frame buffer device 160x50 test # [ 1.547243] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic test # [ 1.547265] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 second # [ 1.535193] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.544408] systemd-modules-load[74]: Module 'virtio_console' is built in test # [ 1.548593] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.549491] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.550228] systemd[1]: Reached target Local File Systems. second # [ 1.535502] systemd-modules-load[75]: Inserted module 'virtio_gpu' second # [ 1.536328] systemd-modules-load[75]: Module 'virtio_rng' is built in test # [ 1.557326] systemd[1]: Starting Create System Files and Directories... test # [ 1.561175] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 1.547890] systemd[1]: Finished Load Kernel Modules. test # [ 1.573034] Console: switching to colour frame buffer device 160x50 test # [ 1.585489] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 test # [ 1.585609] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 second # [ 1.564894] systemd[1]: Starting Apply Kernel Variables... test # [ 1.592892] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device second # [ 1.568804] systemd[1]: Finished Create System Files and Directories. test # [ 1.589114] systemd-modules-load[74]: Inserted module 'virtio_gpu' test # [ 1.590192] systemd-modules-load[74]: Module 'virtio_rng' is built in second # [ 1.583278] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 second # [ 1.585382] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 test # [ 1.606025] systemd[1]: Finished Load Kernel Modules. second # [ 1.595029] systemd[1]: Finished Apply Kernel Variables. second # [ 1.603108] systemd-udevd[83]: Using default interface naming scheme 'v260'. test # [ 1.624260] systemd[1]: Starting Apply Kernel Variables... test # [ 1.625416] systemd[1]: Finished Create System Files and Directories. second # [ 1.625089] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 1.651103] systemd[1]: Finished Apply Kernel Variables. test # [ 1.657533] systemd-udevd[81]: Using default interface naming scheme 'v260'. test # [ 1.678534] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.687166] systemd[1]: Starting Virtual Console Setup... test # [ 1.749141] systemd[1]: Starting Virtual Console Setup... second # [ 1.742159] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.745091] systemd[1]: Finished Virtual Console Setup. test # [ 1.818013] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.819110] systemd[1]: Finished Virtual Console Setup. second # [ 2.021632] systemd[1]: Finished Coldplug All udev Devices. second # [ 2.022238] systemd[1]: Reached target System Initialization. second # [ 2.024171] systemd[1]: Reached target Basic System. test # [ 2.077179] systemd[1]: Finished Coldplug All udev Devices. test # [ 2.077770] systemd[1]: Reached target System Initialization. test # [ 2.078269] systemd[1]: Reached target Basic System. second # [ 2.122942] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.150820] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.183368] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.209387] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.200390] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.201760] systemd[1]: Reached target Initrd Root Device. second # [ 2.204191] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.260555] systemd[1]: Found device /dev/disk/by-label/nixos. test # [ 2.261940] systemd[1]: Reached target Initrd Root Device. test # [ 2.264114] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.254087] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.268911] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.314456] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.322329] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. second # [ 2.372342] systemd[1]: Mounting /sysroot... second # [ 2.403439] EXT4-fs (vda): mounted filesystem f872a561-5625-4c36-b824-b8c12c9e6a9b r/w with ordered data mode. Quota mode: none. second # [ 2.401426] systemd[1]: Mounted /sysroot. second # [ 2.402827] systemd[1]: Reached target Initrd Root File System. second # [ 2.406951] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.423824] systemd[1]: Mounting /sysroot... second # [ 2.431494] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload. second # [ 2.436587] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.461842] EXT4-fs (vda): mounted filesystem b463b6ff-c664-4f93-b9e6-f8c293004dec r/w with ordered data mode. Quota mode: none. test # [ 2.454798] systemd[1]: Mounted /sysroot. test # [ 2.455766] systemd[1]: Reached target Initrd Root File System. second # [ 2.439985] systemd[1]: Reloading... test # [ 2.458660] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.480270] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.485607] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.489354] systemd[1]: Reloading... second # [ 2.628147] systemd[1]: Reloading finished in 189 ms. second # [ 2.637437] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace... second # [ 2.638323] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace... second # [ 2.640238] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 2.648986] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.650293] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.651398] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.662961] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 2.663673] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 2.695430] systemd[1]: Reloading finished in 208 ms. test # [ 2.704120] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace... test # [ 2.706807] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace... test # [ 2.712386] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 2.719570] systemd[1]: initrd-parse-etc.service: Deactivated successfully. test # [ 2.722669] systemd[1]: Finished Mountpoints Configured in the Real Root. test # [ 2.723325] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.740496] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 2.741514] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 3.083103] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/mtd_probe": No such file or directory second # [ 3.086976] (udev-worker)[102]: mtd0ro: /nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory second # [ 3.106316] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.107036] systemd[1]: Stopped Virtual Console Setup. second # [ 3.111020] systemd[1]: Stopping Virtual Console Setup... second # [ 3.111555] systemd[1]: Starting Virtual Console Setup... second # [ 3.125434] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.126157] systemd[1]: Stopped Virtual Console Setup. second # [ 3.128181] systemd[1]: Starting Virtual Console Setup... second # [ 3.142572] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 3.158897] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/mtd_probe": No such file or directory second # [ 3.144814] systemd[1]: Finished Virtual Console Setup. test # [ 3.162300] (udev-worker)[93]: mtd0ro: /nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory test # [ 3.181282] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.183912] systemd[1]: Stopped Virtual Console Setup. test # [ 3.184612] systemd[1]: Stopping Virtual Console Setup... test # [ 3.188090] systemd[1]: Starting Virtual Console Setup... test # [ 3.205693] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.206606] systemd[1]: Stopped Virtual Console Setup. test # [ 3.208202] systemd[1]: Starting Virtual Console Setup... test # [ 3.225431] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 3.227904] systemd[1]: Finished Virtual Console Setup. second # [ 3.372949] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 3.380260] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 3.388296] systemd[1]: Mounting /sysroot/run... second # [ 3.393760] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.420187] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 3.428092] systemd[1]: Mounting /sysroot/nix/.rw-store... test # [ 3.432202] systemd[1]: Mounting /sysroot/run... second # [ 3.419649] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.437253] systemd[1]: Mounting /sysroot/tmp/shared... second # [ 3.423465] systemd[1]: Mounted /sysroot/run. second # [ 3.432105] systemd[1]: Mounted /sysroot/nix/.ro-store. second # [ 3.433569] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.434437] systemd[1]: Mounted /sysroot/tmp/shared. second # [ 3.443236] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.460555] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.462287] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.448250] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.470165] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.475404] systemd[1]: Mounted /sysroot/nix/.ro-store. second # [ 3.462878] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.478697] systemd[1]: Mounted /sysroot/run. second # [ 3.464415] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.480073] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 3.494845] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.495849] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.497591] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 4.372370] systemd[1]: Mounting /sysroot/nix/store... test # [ 4.419238] systemd[1]: Mounting /sysroot/nix/store... second # [ 4.416907] systemd[1]: Mounted /sysroot/nix/store. second # [ 4.419098] systemd[1]: Reached target Initrd File Systems. second # [ 4.423507] systemd[1]: Starting Find NixOS closure... second # [ 4.432311] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... test # [ 4.450520] systemd[1]: Mounted /sysroot/nix/store. test # [ 4.451290] systemd[1]: Reached target Initrd File Systems. test # [ 4.452439] systemd[1]: Starting Find NixOS closure... test # [ 4.460360] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 4.462100] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 4.463145] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. second # [ 4.477819] systemd[1]: Finished Find NixOS closure. test # [ 4.494203] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 4.480279] systemd[1]: Reached target Initrd Default Target. second # [ 4.482163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 4.499338] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. test # [ 4.512711] systemd[1]: Finished Find NixOS closure. test # [ 4.514745] systemd[1]: Reached target Initrd Default Target. test # [ 4.515907] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 4.509883] systemd[1]: Stopped target Initrd Default Target. second # [ 4.511151] systemd[1]: Stopped target Basic System. second # [ 4.513153] systemd[1]: Stopped target Initrd Root Device. second # [ 4.516305] systemd[1]: Stopped target Path Units. second # [ 4.516963] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 4.517916] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. second # [ 4.518798] systemd[1]: Stopped target Slice Units. second # [ 4.519371] systemd[1]: Stopped target Socket Units. second # [ 4.519963] systemd[1]: Stopped target System Initialization. second # [ 4.524200] systemd[1]: Stopped target Swaps. test # [ 4.542488] systemd[1]: Stopped target Initrd Default Target. second # [ 4.528111] systemd[1]: Stopped target Timer Units. test # [ 4.543678] systemd[1]: Stopped target Basic System. second # [ 4.528719] systemd[1]: dbus.socket: Deactivated successfully. second # [ 4.529356] systemd[1]: Closed D-Bus System Message Bus Socket. second # [ 4.530048] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 4.545021] systemd[1]: Stopped target Initrd Root Device. second # [ 4.530834] systemd[1]: Stopped Find NixOS closure. second # [ 4.531353] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 4.548341] systemd[1]: Stopped target Path Units. test # [ 4.549079] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 4.535970] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 4.551501] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. second # [ 4.536724] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 4.537407] systemd[1]: Stopped Apply Kernel Variables. test # [ 4.552735] systemd[1]: Stopped target Slice Units. second # [ 4.538115] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 4.539034] systemd[1]: Stopped Load Kernel Modules. test # [ 4.554827] systemd[1]: Stopped target Socket Units. second # [ 4.540360] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 4.556129] systemd[1]: Stopped target System Initialization. second # [ 4.543580] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 4.560127] systemd[1]: Stopped target Swaps. test # [ 4.560672] systemd[1]: Stopped target Timer Units. test # [ 4.561308] systemd[1]: dbus.socket: Deactivated successfully. test # [ 4.561919] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 4.562524] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 4.563296] systemd[1]: Stopped Find NixOS closure. test # [ 4.563813] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 4.548826] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 4.549552] systemd[1]: Stopped Create System Files and Directories. second # [ 4.550181] systemd[1]: Stopped target Local File Systems. second # [ 4.550674] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 4.551273] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. second # [ 4.551883] systemd[1]: Stopped Coldplug All udev Devices. test # [ 4.568342] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 4.569187] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 4.570236] systemd[1]: Stopped Apply Kernel Variables. test # [ 4.571547] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 4.558157] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 4.559318] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 4.574737] systemd[1]: Stopped Load Kernel Modules. second # [ 4.559987] systemd[1]: Stopped Virtual Console Setup. test # [ 4.575942] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 4.561709] systemd[1]: initrd-cleanup.service: Deactivated successfully. test # [ 4.582137] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 4.582850] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. test # [ 4.583462] systemd[1]: Stopped Create System Files and Directories. second # [ 4.569992] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. second # [ 4.570634] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 4.571161] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 4.571727] systemd[1]: systemd-udevd.service: Consumed 1.012s CPU time over 3.056s wall clock time, 22.6M memory peak. test # [ 4.583987] systemd[1]: Stopped target Local File Systems. test # [ 4.588183] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 4.589338] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 4.590854] systemd[1]: Stopped Coldplug All udev Devices. second # [ 4.575830] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 4.591438] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 4.577258] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 4.593353] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 4.594039] systemd[1]: Stopped Virtual Console Setup. test # [ 4.594496] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 4.579376] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 4.580581] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 4.581896] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 4.583294] systemd[1]: Closed udev Control Socket. second # [ 4.584227] systemd[1]: Starting Cleanup udev Database... test # [ 4.600092] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. second # [ 4.585198] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 4.600838] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 4.588169] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 4.588743] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 4.589412] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 4.605548] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 4.591437] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 4.592155] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 4.607305] systemd[1]: systemd-udevd.service: Consumed 1.023s CPU time over 3.043s wall clock time, 22.8M memory peak. test # [ 4.610645] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 4.611348] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 4.613196] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 4.615041] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 4.615589] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 4.617006] systemd[1]: Closed udev Control Socket. test # [ 4.620324] systemd[1]: Starting Cleanup udev Database... test # [ 4.621378] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 4.622030] systemd[1]: Stopped Create Static Device Nodes in /dev. test # [ 4.622553] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. test # [ 4.624277] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 4.626046] systemd[1]: kmod-static-nodes.service: Deactivated successfully. test # [ 4.626769] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 4.619051] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. second # [ 4.624210] systemd[1]: Finished Cleanup udev Database. second # [ 4.624747] systemd[1]: Reached target Switch Root. second # [ 4.625166] systemd[1]: Starting NixOS Activation... test # [ 4.650083] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 4.651847] systemd[1]: Finished Cleanup udev Database. test # [ 4.653315] systemd[1]: Reached target Switch Root. test # [ 4.654161] systemd[1]: Starting NixOS Activation... second # [ 4.783644] initrd-nixos-activation-start[181]: booting system configuration /nix/store/k9zwrlaml4d69mrzm8vpvvll1hmwm4y7-nixos-system-second-test test # [ 4.835093] initrd-nixos-activation-start[182]: booting system configuration /nix/store/b6nxbs0rjlfiq2hy90qarypf3gl02bhm-nixos-system-test-test second # [ 4.855662] initrd-nixos-activation-start[181]: running activation script... test # [ 4.900421] initrd-nixos-activation-start[182]: running activation script... second # [ 5.280397] initrd-nixos-activation-start[204]: setting up /etc... test # [ 5.340614] initrd-nixos-activation-start[205]: setting up /etc... second # [ 5.515957] initrd-nixos-activation-start[204]: setting up age secrets... test # [ 5.601875] initrd-nixos-activation-start[205]: setting up age secrets... second # [ 5.715822] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 5.717867] systemd[1]: Finished NixOS Activation. second # [ 5.718628] systemd[1]: Starting Switch Root... second # [ 5.738089] systemd[1]: Switching root. second # [ 5.793494] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). test # [ 5.907039] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 5.908106] systemd[1]: Finished NixOS Activation. test # [ 5.909724] systemd[1]: Starting Switch Root... test # [ 5.925427] systemd[1]: Switching root. test # [ 5.984050] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). second # [ 6.509579] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) second # [ 6.516704] systemd[1]: Detected virtualization qemu. second # [ 6.518466] systemd[1]: Detected architecture arm64. second # [ 6.520805] systemd[1]: Detected first boot. second # [ 6.526525] systemd[1]: Initializing machine ID from random generator. test # [ 6.566941] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) test # [ 6.570568] systemd[1]: Detected virtualization qemu. test # [ 6.571896] systemd[1]: Detected architecture arm64. test # [ 6.573674] systemd[1]: Detected first boot. test # [ 6.577956] systemd[1]: Initializing machine ID from random generator. second # [ 6.812201] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 6.843463] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 6.993599] systemd[1]: Applying preset policy. test # [ 7.027671] systemd[1]: Applying preset policy. second # [ 7.440909] systemd[1]: Populated /etc with preset unit settings. test # [ 7.486449] systemd[1]: Populated /etc with preset unit settings. test # [ 8.161065] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 8.162076] systemd[1]: Stopped initrd-switch-root.service. test # [ 8.164885] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 8.166783] systemd[1]: Created slice Slice /system/getty. test # [ 8.168806] systemd[1]: Created slice User and Session Slice. test # [ 8.169572] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 8.172045] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 8.173138] systemd[1]: Expecting device /dev/hvc0... test # [ 8.174150] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 8.175393] systemd[1]: Reached target Local Encrypted Volumes. test # [ 8.176628] systemd[1]: Stopped target initrd-fs.target. test # [ 8.177690] systemd[1]: Stopped target initrd-root-fs.target. test # [ 8.178778] systemd[1]: Stopped target initrd-switch-root.target. test # [ 8.180448] systemd[1]: Reached target Virtual Machines and Containers. test # [ 8.182037] systemd[1]: Reached target Path Units. test # [ 8.183724] systemd[1]: Reached target Remote File Systems. test # [ 8.184817] systemd[1]: Reached target Slice Units. test # [ 8.186203] systemd[1]: Reached target Swaps. second # [ 8.172592] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 8.173761] systemd[1]: Stopped initrd-switch-root.service. test # [ 8.191320] systemd[1]: Listening on Process Core Dump Socket. second # [ 8.176985] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 8.179961] systemd[1]: Created slice Slice /system/getty. second # [ 8.180520] systemd[1]: Created slice User and Session Slice. second # [ 8.180644] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 8.180726] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 8.180796] systemd[1]: Expecting device /dev/hvc0... second # [ 8.180817] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 8.180850] systemd[1]: Reached target Local Encrypted Volumes. second # [ 8.181375] systemd[1]: Stopped target initrd-fs.target. second # [ 8.181768] systemd[1]: Stopped target initrd-root-fs.target. second # [ 8.182091] systemd[1]: Stopped target initrd-switch-root.target. second # [ 8.182434] systemd[1]: Reached target Virtual Machines and Containers. second # [ 8.182746] systemd[1]: Reached target Path Units. second # [ 8.183037] systemd[1]: Reached target Remote File Systems. second # [ 8.183322] systemd[1]: Reached target Slice Units. second # [ 8.183613] systemd[1]: Reached target Swaps. test # [ 8.212318] systemd[1]: Listening on Credential Encryption/Decryption. second # [ 8.208661] systemd[1]: Listening on Process Core Dump Socket. test # [ 8.229252] systemd[1]: Starting Journal Log Access Socket... test # [ 8.231519] systemd[1]: Listening on Journal Audit Socket. test # [ 8.233104] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 8.235741] systemd[1]: Listening on Network Management Netlink Socket. test # [ 8.238250] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 8.222870] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 8.240439] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki test # [ 8.244171] systemd[1]: Listening on udev Control Socket. second # [ 8.229244] systemd[1]: Starting Journal Log Access Socket... second # [ 8.231294] systemd[1]: Listening on Journal Audit Socket. second # [ 8.233175] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 8.252347] systemd[1]: Mounting Huge Pages File System... second # [ 8.235369] systemd[1]: Listening on Network Management Netlink Socket. test # [ 8.256797] systemd[1]: Mounting POSIX Message Queue File System... second # [ 8.237948] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 8.239904] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 8.242970] systemd[1]: Listening on udev Control Socket. test # [ 8.263083] systemd[1]: Mounting Kernel Debug File System... second # [ 8.247712] systemd[1]: Mounting Huge Pages File System... second # [ 8.252056] systemd[1]: Mounting POSIX Message Queue File System... test # [ 8.276122] systemd[1]: Mounting Kernel Trace File System... second # [ 8.260527] systemd[1]: Mounting Kernel Debug File System... test # [ 8.285359] systemd[1]: Starting Create List of Static Device Nodes... second # [ 8.268328] systemd[1]: Mounting Kernel Trace File System... second # [ 8.280073] systemd[1]: Starting Create List of Static Device Nodes... test # [ 8.303083] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 8.287947] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 8.305604] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 8.290081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 8.321168] systemd[1]: Mounting Kernel Configuration File System... second # [ 8.307057] systemd[1]: Mounting Kernel Configuration File System... test # [ 8.326099] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm test # [ 8.328591] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 8.314573] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 8.321543] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore test # [ 8.345542] systemd[1]: Starting Load Kernel Module fuse... test # [ 8.348869] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 second # [ 8.341065] systemd[1]: Starting Load Kernel Module fuse... second # [ 8.345063] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 8.411474] systemd[1]: Starting Journal Service... second # [ 8.395376] systemd[1]: Starting Journal Service... second # [ 8.409658] systemd[1]: Starting Load Kernel Modules... test # [ 8.437316] systemd[1]: Starting Load Kernel Modules... second # [ 8.435219] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 8.462565] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... second # [ 8.448158] systemd[1]: Starting Remount Root and Kernel File Systems... second # [ 8.456093] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 8.478535] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 8.492775] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 8.490327] systemd[1]: Starting Coldplug All udev Devices... test # [ 8.515343] systemd[1]: Starting Coldplug All udev Devices... test # [ 8.517751] systemd[1]: Listening on Journal Log Access Socket. test # [ 8.518379] systemd[1]: Mounted Huge Pages File System. second # [ 8.514620] systemd[1]: Listening on Journal Log Access Socket. test # [ 8.536793] fuse: init (API version 7.45) test # [ 8.538956] systemd[1]: Mounted POSIX Message Queue File System. second # [ 8.522243] fuse: init (API version 7.45) test # [ 8.541311] systemd[1]: Mounted Kernel Debug File System. second # [ 8.528328] systemd[1]: Mounted Huge Pages File System. second # [ 8.533063] systemd[1]: Mounted POSIX Message Queue File System. second # [ 8.534951] systemd[1]: Mounted Kernel Debug File System. second # [ 8.537843] systemd[1]: Mounted Kernel Trace File System. test # [ 8.557382] systemd[1]: Mounted Kernel Trace File System. test # [ 8.560212] systemd[1]: Finished Create List of Static Device Nodes. second # [ 8.550257] systemd[1]: Finished Create List of Static Device Nodes. second # [ 8.556816] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 8.577507] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 8.561013] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 8.566318] systemd[1]: Mounted Kernel Configuration File System. test # [ 8.586382] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 8.593022] systemd[1]: Mounted Kernel Configuration File System. second # [ 8.578291] systemd[1]: modprobe@fuse.service: Deactivated successfully. test # [ 8.599186] systemd[1]: modprobe@fuse.service: Deactivated successfully. second # [ 8.581764] systemd[1]: Finished Load Kernel Module fuse. test # [ 8.603103] systemd[1]: Finished Load Kernel Module fuse. second # [ 8.585570] systemd-journald[304]: Collecting audit messages is enabled. second # [ 8.589280] systemd[1]: Finished Load Kernel Modules. test # [ 8.607868] systemd-journald[325]: Collecting audit messages is enabled. test # [ 8.612479] systemd[1]: Finished Load Kernel Modules. second # [ 8.598599] systemd[1]: Mounting FUSE Control File System... test # [ 8.620963] systemd[1]: Mounting FUSE Control File System... second # [ 8.598785] systemd[1]: Queued start job for default target Multi-User System. test # [ 8.617520] systemd[1]: Queued start job for default target Multi-User System. test # [ 8.629599] systemd[1]: Starting Firewall... second # [ 8.604823] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 8.605665] systemd-modules-load[305]: Module 'atkbd' is built in second # [ 8.606319] systemd-modules-load[305]: Module 'ctr' is built in second # [ 8.606941] systemd-modules-load[305]: Module 'loop' is built in second # [ 8.620133] EXT4-fs (vda): re-mounted f872a561-5625-4c36-b824-b8c12c9e6a9b. test # [ 8.629601] systemd[1]: systemd-journald.service: Deactivated successfully. test # [ 8.630945] systemd-modules-load[326]: Module 'atkbd' is built in test # [ 8.631684] systemd-modules-load[326]: Module 'ctr' is built in second # [ 8.622841] systemd[1]: Starting Firewall... test # [ 8.645089] systemd[1]: Starting Apply Kernel Variables... test # [ 8.636332] systemd-modules-load[326]: Module 'loop' is built in test # [ 8.651631] EXT4-fs (vda): re-mounted b463b6ff-c664-4f93-b9e6-f8c293004dec. second # [ 8.635335] systemd[1]: Starting Apply Kernel Variables... test # [ 8.671287] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 8.665945] systemd-oomd[306]: No swap; memory pressure usage will be degraded second # [ 8.672393] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 8.692763] systemd-oomd[327]: No swap; memory pressure usage will be degraded second # [ 8.686692] systemd[1]: Started Journal Service. test # [ 8.707731] systemd[1]: Started Journal Service. second # [ 8.684840] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 8.713138] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 8.713960] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. second # [ 8.701407] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 8.786583] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 8.789816] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 8.809266] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 8.800450] systemd[1]: Starting Load/Save OS Random Seed... second # [ 8.801093] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 8.816901] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 8.823135] systemd[1]: Starting Load/Save OS Random Seed... test # [ 8.824078] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 8.820924] systemd[1]: Mounted FUSE Control File System. test # [ 8.838361] systemd[1]: Mounted FUSE Control File System. second # [ 8.831939] systemd[1]: Finished Apply Kernel Variables. second # [ 8.838792] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 8.856762] systemd[1]: Finished Apply Kernel Variables. test # [ 8.860202] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 8.862710] systemd[1]: Starting Network Name Resolution... test # [ 8.882483] systemd[1]: Starting Network Name Resolution... test # [ 8.912659] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 8.895556] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 8.932450] systemd-journald[304]: Received client request to flush runtime journal. test # [ 8.973571] systemd-journald[325]: Received client request to flush runtime journal. test # [ 9.301358] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 9.305983] systemd[1]: Reached target Preparation for Local File Systems. test # [ 9.312689] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 9.321095] systemd-resolved[357]: Positive Trust Anchors: test # [ 9.322375] systemd-resolved[357]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 9.325384] systemd-resolved[357]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 9.321278] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 9.322780] systemd[1]: Reached target Preparation for Local File Systems. test # [ 9.336433] systemd-resolved[357]: 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 # [ 9.331027] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 9.344354] systemd-resolved[335]: Positive Trust Anchors: second # [ 9.345395] systemd-resolved[335]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d second # [ 9.348666] systemd-resolved[335]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 9.354846] systemd-resolved[335]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test test # [ 9.375559] systemd[1]: Mounting /run/wrappers... test # [ 9.376299] systemd[1]: Finished Load/Save OS Random Seed. test # [ 9.380329] systemd[1]: Reached target First Boot Complete. test # [ 9.385063] systemd[1]: Mounted /run/wrappers. test # [ 9.388570] systemd[1]: Reached target Local File Systems. second # [ 9.379797] systemd-udevd[345]: Using default interface naming scheme 'v260'. second # [ 9.380726] systemd[1]: Mounting /run/wrappers... second # [ 9.381290] systemd[1]: Finished Load/Save OS Random Seed. second # [ 9.381855] systemd[1]: Reached target First Boot Complete. second # [ 9.382347] systemd[1]: Mounted /run/wrappers. second # [ 9.382769] systemd[1]: Reached target Local File Systems. second # [ 9.383246] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 9.398792] systemd-udevd[368]: Using default interface naming scheme 'v260'. second # [ 9.383800] systemd[1]: Starting register-nix-paths.service... test # [ 9.406251] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 9.412606] systemd[1]: Starting register-nix-paths.service... test # [ 9.420572] systemd[1]: Starting Create SUID/SGID Wrappers... test # [ 9.421289] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 9.423678] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 9.403568] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 9.412641] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 9.413569] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 9.414162] systemd-resolved[335]: Using system hostname 'second'. second # [ 9.414695] systemd[1]: Finished Flush Journal to Persistent Storage. second # [ 9.415232] systemd[1]: Started Network Name Resolution. second # [ 9.415690] systemd[1]: Starting Create System Files and Directories... test # [ 9.436195] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 9.441706] systemd[1]: Starting Create System Files and Directories... test # [ 9.470440] systemd-resolved[357]: Using system hostname 'test'. test # [ 9.488515] systemd[1]: Started Network Name Resolution. second # [ 9.514037] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. second # [ 9.519417] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 9.542267] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 9.545615] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 9.587769] systemd[1]: Finished Create System Files and Directories. test # [ 9.598683] systemd[1]: Starting Rebuild Journal Catalog... second # [ 9.587605] systemd[1]: Finished Create System Files and Directories. test # [ 9.603131] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 9.598308] systemd[1]: Starting Rebuild Journal Catalog... second # [ 9.600689] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 9.609207] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 9.654066] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 9.741981] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 9.777535] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 9.815827] systemd[1]: Finished Rebuild Journal Catalog. test # [ 9.827540] systemd[1]: Starting Update is Completed... second # [ 9.831658] systemd[1]: Finished Rebuild Journal Catalog. second # [ 9.843739] systemd[1]: Starting Update is Completed... test # [ 9.930759] systemd[1]: Finished Update is Completed. second # [ 9.932034] systemd[1]: Finished Update is Completed. second # [ 10.037947] systemd[1]: Finished Coldplug All udev Devices. test # [ 10.143076] systemd[1]: Finished Coldplug All udev Devices. second # [ 10.230256] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 10.294189] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 10.455953] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 10.458156] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 10.496269] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 10.549157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 10.535626] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 10.551257] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 10.572380] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 10.621221] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 10.638454] systemd[1]: Finished Firewall. second # [ 10.641045] systemd[1]: Reached target Preparation for Network. second # [ 10.649236] systemd[1]: Starting Network Management... test # [ 10.771801] systemd[1]: Finished Firewall. test # [ 10.773649] systemd[1]: Reached target Preparation for Network. test # [ 10.780233] systemd[1]: Starting Network Management... second # [ 10.824816] (udev-worker)[411]: Network interface NamePolicy= disabled on kernel command line. second # [ 10.828470] (udev-worker)[414]: Network interface NamePolicy= disabled on kernel command line. test # [ 10.905429] systemd[1]: Finished register-nix-paths.service. test # [ 10.907237] systemd[1]: Reached target System Initialization. second # [ 10.895550] systemd[1]: Finished register-nix-paths.service. test # [ 10.910732] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 10.896354] systemd[1]: Reached target System Initialization. second # [ 10.896894] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 10.897497] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 10.912848] systemd[1]: Started Daily Cleanup of Temporary Directories. second # [ 10.898082] systemd[1]: Reached target Timer Units. second # [ 10.898518] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 10.899064] systemd[1]: Listening on Nix Daemon Socket. test # [ 10.915342] systemd[1]: Reached target Timer Units. test # [ 10.917522] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 10.919600] systemd[1]: Listening on Nix Daemon Socket. test # [ 10.925213] systemd[1]: Listening on Authorization Manager Agent Helper. test # [ 10.925816] systemd[1]: Listening on Hostname Service Socket. test # [ 10.926328] systemd[1]: Reached target Socket Units. second # [ 10.914068] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 10.914814] systemd[1]: Listening on Hostname Service Socket. test # [ 10.931684] systemd[1]: Starting D-Bus System Message Bus... second # [ 10.926555] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 10.921691] systemd[1]: Reached target Socket Units. second # [ 10.925161] systemd[1]: Starting D-Bus System Message Bus... test # [ 10.955529] (udev-worker)[427]: Network interface NamePolicy= disabled on kernel command line. test # [ 10.974900] (udev-worker)[431]: Network interface NamePolicy= disabled on kernel command line. second # [ 10.969352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 10.969879] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' test # [ 11.031823] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 11.024501] systemd-networkd[536]: /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 # [ 11.028647] systemd-networkd[536]: /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 # [ 11.036334] systemd-networkd[536]: lo: Link UP second # [ 11.036983] systemd-networkd[536]: lo: Gained carrier test # [ 11.066390] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 11.068206] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 11.043468] systemd[1]: Started Network Management. second # [ 11.049316] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 11.055409] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 11.065130] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 11.068499] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 11.071709] systemd-networkd[536]: eth1: Link UP second # [ 11.074927] systemd-networkd[536]: eth1: Gained carrier second # [ 11.077294] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 11.099453] mousedev: PS/2 mouse device common for all mice test # [ 11.110151] systemd-networkd[557]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. test # [ 11.113251] systemd-networkd[557]: /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 # [ 11.118888] systemd-networkd[557]: lo: Link UP test # [ 11.121132] systemd-networkd[557]: lo: Gained carrier test # [ 11.124314] systemd[1]: Started Network Management. test # [ 11.128984] dbus-broker-launch[561]: Looking up NSS user entry for 'systemd-timesync'... second # [ 11.119477] dbus-broker-launch[542]: Looking up NSS user entry for 'systemd-timesync'... test # [ 11.131593] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 11.120977] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 11.134653] dbus-broker-launch[542]: NSS returned no entry for 'systemd-timesync' second # [ 11.136406] dbus-broker-launch[542]: Invalid user-name in /nix/store/zd0mj0i4bnn5z4jx7yddr843bdd3khb0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 11.152603] dbus-broker-launch[561]: NSS returned no entry for 'systemd-timesync' test # [ 11.154492] dbus-broker-launch[561]: Invalid user-name in /nix/store/zd0mj0i4bnn5z4jx7yddr843bdd3khb0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 11.161472] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 11.169080] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 11.174791] systemd-networkd[557]: eth1: Link UP test # [ 11.177592] systemd-networkd[557]: eth1: Gained carrier test # [ 11.179794] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 11.183172] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' second # [ 11.188530] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' test # [ 11.206430] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 11.193459] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/c3af85xim0zyds0g3slc36c1bnj8n0vd-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 11.201931] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/cwwqsy7vfh399nrfcwlcn4kk8k6vgyw2-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 11.206146] systemd[1]: Started D-Bus System Message Bus. test # [ 11.224361] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' test # [ 11.228263] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' test # [ 11.242424] mousedev: PS/2 mouse device common for all mice test # [ 11.234133] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/c3af85xim0zyds0g3slc36c1bnj8n0vd-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' test # [ 11.239233] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/cwwqsy7vfh399nrfcwlcn4kk8k6vgyw2-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' test # [ 11.246533] systemd[1]: Started D-Bus System Message Bus. second # [ 11.244279] dbus-broker-launch[542]: Ready second # [ 11.250181] systemd[1]: Reached target Basic System. second # [ 11.255074] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 11.258620] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 11.271678] systemd[1]: Starting Network Manager... test # [ 11.288658] dbus-broker-launch[561]: Ready test # [ 11.293365] systemd[1]: Reached target Basic System. test # [ 11.298748] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 11.285637] systemd[1]: Started backdoor.service. test # [ 11.304067] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 11.308770] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 11.325218] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 11.319879] systemd[1]: Starting Import lastlog data into lastlog2 database... second # [ 11.338316] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 11.359356] systemd[1]: Starting Network Manager... second # [ 11.359042] systemd[1]: Starting Post-Boot Actions... test # [ 11.388083] systemd[1]: Started backdoor.service. test # [ 11.402263] systemd[1]: Starting Import lastlog data into lastlog2 database... second # [ 11.392651] systemd[1]: Started Reset console on configuration changes. second # [ 11.403876] f6xzm5kixfjy7jjnjkrzp2isxv790ipf-wifi-secrets[552]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 11.454262] f6xzm5kixfjy7jjnjkrzp2isxv790ipf-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 11.461013] systemd[1]: Starting Name Service Cache Daemon (nsncd)... second # connecting to host... test # [ 11.469395] 5nnma1imjqgdsrcmbqy7gg7cjykpb6ji-wifi-secrets[575]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 11.461552] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. test # [ 11.477695] f2pdrgggm4v4cwnq3ayrw6q28aaf8j7z-wifi-secrets[574]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 11.467001] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 11.499287] systemd[1]: Starting Post-Boot Actions... test # [ 11.533623] systemd[1]: Started Reset console on configuration changes. test # [ 11.555282] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 11.561008] systemd[1]: Finished Post-Boot Actions. test # [ 11.588624] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 11.594044] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. test # [ 11.599353] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 11.604613] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 11.612597] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 11.600352] systemd[1]: Started Name Service Cache Daemon (nsncd). test # connecting to host... second # [ 11.603569] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 11.607277] systemd[1]: Reached target Host and Network Name Lookups. second # [ 11.611627] nsncd[556]: Aug 10 15:05:39.371 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" second # [ 11.615698] systemd[1]: Reached target User and Group Name Lookups. second # [ 11.620112] systemd[1]: Starting User Login Management... second # [ 11.647064] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 11.680414] systemd[1]: Finished Post-Boot Actions. test: Guest shell says: b'Spawning backdoor root shell...\n' test: connected to guest root shell test: (connecting took 11.96 seconds) test: (finished: waiting for the VM to finish booting, in 11.96 seconds) test # [ 11.731325] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 11.738151] systemd[1]: Started Name Service Cache Daemon (nsncd). test # [ 11.739069] nsncd[580]: Aug 10 15:05:39.485 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 11.747968] systemd[1]: Reached target Host and Network Name Lookups. test # [ 11.752485] systemd[1]: Reached target User and Group Name Lookups. test # [ 11.757798] systemd[1]: Starting User Login Management... second # [ 11.751793] systemd[1]: Starting Hostname Service... test # [ 11.782199] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 11.765957] systemd-logind[586]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 11.771579] systemd-logind[586]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 11.775147] systemd-logind[586]: New seat seat0. second # [ 11.775594] systemd[1]: Started User Login Management. second # [ 11.786866] systemd[1]: Starting linger-users.service... second # [ 11.859763] systemd[1]: linger-users.service: Deactivated successfully. second # [ 11.860980] systemd[1]: Finished linger-users.service. test # [ 11.895384] systemd[1]: Starting Hostname Service... second # [ 11.889740] systemd[1]: Started Hostname Service. test # [ 11.922245] systemd-logind[623]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 11.927201] systemd-logind[623]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 11.931047] systemd-logind[623]: New seat seat0. test # [ 11.932935] systemd[1]: Started User Login Management. test # [ 11.936624] systemd[1]: Starting linger-users.service... second # [ 11.986586] systemd-networkd[536]: eth0: Link UP second # [ 11.987090] systemd-networkd[536]: eth0: Gained carrier test # [ 12.012905] systemd[1]: linger-users.service: Deactivated successfully. test # [ 12.014805] systemd[1]: Finished linger-users.service. second # [ 12.008119] systemd[1]: Started Network Manager. second # [ 12.010675] systemd[1]: Reached target Network. test # [ 12.029265] systemd[1]: Started Hostname Service. second # [ 12.015050] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 12.021523] systemd[1]: Starting Permit User Sessions... test # [ 12.119572] systemd-networkd[557]: eth0: Link UP test # [ 12.120567] systemd-networkd[557]: eth0: Gained carrier test # [ 12.143590] systemd[1]: Started Network Manager. test # [ 12.144293] systemd[1]: Reached target Network. test # [ 12.150389] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... test # [ 12.157807] systemd[1]: Starting Permit User Sessions... second # [ 12.178641] systemd[1]: Finished Permit User Sessions. second # [ 12.195176] systemd[1]: Started Getty on tty1. second # [ 12.195656] systemd[1]: Reached target Login Prompts. second # [ 12.253197] systemd-resolved[335]: eth0: Bus client set default route setting: yes second # [ 12.257161] systemd-resolved[335]: eth0: Bus client set DNS server list to: 10.0.2.3 test # [ 12.296747] systemd[1]: Finished Permit User Sessions. second # [ 12.288156] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. second # [ 12.288899] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 12.310263] systemd[1]: Started Getty on tty1. test # [ 12.310877] systemd[1]: Reached target Login Prompts. test # [ 12.346379] NetworkManager[576]: [1786374340.0934] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty test # [ 12.365117] systemd-resolved[357]: eth0: Bus client set default route setting: yes test # [ 12.366355] systemd-resolved[357]: eth0: Bus client set DNS server list to: 10.0.2.3 test # [ 12.402947] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. test # [ 12.403737] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. second # [ 12.392507] systemd[1]: Reached target Multi-User System. second # [ 12.394726] systemd[1]: Startup finished in 856ms (kernel) + 5.156s (initrd) + 6.380s (userspace) = 12.393s. test # [ 12.537699] systemd[1]: Reached target Multi-User System. test # [ 12.539801] systemd[1]: Startup finished in 869ms (kernel) + 5.203s (initrd) + 6.466s (userspace) = 12.539s. second # [ 12.576827] systemd-networkd[536]: eth1: Gained IPv6LL test # [ 12.704597] systemd-networkd[557]: eth1: Gained IPv6LL second # [ 12.798931] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 test: (finished: waiting for unit NetworkManager.service, in 13.21 seconds) test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds) test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds) test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection second # [ 13.045278] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds) (finished: run the VM test script, in 13.86 seconds) second # [ 13.090940] systemd[1]: Starting Virtual Console Setup... test # [ 13.133421] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 second # [ 13.114445] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 13.116261] systemd[1]: Stopped Virtual Console Setup. second # [ 13.122190] systemd[1]: Starting Virtual Console Setup... second # [ 13.122882] systemd-logind[586]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) test script finished in 13.98s cleanup kill QemuMachine (pid 14) second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13) kill QemuMachine (pid 15) test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13) (finished: cleanup, in 0.32 seconds)