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 second # Disk image does not exist, creating the virtualisation disk image... second # Formatting '/build/vm-state-second/tmp.12y1eoh0JD', fmt=raw size=1073741824 second # mke2fs 1.47.4 (6-Mar-2025) second # Discarding device blocks: 0/262144 done second # Creating filesystem with 262144 4k blocks and 65536 inodes second # Filesystem UUID: 6903694c-da93-4331-adf0-eac2de76668b second # Superblock backups stored on blocks: second # 32768, 98304, 163840, 229376 second # second # Allocating group tables: 0/8 done second # Writing inode tables: 0/8 done second # Creating journal (8192 blocks): done second # Writing superblocks and filesystem accounting information: 0/8 done second # second # Virtualisation disk image created. second: QEMU running (pid 15) test # Disk image does not exist, creating the virtualisation disk image... test: QEMU running (pid 14) test # Formatting '/build/vm-state-test/tmp.r14ZDwrWda', fmt=raw size=1073741824 test # mke2fs 1.47.4 (6-Mar-2025) test # Discarding device blocks: 0/262144 done test # Creating filesystem with 262144 4k blocks and 65536 inodes test # Filesystem UUID: c20ff810-82c8-40ee-9446-981f605f127a test # Superblock backups stored on blocks: test # 32768, 98304, 163840, 229376 test # test # Allocating group tables: 0/8 done test # Writing inode tables: 0/8 done test # Creating journal (8192 blocks): done test # Writing superblocks and filesystem accounting information: 0/8 done test # test # Virtualisation disk image created. (finished: start all VMs, in 0.46 seconds) test: waiting for unit NetworkManager.service test: waiting for the VM to finish booting second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 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 second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] second # [ 0.000000] Zone ranges: second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] DMA32 empty second # [ 0.000000] Normal empty second # [ 0.000000] Device empty second # [ 0.000000] Movable zone start for each node second # [ 0.000000] Early memory node ranges second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs second # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 second # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] CPU features: detected: Spectre-BHB second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) second # [ 0.000000] CPU features: detected: ARM erratum 1418040 second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing second # [ 0.000000] alternatives: applying boot alternatives 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/j1v4crk8h06q6z20jhdz651agnyqifwh-nixos-system-second-test/init regInfo=/nix/store/i8jqfnw9nrhs6p579m8g58arlhgk5mws-closure-info/registration console=ttyAMA0,115200n8 console=tty0 second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/i8jqfnw9nrhs6p579m8g58arlhgk5mws-closure-info/registration", will be passed to user space. second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB second # [ 0.000000] software IO TLB: area num 1. second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.000000] Policy zone: DMA second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages second # [ 0.000000] ftrace: allocated 294 pages with 4 groups second # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. second # [ 0.000000] Trampoline variant of Tasks RCU enabled. second # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 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] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.000000] GICv3: 0 Extended SPIs implemented second # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 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 second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns second # [ 0.000054] arm-pv: using stolen time PV second # [ 0.000355] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000628] Console: colour dummy device 80x25 second # [ 0.000640] printk: legacy console [tty0] enabled second # [ 0.000865] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.000870] pid_max: default: 32768 minimum: 301 second # [ 0.000940] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.001135] landlock: Up and running. second # [ 0.001138] Yama: becoming mindful. second # [ 0.001511] LSM support for eBPF active second # [ 0.001683] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] second # [ 0.001704] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.004306] rcu: Hierarchical SRCU implementation. test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026 test # [ 0.000000] KASLR enabled second # [ 0.004312] rcu: Max phase no-delay instances is 1000. test # [ 0.000000] random: crng init done second # [ 0.005287] fsl-mc MSI: its@8080000 domain created test # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.005433] EFI services will not be available. test # [ 0.000000] efi: UEFI not found. second # [ 0.005525] smp: Bringing up secondary CPUs ... test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT second # [ 0.005559] smp: Brought up 1 node, 1 CPU second # [ 0.005561] SMP: Total of 1 processors activated. test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.005563] CPU: All CPU(s) started at EL1 test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] second # [ 0.005575] CPU features: detected: 32-bit EL0 Support test # [ 0.000000] Zone ranges: test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.005578] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.000000] DMA32 empty test # [ 0.000000] Normal empty second # [ 0.005581] CPU features: detected: Common not Private translations test # [ 0.000000] Device empty second # [ 0.005583] CPU features: detected: CRC32 instructions test # [ 0.000000] Movable zone start for each node test # [ 0.000000] Early memory node ranges second # [ 0.005585] CPU features: detected: Data cache clean to Point of Persistence test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.005588] CPU features: detected: Enhanced Virtualization Traps test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.005598] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.005600] CPU features: detected: LSE atomic instructions test # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.005602] CPU features: detected: Privileged Access Never test # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.005604] CPU features: detected: PMUv3 test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] psci: Trusted OS migration not required second # [ 0.005605] CPU features: detected: RAS Extension Support test # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.005608] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.005629] alternatives: applying system-wide alternatives test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296 second # [ 0.007001] CPU features: detected: Hardware dirty bit management on CPU0 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 second # [ 0.007224] Memory: 893584K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113428K reserved, 32768K cma-reserved) test # [ 0.000000] CPU features: detected: Spectre-BHB second # [ 0.007679] devtmpfs: initialized test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.009132] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] CPU features: detected: ARM erratum 1418040 second # [ 0.009155] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.009290] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.009293] 0 pages in range for non-PLT usage test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing second # [ 0.009294] 508336 pages in range for PLT usage test # [ 0.000000] alternatives: applying boot alternatives second # [ 0.009426] pinctrl core: initialized pinctrl subsystem second # [ 0.010175] DMI not present or invalid. second # [ 0.013458] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.015756] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.015903] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations 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/cxqrz5vkhg22v52q8wapgmk904vn4zwv-nixos-system-test-test/init regInfo=/nix/store/4hxx3fxsz64a5cjv12j7rfp6p3fgq1l9-closure-info/registration console=ttyAMA0,115200n8 console=tty0 second # [ 0.016073] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations second # [ 0.016090] audit: initializing netlink subsys (disabled) second # [ 0.016740] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4hxx3fxsz64a5cjv12j7rfp6p3fgq1l9-closure-info/registration", will be passed to user space. second # [ 0.016742] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes second # [ 0.016745] thermal_sys: Registered thermal governor 'step_wise' test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) second # [ 0.016747] thermal_sys: Registered thermal governor 'user_space' second # [ 0.016749] thermal_sys: Registered thermal governor 'power_allocator' 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 second # [ 0.016778] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 test # [ 0.000000] software IO TLB: area num 1. second # [ 0.016793] cpuidle: using governor ladder second # [ 0.016797] cpuidle: using governor menu test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.016919] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. test # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.016940] ASID allocator initialised with 32768 entries test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.017574] Serial: AMBA PL011 UART driver test # [ 0.000000] Policy zone: DMA test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.020606] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.020739] printk: console [ttyAMA0] enabled test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 test # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages test # [ 0.000000] ftrace: allocated 294 pages with 4 groups test # [ 0.000000] rcu: Hierarchical RCU implementation. test # [ 0.000000] rcu: RCU event tracing is enabled. test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. test # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] Rude variant of Tasks RCU enabled. test # [ 0.000000] Tracing variant of Tasks RCU enabled. test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 test # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] Root IRQ handler: gic_handle_irq test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.097796] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] second # [ 0.097813] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.097818] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.097824] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) second # [ 0.097827] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000 second # [ 0.097830] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000 second # [ 0.097833] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.097836] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.102906] fbcon: Taking over console second # [ 0.102924] ACPI: Interpreter disabled. test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.104179] iommu: Default domain type: Translated test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.000052] arm-pv: using stolen time PV second # [ 0.104189] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.105399] SCSI subsystem initialized test # [ 0.000345] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) test # [ 0.000582] Console: colour dummy device 80x25 test # [ 0.000593] printk: legacy console [tty0] enabled second # [ 0.106009] usbcore: registered new interface driver usbfs second # [ 0.106041] usbcore: registered new interface driver hub test # [ 0.000808] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.106051] usbcore: registered new device driver usb test # [ 0.000813] pid_max: default: 32768 minimum: 301 second # [ 0.106251] pps_core: LinuxPPS API ver. 1 registered test # [ 0.000897] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.001018] landlock: Up and running. second # [ 0.106257] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.001021] Yama: becoming mindful. second # [ 0.106263] PTP clock support registered test # [ 0.001353] LSM support for eBPF active second # [ 0.106291] EDAC MC: Ver: 3.0.0 test # [ 0.001510] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.109303] scmi_core: SCMI protocol bus registered test # [ 0.001541] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.003928] rcu: Hierarchical SRCU implementation. second # [ 0.110072] FPGA manager framework test # [ 0.003934] rcu: Max phase no-delay instances is 1000. test # [ 0.004946] fsl-mc MSI: its@8080000 domain created test # [ 0.005094] EFI services will not be available. test # [ 0.005189] smp: Bringing up secondary CPUs ... test # [ 0.005220] smp: Brought up 1 node, 1 CPU test # [ 0.005223] SMP: Total of 1 processors activated. test # [ 0.005225] CPU: All CPU(s) started at EL1 test # [ 0.005236] CPU features: detected: 32-bit EL0 Support test # [ 0.005240] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.005242] CPU features: detected: Common not Private translations test # [ 0.005245] CPU features: detected: CRC32 instructions test # [ 0.005246] CPU features: detected: Data cache clean to Point of Persistence test # [ 0.005249] CPU features: detected: Enhanced Virtualization Traps test # [ 0.005259] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.005261] CPU features: detected: LSE atomic instructions test # [ 0.005263] CPU features: detected: Privileged Access Never test # [ 0.005264] CPU features: detected: PMUv3 test # [ 0.005266] CPU features: detected: RAS Extension Support second # [ 0.117954] vgaarb: loaded test # [ 0.005269] CPU features: detected: Speculative Store Bypassing Safe (SSBS) second # [ 0.118429] clocksource: Switched to clocksource arch_sys_counter test # [ 0.005289] alternatives: applying system-wide alternatives test # [ 0.006669] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.006845] Memory: 893912K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113420K reserved, 32768K cma-reserved) test # [ 0.007246] devtmpfs: initialized test # [ 0.008691] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.008712] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). second # [ 0.122175] VFS: Disk quotas dquot_6.6.0 test # [ 0.008833] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL test # [ 0.008836] 0 pages in range for non-PLT usage second # [ 0.122207] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.008837] 508336 pages in range for PLT usage test # [ 0.008963] pinctrl core: initialized pinctrl subsystem test # [ 0.009713] DMI not present or invalid. test # [ 0.012790] NET: Registered PF_NETLINK/PF_ROUTE protocol family second # [ 0.124579] netfs: FS-Cache loaded test # [ 0.015028] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.124697] pnp: PnP ACPI: disabled test # [ 0.015181] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.015352] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.015368] audit: initializing netlink subsys (disabled) test # [ 0.015925] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.015927] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.015930] thermal_sys: Registered thermal governor 'step_wise' test # [ 0.015933] thermal_sys: Registered thermal governor 'user_space' test # [ 0.015934] thermal_sys: Registered thermal governor 'power_allocator' test # [ 0.015962] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 test # [ 0.015976] cpuidle: using governor ladder test # [ 0.015980] cpuidle: using governor menu second # [ 0.127053] NET: Registered PF_INET protocol family test # [ 0.016115] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. test # [ 0.016135] ASID allocator initialised with 32768 entries second # [ 0.127233] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.016767] Serial: AMBA PL011 UART driver test # [ 0.019816] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 test # [ 0.019975] printk: console [ttyAMA0] enabled test # [ 0.094982] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.094997] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.095002] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.095008] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.095011] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.095014] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.095017] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.095020] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.100148] fbcon: Taking over console test # [ 0.100167] ACPI: Interpreter disabled. test # [ 0.101424] iommu: Default domain type: Translated test # [ 0.101434] iommu: DMA domain TLB invalidation policy: strict mode test # [ 0.102614] SCSI subsystem initialized test # [ 0.103222] usbcore: registered new interface driver usbfs test # [ 0.103256] usbcore: registered new interface driver hub test # [ 0.103266] usbcore: registered new device driver usb test # [ 0.103461] pps_core: LinuxPPS API ver. 1 registered test # [ 0.103467] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.103473] PTP clock support registered test # [ 0.103503] EDAC MC: Ver: 3.0.0 test # [ 0.111139] scmi_core: SCMI protocol bus registered test # [ 0.111900] FPGA manager framework test # [ 0.112942] vgaarb: loaded test # [ 0.113370] clocksource: Switched to clocksource arch_sys_counter second # [ 0.151074] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.151105] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.151119] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.151158] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.151226] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.151327] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.151396] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151445] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.151578] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.151619] NET: Registered PF_XDP protocol family second # [ 0.151640] PCI: CLS 0 bytes, default 64 second # [ 0.151847] Trying to unpack rootfs image as initramfs... test # [ 0.123082] VFS: Disk quotas dquot_6.6.0 test # [ 0.123122] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.125476] netfs: FS-Cache loaded test # [ 0.125623] pnp: PnP ACPI: disabled second # [ 0.164807] kvm [1]: HYP mode not available test # [ 0.128004] NET: Registered PF_INET protocol family test # [ 0.128196] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.151890] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.151922] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) test # [ 0.151936] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.151972] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.152040] TCP: Hash tables configured (established 8192 bind 8192) test # [ 0.152131] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.152160] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.152214] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.152354] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.152441] NET: Registered PF_XDP protocol family test # [ 0.152464] PCI: CLS 0 bytes, default 64 test # [ 0.152677] Trying to unpack rootfs image as initramfs... test # [ 0.163867] kvm [1]: HYP mode not available second # [ 0.230678] Initialise system trusted keyrings second # [ 0.238491] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.239523] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.240054] 9p: Installing v9fs 9p2000 file system support second # [ 0.259419] Key type asymmetric registered second # [ 0.259451] Asymmetric key parser 'x509' registered second # [ 0.259524] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.260958] io scheduler mq-deadline registered second # [ 0.260969] io scheduler kyber registered second # [ 0.266571] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.267415] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.267742] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.267764] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.267779] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.267785] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.267814] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.267838] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.267918] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.267936] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.267942] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.267946] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.267949] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.268020] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.268452] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.268567] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.268578] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.268598] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.268609] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.268894] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.237776] Initialise system trusted keyrings second # [ 0.269019] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.269029] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.238291] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.269050] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.239287] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.269329] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.269440] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] second # [ 0.269451] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.269471] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.239796] 9p: Installing v9fs 9p2000 file system support second # [ 0.269747] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.269858] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] second # [ 0.269868] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.269888] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.270170] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.270281] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] second # [ 0.270292] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.270311] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.294692] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint second # [ 0.294823] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] second # [ 0.294835] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.294855] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.295139] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.295252] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] second # [ 0.295262] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.295282] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.259114] Key type asymmetric registered second # [ 0.295297] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.259140] Asymmetric key parser 'x509' registered second # [ 0.295580] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.259203] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.295694] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.295713] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.295984] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.296098] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.296117] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.296363] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.296473] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.296634] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.296849] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.296863] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.265432] io scheduler mq-deadline registered test # [ 0.265449] io scheduler kyber registered second # [ 0.296884] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.297188] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.297302] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.297313] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.297332] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.297819] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.268365] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.297831] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.269134] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.297836] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned second # [ 0.297868] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned second # [ 0.297900] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned second # [ 0.297934] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned second # [ 0.297964] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned second # [ 0.297995] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.269959] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.298025] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.269984] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.298055] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.270015] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.298085] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.270021] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.298114] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.270047] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.298192] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned second # [ 0.298222] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.270068] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.298236] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.270149] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.298250] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.270167] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.298264] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.270172] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.298278] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned test # [ 0.270176] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.298292] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned test # [ 0.270179] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.298307] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned test # [ 0.270247] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.298321] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned test # [ 0.270687] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.298335] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned test # [ 0.270804] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.298350] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned test # [ 0.270815] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.298364] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned test # [ 0.270836] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.298378] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned test # [ 0.270847] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.298392] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned test # [ 0.271133] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.298406] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.271247] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.298420] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned test # [ 0.271258] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.271277] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.271556] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.271669] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] test # [ 0.271679] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.271699] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.271974] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.272086] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.272097] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.272116] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.272395] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.272507] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] test # [ 0.272517] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.272538] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.272821] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.272933] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] test # [ 0.272944] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.334494] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned test # [ 0.272964] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.334529] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned test # [ 0.273250] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.334545] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned test # [ 0.273364] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] second # [ 0.334560] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned second # [ 0.334576] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned second # [ 0.334591] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned second # [ 0.334619] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.334625] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.334629] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.335365] pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.346248] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.305441] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.305471] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.305487] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.305924] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.306046] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.306065] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.306352] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint test # [ 0.306466] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.306486] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.355606] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.306733] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.358234] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.306842] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] test # [ 0.307006] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.307235] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] test # [ 0.307248] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.307268] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.307551] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.307663] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.307673] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.307693] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.308098] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.308109] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.308114] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.308147] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.308179] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.308210] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.308239] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.308269] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.308299] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.308330] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.308360] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned second # [ 0.367477] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.308389] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned second # [ 0.369587] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) test # [ 0.308451] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.308481] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.308496] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.308510] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.308524] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.308538] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.372948] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) test # [ 0.308553] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned test # [ 0.308567] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned test # [ 0.308581] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned test # [ 0.308595] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned test # [ 0.308609] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned test # [ 0.308623] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned test # [ 0.308637] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned test # [ 0.308651] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned test # [ 0.308665] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.308679] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned test # [ 0.308693] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned test # [ 0.308712] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned test # [ 0.308725] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned test # [ 0.308739] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned test # [ 0.308753] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned test # [ 0.308767] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned second # [ 0.378852] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) test # [ 0.308785] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.381607] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.308791] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] test # [ 0.308795] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] test # [ 0.309488] pci 0000:00:0a.0: enabling device (0000 -> 0002) test # [ 0.346635] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.348646] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) second # [ 0.391521] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.394110] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) test # [ 0.359322] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.361069] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.403613] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) second # [ 0.415193] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled test # [ 0.371401] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) test # [ 0.373247] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) second # [ 0.416729] msm_serial: driver initialized second # [ 0.416813] SuperH (H)SCI(F) driver initialized second # [ 0.416847] STM32 USART driver initialized test # [ 0.382885] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) test # [ 0.386247] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.387906] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.442620] loop: module loaded second # [ 0.442752] virtio_blk virtio5: 1/0/0 default/read/poll queues second # [ 0.443412] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) test # [ 0.397532] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) test # [ 0.399682] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) second # [ 0.446816] megasas: 07.734.00.00-rc1 second # [ 0.447291] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.411783] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled test # [ 0.413320] msm_serial: driver initialized test # [ 0.413810] SuperH (H)SCI(F) driver initialized test # [ 0.413855] STM32 USART driver initialized second # [ 0.465833] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.465911] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.468935] Using buffer write method second # [ 0.469015] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] second # [ 0.474451] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.474471] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.477594] Using buffer write method second # [ 0.477630] Concatenating MTD devices: second # [ 0.477634] (0): "0.flash" second # [ 0.477637] (1): "0.flash" second # [ 0.477640] into device "0.flash" test # [ 0.438130] loop: module loaded test # [ 0.438269] virtio_blk virtio5: 1/0/0 default/read/poll queues test # [ 0.438880] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) test # [ 0.445801] megasas: 07.734.00.00-rc1 test # [ 0.446320] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.451298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.451321] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.453158] Using buffer write method test # [ 0.453226] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.458535] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.458622] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.460687] Using buffer write method test # [ 0.460720] Concatenating MTD devices: test # [ 0.460725] (0): "0.flash" test # [ 0.460728] (1): "0.flash" test # [ 0.460730] into device "0.flash" second # [ 0.658416] Freeing initrd memory: 24820K second # [ 0.665190] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.667822] thunder_xcv, ver 1.0 second # [ 0.667852] thunder_bgx, ver 1.0 second # [ 0.667865] nicpf, ver 1.0 second # [ 0.668147] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.668154] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.668170] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.668177] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.668190] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.668194] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.668206] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.668209] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.668270] sky2: driver version 1.30 second # [ 0.669055] usbcore: registered new interface driver usb-storage second # [ 0.669102] usbcore: registered new interface driver usbserial_generic second # [ 0.669110] usbserial: USB Serial support registered for generic second # [ 0.669379] hv_vmbus: registering driver hyperv_keyboard second # [ 0.676816] ehci-pci 0000:00:0a.0: EHCI Host Controller second # [ 0.676879] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 second # [ 0.677124] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 second # [ 0.678831] rtc-pl031 9010000.pl031: registered as rtc0 second # [ 0.678871] rtc-pl031 9010000.pl031: setting system clock to 2026-09-18T20:03:47 UTC (1789761827) second # [ 0.679033] i2c_dev: i2c /dev entries driver second # [ 0.681868] sdhci: Secure Digital Host Controller Interface driver second # [ 0.681886] sdhci: Copyright(c) Pierre Ossman second # [ 0.682015] Synopsys Designware Multimedia Card Interface Driver second # [ 0.682174] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.656812] Freeing initrd memory: 24812K test # [ 0.663716] tun: Universal TUN/TAP device driver, 1.6 test # [ 0.666421] thunder_xcv, ver 1.0 test # [ 0.666450] thunder_bgx, ver 1.0 test # [ 0.666462] nicpf, ver 1.0 test # [ 0.666776] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.666784] e1000: Copyright (c) 1999-2006 Intel Corporation. test # [ 0.666798] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.666805] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.666818] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.666821] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.666832] igbvf: Intel(R) Gigabit Virtual Function Network Driver test # [ 0.666835] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.666900] sky2: driver version 1.30 test # [ 0.667707] usbcore: registered new interface driver usb-storage test # [ 0.667791] usbcore: registered new interface driver usbserial_generic test # [ 0.667799] usbserial: USB Serial support registered for generic test # [ 0.668087] hv_vmbus: registering driver hyperv_keyboard test # [ 0.669066] ehci-pci 0000:00:0a.0: EHCI Host Controller test # [ 0.669096] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 test # [ 0.669337] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 test # [ 0.677572] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.677607] rtc-pl031 9010000.pl031: setting system clock to 2026-09-18T20:03:48 UTC (1789761828) test # [ 0.677798] i2c_dev: i2c /dev entries driver test # [ 0.680725] sdhci: Secure Digital Host Controller Interface driver test # [ 0.680743] sdhci: Copyright(c) Pierre Ossman test # [ 0.680884] Synopsys Designware Multimedia Card Interface Driver test # [ 0.681052] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.681415] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 test # [ 0.681789] hub 1-0:1.0: USB hub found test # [ 0.681819] hub 1-0:1.0: 6 ports detected test # [ 0.685457] hid: raw HID events driver (C) Jiri Kosina test # [ 0.685606] usbcore: registered new interface driver usbhid second # [ 0.684769] hid: raw HID events driver (C) Jiri Kosina test # [ 0.685612] usbhid: USB HID core driver test # [ 0.694812] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available test # [ 0.695626] drop_monitor: Initializing network drop monitor service second # [ 0.684921] usbcore: registered new interface driver usbhid second # [ 0.684926] usbhid: USB HID core driver test # [ 0.695745] NET: Registered PF_INET6 protocol family second # [ 0.737111] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 second # [ 0.737500] hub 1-0:1.0: USB hub found second # [ 0.739371] hub 1-0:1.0: 6 ports detected second # [ 0.739459] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available test # [ 0.698064] Segment Routing with IPv6 test # [ 0.698103] In-situ OAM (IOAM) with IPv6 test # [ 0.698172] NET: Registered PF_PACKET protocol family second # [ 0.740257] drop_monitor: Initializing network drop monitor service second # [ 0.740382] NET: Registered PF_INET6 protocol family second # [ 0.743340] Segment Routing with IPv6 second # [ 0.743378] In-situ OAM (IOAM) with IPv6 second # [ 0.743431] NET: Registered PF_PACKET protocol family test # [ 0.699335] 9pnet: Installing 9P2000 support test # [ 0.700895] Key type dns_resolver registered test # [ 0.704454] registered taskstats version 1 test # [ 0.704622] Loading compiled-in X.509 certificates second # [ 0.744569] 9pnet: Installing 9P2000 support second # [ 0.746120] Key type dns_resolver registered second # [ 0.749580] registered taskstats version 1 second # [ 0.749752] Loading compiled-in X.509 certificates test # [ 0.710431] Demotion targets for Node 0: null test # [ 0.710513] Key type .fscrypt registered test # [ 0.710519] Key type fscrypt-provisioning registered test # [ 0.710620] ima: No TPM chip found, activating TPM-bypass! test # [ 0.710632] ima: Allocated hash algorithm: sha1 test # [ 0.710684] ima: No architecture policies found second # [ 0.755214] Demotion targets for Node 0: null second # [ 0.755294] Key type .fscrypt registered second # [ 0.755300] Key type fscrypt-provisioning registered second # [ 0.755400] ima: No TPM chip found, activating TPM-bypass! test # [ 0.713388] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.755412] ima: Allocated hash algorithm: sha1 second # [ 0.755461] ima: No architecture policies found second # [ 0.758178] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.723500] clk: Disabling unused clocks test # [ 0.723521] PM: genpd: Disabling unused power domains second # [ 0.768292] clk: Disabling unused clocks second # [ 0.768312] PM: genpd: Disabling unused power domains test # [ 0.726763] Freeing unused kernel memory: 4736K test # [ 0.726854] Run /init as init process second # [ 0.771563] Freeing unused kernel memory: 4736K second # [ 0.771657] Run /init as init process test # [ 0.747873] systemd[1]: Successfully made /usr/ read-only. second # [ 0.792917] systemd[1]: Successfully made /usr/ read-only. test # [ 0.929415] usb 1-1: new high-speed USB device number 2 using ehci-pci second # [ 0.986484] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 1.081524] 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.127606] 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.134699] systemd[1]: Detected virtualization qemu. second # [ 1.136249] systemd[1]: Detected architecture arm64. test # [ 1.085459] 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 # [ 1.085503] systemd[1]: Detected virtualization qemu. second # [ 1.137512] systemd[1]: Running in initrd. test # [ 1.085571] systemd[1]: Detected architecture arm64. second # [ 1.140024] systemd[1]: Initializing machine ID from random generator. test # [ 1.085589] systemd[1]: Running in initrd. test # [ 1.086508] systemd[1]: Initializing machine ID from random generator. second # [ 1.142117] systemd[1]: Hostname set to . test # [ 1.086810] systemd[1]: Hostname set to . second # [ 1.148865] 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.169656] 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.234651] 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 test # [ 1.201844] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.254485] systemd[1]: Queued start job for default target Initrd Default Target. test # [ 1.210891] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.211746] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.212521] systemd[1]: Expecting device /dev/disk/by-label/nixos... test # [ 1.213121] systemd[1]: Reached target Path Units. test # [ 1.213666] systemd[1]: Reached target Slice Units. test # [ 1.214187] systemd[1]: Reached target Swaps. test # [ 1.214651] systemd[1]: Reached target Timer Units. test # [ 1.215316] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 1.216137] systemd[1]: Listening on Journal Socket (/dev/log). test # [ 1.216899] systemd[1]: Listening on Journal Sockets. test # [ 1.217595] systemd[1]: Listening on udev Control Socket. test # [ 1.218396] systemd[1]: Listening on udev Kernel Socket. test # [ 1.218971] systemd[1]: Reached target Socket Units. test # [ 1.220719] systemd[1]: Starting Create List of Static Device Nodes... second # [ 1.264199] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.265196] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.266593] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.267433] systemd[1]: Reached target Path Units. second # [ 1.268076] systemd[1]: Reached target Slice Units. second # [ 1.268735] systemd[1]: Reached target Swaps. test # [ 1.226129] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 1.269360] systemd[1]: Reached target Timer Units. test # [ 1.226846] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 1.270211] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.271342] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.272302] systemd[1]: Listening on Journal Sockets. second # [ 1.273145] systemd[1]: Listening on udev Control Socket. second # [ 1.274013] systemd[1]: Listening on udev Kernel Socket. test # [ 1.231878] systemd[1]: Mounting Kernel Configuration File System... second # [ 1.274803] systemd[1]: Reached target Socket Units. second # [ 1.276855] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.240913] systemd[1]: Starting Journal Service... second # [ 1.285695] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 1.286623] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 1.289179] systemd[1]: Mounting Kernel Configuration File System... second # [ 1.300549] systemd[1]: Starting Journal Service... test # [ 1.258022] systemd[1]: Starting Load Kernel Modules... test # [ 1.258088] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 1.311076] systemd[1]: Starting Load Kernel Modules... second # [ 1.311139] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 1.272139] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.277707] systemd[1]: Finished Create List of Static Device Nodes. test # [ 1.278566] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 1.278743] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 1.278925] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.325132] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.288503] systemd-journald[73]: Collecting audit messages is disabled. second # [ 1.330556] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.331520] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 1.331700] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 1.289923] usb 1-2: new high-speed USB device number 3 using ehci-pci test # [ 1.290871] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.347275] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.348315] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.351332] systemd-journald[73]: Collecting audit messages is disabled. second # [ 1.354537] usb 1-2: new high-speed USB device number 3 using ehci-pci test # [ 1.317901] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.321656] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 1.378999] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.380638] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.337838] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.345459] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.395898] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.357736] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 test # [ 1.357974] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.357980] [drm] features: -context_init test # [ 1.358442] [drm] number of scanouts: 1 test # [ 1.358457] [drm] number of cap sets: 0 second # [ 1.406510] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.410862] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 second # [ 1.411071] [drm] features: -virgl +edid -resource_blob -host_visible second # [ 1.411077] [drm] features: -context_init second # [ 1.411523] [drm] number of scanouts: 1 second # [ 1.411538] [drm] number of cap sets: 0 test # [ 1.371025] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic test # [ 1.371040] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 test # [ 1.375049] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.375388] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.375422] systemd[1]: Reached target Local File Systems. test # [ 1.378292] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 1.426835] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic second # [ 1.426851] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 test # [ 1.391301] systemd[1]: Started Journal Service. test # [ 1.383472] systemd-modules-load[74]: Inserted module 'dm_mod' test # [ 1.384358] systemd-modules-load[74]: Module 'virtio_balloon' is built in test # [ 1.385075] systemd-modules-load[74]: Module 'virtio_console' is built in second # [ 1.438881] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.439061] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.439092] systemd[1]: Reached target Local File Systems. second # [ 1.441733] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 1.445220] systemd[1]: Started Journal Service. test # [ 1.397778] Console: switching to colour frame buffer device 160x50 second # [ 1.442835] systemd-modules-load[74]: Inserted module 'dm_mod' second # [ 1.443697] systemd-modules-load[74]: Module 'virtio_balloon' is built in test # [ 1.401178] systemd[1]: Starting Create System Files and Directories... test # [ 1.414070] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device second # [ 1.458812] Console: switching to colour frame buffer device 160x50 second # [ 1.464137] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device second # [ 1.459742] systemd-modules-load[74]: Module 'virtio_console' is built in test # [ 1.414874] systemd-modules-load[74]: Inserted module 'virtio_gpu' test # [ 1.415492] systemd-modules-load[74]: Module 'virtio_rng' is built in second # [ 1.465011] systemd[1]: Starting Create System Files and Directories... test # [ 1.421179] systemd[1]: Finished Load Kernel Modules. second # [ 1.468534] systemd-modules-load[74]: Inserted module 'virtio_gpu' second # [ 1.472146] systemd-modules-load[74]: Module 'virtio_rng' is built in test # [ 1.437085] systemd[1]: Starting Apply Kernel Variables... second # [ 1.483835] systemd[1]: Finished Load Kernel Modules. test # [ 1.459458] 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.461709] 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.500856] systemd[1]: Starting Apply Kernel Variables... test # [ 1.469175] systemd[1]: Finished Apply Kernel Variables. second # [ 1.516837] systemd[1]: Finished Create System Files and Directories. test # [ 1.472209] systemd[1]: Finished Create System Files and Directories. test # [ 1.473030] systemd-udevd[81]: Using default interface naming scheme 'v260'. second # [ 1.526677] 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.528654] 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.531553] systemd[1]: Finished Apply Kernel Variables. second # [ 1.533221] systemd-udevd[81]: Using default interface naming scheme 'v260'. test # [ 1.496115] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.556720] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 1.548994] systemd[1]: Starting Virtual Console Setup... second # [ 1.609036] systemd[1]: Starting Virtual Console Setup... test # [ 1.596649] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.598743] systemd[1]: Finished Virtual Console Setup. second # [ 1.652985] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.654691] systemd[1]: Finished Virtual Console Setup. second # [ 1.917641] systemd[1]: Finished Coldplug All udev Devices. second # [ 1.918212] systemd[1]: Reached target System Initialization. second # [ 1.918721] systemd[1]: Reached target Basic System. test # [ 1.877454] systemd[1]: Finished Coldplug All udev Devices. test # [ 1.878068] systemd[1]: Reached target System Initialization. test # [ 1.878569] systemd[1]: Reached target Basic System. second # [ 2.011340] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. test # [ 1.978568] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.046930] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.003824] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.079482] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.080538] systemd[1]: Reached target Initrd Root Device. second # [ 2.082588] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.037557] systemd[1]: Found device /dev/disk/by-label/nixos. test # [ 2.039822] systemd[1]: Reached target Initrd Root Device. test # [ 2.042164] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.116832] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.077369] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.148963] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.104499] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.265859] systemd[1]: Mounting /sysroot... second # [ 2.321977] systemd[1]: Mounting /sysroot... test # [ 2.300225] EXT4-fs (vda): mounted filesystem c20ff810-82c8-40ee-9446-981f605f127a r/w with ordered data mode. Quota mode: none. test # [ 2.295862] systemd[1]: Mounted /sysroot. test # [ 2.297651] systemd[1]: Reached target Initrd Root File System. test # [ 2.301294] systemd[1]: Starting Mountpoints Configured in the Real Root... second # [ 2.354700] EXT4-fs (vda): mounted filesystem 6903694c-da93-4331-adf0-eac2de76668b r/w with ordered data mode. Quota mode: none. second # [ 2.352902] systemd[1]: Mounted /sysroot. second # [ 2.355788] systemd[1]: Reached target Initrd Root File System. second # [ 2.362475] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.323003] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.329832] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.331945] systemd[1]: Reloading... second # [ 2.378947] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload. second # [ 2.383831] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)... second # [ 2.387386] systemd[1]: Reloading... test # [ 2.510689] systemd[1]: Reloading finished in 181 ms. test # [ 2.520153] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace... test # [ 2.523859] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace... test # [ 2.525623] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 2.531275] systemd[1]: initrd-parse-etc.service: Deactivated successfully. test # [ 2.534476] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.581364] systemd[1]: Reloading finished in 194 ms. test # [ 2.535116] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.591917] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace... second # [ 2.594653] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace... test # [ 2.552210] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 2.552912] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 2.607308] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 2.608678] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.610131] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.611981] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.624467] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 2.625150] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 2.912299] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory second # [ 2.915478] (udev-worker)[99]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-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 # [ 2.883703] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory second # [ 2.931409] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 2.933268] systemd[1]: Stopped Virtual Console Setup. second # [ 2.934156] systemd[1]: Stopping Virtual Console Setup... test # [ 2.888233] (udev-worker)[95]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-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 # [ 2.937235] systemd[1]: Starting Virtual Console Setup... test # [ 2.901992] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 2.903145] systemd[1]: Stopped Virtual Console Setup. test # [ 2.905795] systemd[1]: Stopping Virtual Console Setup... test # [ 2.906466] systemd[1]: Starting Virtual Console Setup... second # [ 2.958395] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 2.960912] systemd[1]: Finished Virtual Console Setup. test # [ 2.925573] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 2.928191] systemd[1]: Stopped Virtual Console Setup. test # [ 2.928711] systemd[1]: Starting Virtual Console Setup... test # [ 2.941411] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 2.943447] systemd[1]: Finished Virtual Console Setup. test # [ 3.269136] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 3.276330] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 3.325581] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 3.288128] systemd[1]: Mounting /sysroot/run... second # [ 3.337771] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 3.340123] systemd[1]: Mounting /sysroot/run... test # [ 3.296783] systemd[1]: Mounting /sysroot/tmp/shared... second # [ 3.350834] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.307729] systemd[1]: Mounting /sysroot/tmp/xchg... second # [ 3.365254] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.345584] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.397110] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.361788] systemd[1]: Mounted /sysroot/nix/.ro-store. second # [ 3.408761] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.410627] systemd[1]: Mounted /sysroot/run. test # [ 3.364698] systemd[1]: Mounted /sysroot/run. second # [ 3.411859] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 3.367197] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 3.368973] systemd[1]: Mounted /sysroot/tmp/xchg. second # [ 3.416954] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.373112] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.423954] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.390037] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.437200] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.392192] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.439366] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.393250] systemd[1]: Mounting /sysroot/nix/store... second # [ 3.440284] systemd[1]: Mounting /sysroot/nix/store... test # [ 3.426511] systemd[1]: Mounted /sysroot/nix/store. test # [ 3.427574] systemd[1]: Reached target Initrd File Systems. second # [ 3.474465] systemd[1]: Mounted /sysroot/nix/store. second # [ 3.475686] systemd[1]: Reached target Initrd File Systems. test # [ 3.430567] systemd[1]: Starting Find NixOS closure... second # [ 3.477832] systemd[1]: Starting Find NixOS closure... test # [ 3.440728] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 3.488209] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... test # [ 3.461467] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 3.510675] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.474931] systemd[1]: Finished Find NixOS closure. test # [ 3.477319] systemd[1]: Reached target Initrd Default Target. second # [ 3.524668] systemd[1]: Finished Find NixOS closure. test # [ 3.478357] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 3.526655] systemd[1]: Reached target Initrd Default Target. second # [ 3.527766] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 3.504310] systemd[1]: Stopped target Initrd Default Target. test # [ 3.505336] systemd[1]: Stopped target Basic System. second # [ 3.552353] systemd[1]: Stopped target Initrd Default Target. test # [ 3.506214] systemd[1]: Stopped target Initrd Root Device. second # [ 3.553377] systemd[1]: Stopped target Basic System. second # [ 3.554270] systemd[1]: Stopped target Initrd Root Device. test # [ 3.508214] systemd[1]: Stopped target Path Units. test # [ 3.508840] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 3.556106] systemd[1]: Stopped target Path Units. second # [ 3.556878] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.511460] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.512950] systemd[1]: Stopped target Slice Units. second # [ 3.559980] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. second # [ 3.560957] systemd[1]: Stopped target Slice Units. test # [ 3.516246] systemd[1]: Stopped target Socket Units. test # [ 3.516852] systemd[1]: Stopped target System Initialization. test # [ 3.517516] systemd[1]: Stopped target Swaps. second # [ 3.564111] systemd[1]: Stopped target Socket Units. test # [ 3.518013] systemd[1]: Stopped target Timer Units. second # [ 3.564733] systemd[1]: Stopped target System Initialization. test # [ 3.518569] systemd[1]: dbus.socket: Deactivated successfully. second # [ 3.565396] systemd[1]: Stopped target Swaps. test # [ 3.519222] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.519868] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 3.567050] systemd[1]: Stopped target Timer Units. second # [ 3.567638] systemd[1]: dbus.socket: Deactivated successfully. second # [ 3.569142] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.524408] systemd[1]: Stopped Find NixOS closure. test # [ 3.525511] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 3.572131] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 3.572956] systemd[1]: Stopped Find NixOS closure. test # [ 3.527046] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.573585] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 3.575382] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.529579] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 3.530302] systemd[1]: Stopped Apply Kernel Variables. second # [ 3.577908] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 3.531484] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 3.578770] systemd[1]: Stopped Apply Kernel Variables. second # [ 3.579658] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 3.583322] systemd[1]: Stopped Load Kernel Modules. test # [ 3.537321] systemd[1]: Stopped Load Kernel Modules. test # [ 3.537813] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 3.538610] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 3.539332] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 3.585620] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 3.588049] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 3.539978] systemd[1]: Stopped Create System Files and Directories. test # [ 3.545428] systemd[1]: Stopped target Local File Systems. second # [ 3.592333] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 3.593391] systemd[1]: Stopped Create System Files and Directories. test # [ 3.547096] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 3.594060] systemd[1]: Stopped target Local File Systems. second # [ 3.595264] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 3.549267] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.550187] systemd[1]: Stopped Coldplug All udev Devices. test # [ 3.550686] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 3.597380] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.551310] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.551926] systemd[1]: Stopped Virtual Console Setup. second # [ 3.599349] systemd[1]: Stopped Coldplug All udev Devices. second # [ 3.599863] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 3.600652] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.601269] systemd[1]: Stopped Virtual Console Setup. second # [ 3.601722] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.559384] systemd[1]: initrd-cleanup.service: Deactivated successfully. test # [ 3.559998] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.561658] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.562889] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 3.610444] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 3.611093] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 3.611665] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.565273] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 3.565920] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 3.613107] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 3.613769] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 3.567655] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.568602] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.615254] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.569693] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 3.617402] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.572141] systemd[1]: Closed udev Control Socket. second # [ 3.618626] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 3.572630] systemd[1]: Starting Cleanup udev Database... test # [ 3.573097] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 3.619971] systemd[1]: Closed udev Control Socket. second # [ 3.621165] systemd[1]: Starting Cleanup udev Database... test # [ 3.575259] systemd[1]: Stopped Create Static Device Nodes in /dev. test # [ 3.575818] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. test # [ 3.577184] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 3.577864] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 3.624254] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 3.578461] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 3.624930] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 3.625467] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 3.626143] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. second # [ 3.627989] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 3.628761] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 3.599774] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.602195] systemd[1]: Finished Cleanup udev Database. test # [ 3.602672] systemd[1]: Reached target Switch Root. test # [ 3.604822] systemd[1]: Starting NixOS Activation... second # [ 3.651981] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. second # [ 3.653436] systemd[1]: Finished Cleanup udev Database. second # [ 3.655418] systemd[1]: Reached target Switch Root. second # [ 3.660801] systemd[1]: Starting NixOS Activation... second # [ 3.778743] initrd-nixos-activation-start[179]: booting system configuration /nix/store/j1v4crk8h06q6z20jhdz651agnyqifwh-nixos-system-second-test second # [ 3.824874] initrd-nixos-activation-start[179]: running activation script... test # [ 3.791978] initrd-nixos-activation-start[181]: booting system configuration /nix/store/cxqrz5vkhg22v52q8wapgmk904vn4zwv-nixos-system-test-test test # [ 3.863150] initrd-nixos-activation-start[181]: running activation script... second # [ 4.223429] initrd-nixos-activation-start[202]: setting up /etc... test # [ 4.338240] initrd-nixos-activation-start[204]: setting up /etc... second # [ 4.493951] initrd-nixos-activation-start[202]: setting up age secrets... test # [ 4.606433] initrd-nixos-activation-start[204]: setting up age secrets... second # [ 4.726466] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 4.728121] systemd[1]: Finished NixOS Activation. second # [ 4.728860] systemd[1]: Starting Switch Root... second # [ 4.748069] systemd[1]: Switching root. second # [ 4.802734] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). test # [ 4.828478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 4.830161] systemd[1]: Finished NixOS Activation. test # [ 4.832136] systemd[1]: Starting Switch Root... test # [ 4.847955] systemd[1]: Switching root. test # [ 4.905477] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). second # [ 5.404392] 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 # [ 5.411637] systemd[1]: Detected virtualization qemu. second # [ 5.413388] systemd[1]: Detected architecture arm64. second # [ 5.415232] systemd[1]: Detected first boot. second # [ 5.421074] systemd[1]: Initializing machine ID from random generator. test # [ 5.501010] 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 # [ 5.508451] systemd[1]: Detected virtualization qemu. test # [ 5.510309] systemd[1]: Detected architecture arm64. test # [ 5.512545] systemd[1]: Detected first boot. test # [ 5.518183] systemd[1]: Initializing machine ID from random generator. second # [ 5.694931] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 5.805731] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 5.870335] systemd[1]: Applying preset policy. test # [ 5.986665] systemd[1]: Applying preset policy. second # [ 6.445025] systemd[1]: Populated /etc with preset unit settings. test # [ 6.546793] systemd[1]: Populated /etc with preset unit settings. second # [ 6.999759] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 7.000633] systemd[1]: Stopped initrd-switch-root.service. second # [ 7.002752] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 7.004278] systemd[1]: Created slice Slice /system/getty. second # [ 7.005855] systemd[1]: Created slice User and Session Slice. second # [ 7.007293] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 7.008070] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 7.009240] systemd[1]: Expecting device /dev/hvc0... second # [ 7.010215] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 7.011311] systemd[1]: Reached target Local Encrypted Volumes. second # [ 7.012390] systemd[1]: Stopped target initrd-fs.target. second # [ 7.013356] systemd[1]: Stopped target initrd-root-fs.target. second # [ 7.014342] systemd[1]: Stopped target initrd-switch-root.target. second # [ 7.015952] systemd[1]: Reached target Virtual Machines and Containers. second # [ 7.017495] systemd[1]: Reached target Path Units. second # [ 7.018974] systemd[1]: Reached target Remote File Systems. second # [ 7.019868] systemd[1]: Reached target Slice Units. second # [ 7.021429] systemd[1]: Reached target Swaps. second # [ 7.026168] systemd[1]: Listening on Process Core Dump Socket. second # [ 7.029929] systemd[1]: Listening on Credential Encryption/Decryption. second # [ 7.034783] systemd[1]: Starting Journal Log Access Socket... second # [ 7.036510] systemd[1]: Listening on Journal Audit Socket. second # [ 7.037580] systemd[1]: Listening on Network Management Metrics Varlink Socket. second # [ 7.038794] systemd[1]: Listening on Network Management Netlink Socket. second # [ 7.039956] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 7.041063] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 7.042419] systemd[1]: Listening on udev Control Socket. second # [ 7.046721] systemd[1]: Mounting Huge Pages File System... second # [ 7.050586] systemd[1]: Mounting POSIX Message Queue File System... second # [ 7.057141] systemd[1]: Mounting Kernel Debug File System... second # [ 7.060637] systemd[1]: Mounting Kernel Trace File System... second # [ 7.067955] systemd[1]: Starting Create List of Static Device Nodes... second # [ 7.080819] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 7.083119] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 7.093861] systemd[1]: Mounting Kernel Configuration File System... second # [ 7.095528] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 7.099145] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 7.106537] systemd[1]: Starting Load Kernel Module fuse... second # [ 7.108641] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 7.086840] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 7.087747] systemd[1]: Stopped initrd-switch-root.service. test # [ 7.090048] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 7.092043] systemd[1]: Created slice Slice /system/getty. test # [ 7.093699] systemd[1]: Created slice User and Session Slice. test # [ 7.094915] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 7.096283] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 7.097800] systemd[1]: Expecting device /dev/hvc0... test # [ 7.098943] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 7.100011] systemd[1]: Reached target Local Encrypted Volumes. test # [ 7.101556] systemd[1]: Stopped target initrd-fs.target. test # [ 7.103013] systemd[1]: Stopped target initrd-root-fs.target. test # [ 7.104488] systemd[1]: Stopped target initrd-switch-root.target. test # [ 7.106082] systemd[1]: Reached target Virtual Machines and Containers. test # [ 7.107630] systemd[1]: Reached target Path Units. test # [ 7.109027] systemd[1]: Reached target Remote File Systems. test # [ 7.110495] systemd[1]: Reached target Slice Units. second # [ 7.155014] systemd[1]: Starting Journal Service... test # [ 7.111892] systemd[1]: Reached target Swaps. test # [ 7.116539] systemd[1]: Listening on Process Core Dump Socket. test # [ 7.120435] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 7.125284] systemd[1]: Starting Journal Log Access Socket... test # [ 7.127513] systemd[1]: Listening on Journal Audit Socket. test # [ 7.129095] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 7.131090] systemd[1]: Listening on Network Management Netlink Socket. test # [ 7.132867] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 7.134673] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 7.178906] systemd[1]: Starting Load Kernel Modules... test # [ 7.137133] systemd[1]: Listening on udev Control Socket. test # [ 7.141026] systemd[1]: Mounting Huge Pages File System... test # [ 7.144923] systemd[1]: Mounting POSIX Message Queue File System... test # [ 7.149820] systemd[1]: Mounting Kernel Debug File System... test # [ 7.158213] systemd[1]: Mounting Kernel Trace File System... second # [ 7.204689] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 7.166166] systemd[1]: Starting Create List of Static Device Nodes... test # [ 7.180379] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 7.225347] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 7.182081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 7.227372] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.189178] systemd[1]: Mounting Kernel Configuration File System... second # [ 7.234068] systemd[1]: Starting Coldplug All udev Devices... test # [ 7.191104] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm test # [ 7.194199] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore test # [ 7.209343] systemd[1]: Starting Load Kernel Module fuse... second # [ 7.254810] systemd[1]: Listening on Journal Log Access Socket. test # [ 7.210948] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 second # [ 7.267860] systemd[1]: Mounted Huge Pages File System. second # [ 7.268370] systemd[1]: Mounted POSIX Message Queue File System. second # [ 7.268747] systemd[1]: Mounted Kernel Debug File System. second # [ 7.269127] systemd[1]: Mounted Kernel Trace File System. second # [ 7.280915] systemd[1]: Finished Create List of Static Device Nodes. second # [ 7.286587] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 7.294819] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 7.295397] systemd[1]: Mounted Kernel Configuration File System. second # [ 7.300615] fuse: init (API version 7.45) second # [ 7.304801] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 7.274219] systemd[1]: Starting Journal Service... second # [ 7.323491] systemd-journald[302]: Collecting audit messages is enabled. second # [ 7.325039] systemd[1]: modprobe@fuse.service: Deactivated successfully. second # [ 7.330494] systemd[1]: Finished Load Kernel Module fuse. second # [ 7.333689] systemd[1]: Queued start job for default target Multi-User System. second # [ 7.341777] systemd[1]: Finished Load Kernel Modules. second # [ 7.343156] systemd[1]: Started Journal Service. test # [ 7.300971] systemd[1]: Starting Load Kernel Modules... second # [ 7.338467] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 7.346396] systemd-modules-load[303]: Module 'atkbd' is built in second # [ 7.347048] systemd-modules-load[303]: Module 'ctr' is built in second # [ 7.347654] systemd-modules-load[303]: Module 'loop' is built in second # [ 7.354885] systemd[1]: Mounting FUSE Control File System... test # [ 7.320608] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... second # [ 7.360298] systemd[1]: Starting Firewall... second # [ 7.368949] systemd[1]: Starting Apply Kernel Variables... second # [ 7.382565] EXT4-fs (vda): re-mounted 6903694c-da93-4331-adf0-eac2de76668b. test # [ 7.344262] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 7.346080] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.354832] systemd[1]: Starting Coldplug All udev Devices... test # [ 7.370134] systemd[1]: Listening on Journal Log Access Socket. second # [ 7.408333] systemd-oomd[304]: No swap; memory pressure usage will be degraded second # [ 7.410896] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 7.422005] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 7.399923] systemd[1]: Mounted Huge Pages File System. test # [ 7.400448] systemd[1]: Mounted POSIX Message Queue File System. test # [ 7.400835] systemd[1]: Mounted Kernel Debug File System. test # [ 7.401201] systemd[1]: Mounted Kernel Trace File System. test # [ 7.409647] fuse: init (API version 7.45) test # [ 7.415482] systemd[1]: Finished Create List of Static Device Nodes. test # [ 7.418873] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 7.422263] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 7.422820] systemd[1]: Mounted Kernel Configuration File System. second # [ 7.468147] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 7.435147] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 7.437796] systemd[1]: modprobe@fuse.service: Deactivated successfully. second # [ 7.476279] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore second # [ 7.482667] systemd[1]: Starting Load/Save OS Random Seed... test # [ 7.445142] systemd[1]: Finished Load Kernel Module fuse. second # [ 7.484575] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.450179] systemd[1]: Finished Load Kernel Modules. test # [ 7.458021] systemd-journald[325]: Collecting audit messages is enabled. second # [ 7.502659] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 7.460169] systemd[1]: Queued start job for default target Multi-User System. test # [ 7.463651] systemd[1]: systemd-journald.service: Deactivated successfully. test # [ 7.468586] systemd-modules-load[326]: Module 'atkbd' is built in second # [ 7.515046] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 7.479863] systemd[1]: Mounting FUSE Control File System... test # [ 7.473553] systemd-modules-load[326]: Module 'ctr' is built in test # [ 7.477539] systemd-modules-load[326]: Module 'loop' is built in test # [ 7.505537] EXT4-fs (vda): re-mounted c20ff810-82c8-40ee-9446-981f605f127a. second # [ 7.544790] systemd[1]: Mounted FUSE Control File System. test # [ 7.508632] systemd[1]: Starting Firewall... second # [ 7.548186] systemd[1]: Finished Apply Kernel Variables. test # [ 7.504902] systemd-oomd[327]: No swap; memory pressure usage will be degraded test # [ 7.521275] systemd[1]: Starting Apply Kernel Variables... second # [ 7.560319] systemd[1]: Starting Network Name Resolution... test # [ 7.530047] systemd[1]: Started Journal Service. test # [ 7.528512] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 7.540938] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 7.617951] systemd-journald[302]: Received client request to flush runtime journal. test # [ 7.594688] systemd[1]: Mounted FUSE Control File System. test # [ 7.602155] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 7.603555] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 7.611319] systemd[1]: Starting Load/Save OS Random Seed... test # [ 7.613109] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.636938] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 7.647463] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 7.707326] systemd[1]: Finished Load/Save OS Random Seed. second # [ 7.713993] systemd[1]: Reached target First Boot Complete. second # [ 7.721579] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 7.722177] systemd[1]: Reached target Preparation for Local File Systems. second # [ 7.722767] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 7.726782] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 7.685129] systemd[1]: Finished Apply Kernel Variables. test # [ 7.701769] systemd[1]: Starting Network Name Resolution... test # [ 7.714251] systemd-journald[325]: Received client request to flush runtime journal. second # [ 7.769082] systemd-resolved[333]: Positive Trust Anchors: second # [ 7.771101] systemd-resolved[333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d second # [ 7.775069] systemd-resolved[333]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 7.780490] systemd-resolved[333]: 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 # [ 7.785130] systemd[1]: Finished Load/Save OS Random Seed. test # [ 7.787225] systemd[1]: Reached target First Boot Complete. test # [ 7.794233] systemd[1]: Finished Flush Journal to Persistent Storage. second # [ 7.849742] systemd-udevd[339]: Using default interface naming scheme 'v260'. test # [ 7.821713] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 7.822372] systemd[1]: Reached target Preparation for Local File Systems. test # [ 7.829693] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 7.881914] systemd-resolved[356]: Positive Trust Anchors: test # [ 7.888211] systemd-resolved[356]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 7.890365] systemd-resolved[356]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 7.893072] systemd-resolved[356]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test second # [ 7.984424] systemd-resolved[333]: Using system hostname 'second'. second # [ 7.993533] systemd[1]: Started Network Name Resolution. second # [ 8.001655] systemd[1]: Mounting /run/wrappers... test # [ 7.963440] systemd-udevd[363]: Using default interface naming scheme 'v260'. second # [ 8.049275] systemd[1]: Mounted /run/wrappers. second # [ 8.052872] systemd[1]: Reached target Local File Systems. second # [ 8.056446] systemd[1]: Listening on Boot Loader Control Service Socket. second # [ 8.066617] systemd[1]: Starting register-nix-paths.service... second # [ 8.070174] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 8.071030] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 8.079041] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 8.082126] systemd[1]: Starting Create System Files and Directories... test # [ 8.086161] systemd[1]: Mounting /run/wrappers... second # [ 8.133025] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 8.124616] systemd-resolved[356]: Using system hostname 'test'. test # [ 8.134115] systemd[1]: Started Network Name Resolution. test # [ 8.172595] systemd[1]: Mounted /run/wrappers. test # [ 8.173216] systemd[1]: Reached target Local File Systems. test # [ 8.177589] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 8.180986] systemd[1]: Starting register-nix-paths.service... test # [ 8.184531] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 8.234688] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 8.188231] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 8.196761] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 8.243467] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 8.228784] systemd[1]: Starting Create System Files and Directories... second # [ 8.286751] systemd[1]: Finished Create System Files and Directories. second # [ 8.295858] systemd[1]: Starting Rebuild Journal Catalog... second # [ 8.304748] systemd[1]: Starting Record System Boot/Shutdown in UTMP... test # [ 8.281137] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 8.338684] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 8.349902] systemd[1]: Finished Save Transient machine-id to Disk. second # [ 8.428347] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 8.402751] systemd[1]: Finished Create System Files and Directories. test # [ 8.413543] systemd[1]: Starting Rebuild Journal Catalog... test # [ 8.424270] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 8.535118] systemd[1]: Finished Rebuild Journal Catalog. second # [ 8.546507] systemd[1]: Starting Update is Completed... test # [ 8.580999] systemd[1]: Finished Record System Boot/Shutdown in UTMP. second # [ 8.628226] systemd[1]: Finished Update is Completed. second # [ 8.677696] systemd[1]: Finished Coldplug All udev Devices. test # [ 8.631205] systemd[1]: Finished Rebuild Journal Catalog. test # [ 8.642058] systemd[1]: Starting Update is Completed... test # [ 8.724939] systemd[1]: Finished Update is Completed. second # [ 8.795929] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 8.788523] systemd[1]: Finished Coldplug All udev Devices. test # [ 8.912680] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 9.071964] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. second # [ 9.151443] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 9.160134] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 9.162573] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 9.193803] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 9.289325] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. test # [ 9.293147] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 9.344581] systemd[1]: Finished Firewall. second # [ 9.345586] systemd[1]: Reached target Preparation for Network. second # [ 9.352512] systemd[1]: Starting Network Management... test # [ 9.315855] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 9.400466] systemd[1]: Finished register-nix-paths.service. second # [ 9.401180] systemd[1]: Reached target System Initialization. second # [ 9.402627] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 9.405478] systemd[1]: Started Daily Cleanup of Temporary Directories. second # [ 9.409712] systemd[1]: Reached target Timer Units. second # [ 9.411085] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 9.416199] systemd[1]: Listening on Nix Daemon Socket. second # [ 9.422415] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 9.423055] systemd[1]: Listening on Hostname Service Socket. second # [ 9.423572] systemd[1]: Reached target Socket Units. second # [ 9.426300] systemd[1]: Starting D-Bus System Message Bus... second # [ 9.480725] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line. second # [ 9.490551] (udev-worker)[461]: Network interface NamePolicy= disabled on kernel command line. test # [ 9.498600] systemd[1]: Finished Firewall. test # [ 9.499434] systemd[1]: Reached target Preparation for Network. test # [ 9.504427] systemd[1]: Starting Network Management... test # [ 9.517564] systemd[1]: Finished register-nix-paths.service. test # [ 9.518157] systemd[1]: Reached target System Initialization. test # [ 9.518676] systemd[1]: Started Discard unused filesystem blocks once a week. test # [ 9.519283] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 9.519847] systemd[1]: Reached target Timer Units. test # [ 9.525558] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 9.526690] systemd[1]: Listening on Nix Daemon Socket. test # [ 9.536103] systemd[1]: Listening on Authorization Manager Agent Helper. test # [ 9.536760] systemd[1]: Listening on Hostname Service Socket. test # [ 9.537925] systemd[1]: Reached target Socket Units. test # [ 9.542696] systemd[1]: Starting D-Bus System Message Bus... second # [ 9.609020] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.625740] dbus-broker-launch[537]: Looking up NSS user entry for 'systemd-timesync'... second # [ 9.642643] dbus-broker-launch[537]: NSS returned no entry for 'systemd-timesync' test # [ 9.599804] (udev-worker)[401]: Network interface NamePolicy= disabled on kernel command line. second # [ 9.643905] dbus-broker-launch[537]: Invalid user-name in /nix/store/b0p4pf8py0z3xfadb6kr73d6p57pahad-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"[ 9.653903] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 9.654421] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # test # [ 9.617043] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line. second # [ 9.707799] mousedev: PS/2 mouse device common for all mice second # [ 9.703409] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' second # [ 9.709030] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 9.716725] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 9.724376] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 9.731382] systemd[1]: Started D-Bus System Message Bus. second # [ 9.734739] systemd-networkd[534]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. second # [ 9.739420] systemd-networkd[534]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. second # [ 9.747842] systemd-networkd[534]: lo: Link UP second # [ 9.749099] systemd-networkd[534]: lo: Gained carrier second # [ 9.749881] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.755175] systemd-networkd[534]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 9.760708] systemd-networkd[534]: eth1: Link UP second # [ 9.761147] systemd-networkd[534]: eth1: Gained carrier second # [ 9.761612] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.783052] dbus-broker-launch[537]: Ready second # [ 9.785454] systemd[1]: Started Network Management. test # [ 9.747742] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.787256] systemd[1]: Reached target Basic System. second # [ 9.791682] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 9.797416] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 9.755640] dbus-broker-launch[559]: Looking up NSS user entry for 'systemd-timesync'... second # [ 9.808351] systemd[1]: Starting Network Manager... test # [ 9.771178] dbus-broker-launch[559]: NSS returned no entry for 'systemd-timesync' test # [ 9.772352] dbus-broker-launch[559]: Invalid user-name in /nix/store/b0p4pf8py0z3xfadb6kr73d6p57pahad-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" second # [ 9.823439] systemd[1]: Started backdoor.service. test # [ 9.789601] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 9.790114] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 9.847917] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 9.815238] mousedev: PS/2 mouse device common for all mice second # [ 9.871282] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 9.830855] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' test # [ 9.833468] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' test # [ 9.839021] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' test # [ 9.847250] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' test # [ 9.856665] systemd-networkd[556]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. second # [ 9.904846] systemd[1]: Starting Post-Boot Actions... test # [ 9.863034] systemd[1]: Started D-Bus System Message Bus. test # [ 9.866378] systemd-networkd[556]: /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 # [ 9.872475] systemd-networkd[556]: lo: Link UP test # [ 9.875349] systemd-networkd[556]: lo: Gained carrier test # [ 9.876149] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.928798] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[547]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 9.879582] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 9.884219] systemd-networkd[556]: eth1: Link UP test # [ 9.884985] systemd-networkd[556]: eth1: Gained carrier test # [ 9.892571] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.943241] systemd[1]: Started Reset console on configuration changes. test # [ 9.911745] dbus-broker-launch[559]: Ready test # [ 9.913943] systemd[1]: Started Network Management. test # [ 9.915595] systemd[1]: Reached target Basic System. test # [ 9.919433] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 9.926427] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 9.931787] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 9.988837] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... test # [ 9.948294] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 10.016521] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. test # [ 9.981777] systemd[1]: Starting Network Manager... second # connecting to host... second # [ 10.048795] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.006408] systemd[1]: Started backdoor.service. test # [ 10.037869] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 10.062817] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 10.123491] systemd[1]: Finished Post-Boot Actions. second # [ 10.132632] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 10.136418] nsncd[551]: Sep 18 20:03:56.961 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 10.092246] ispsphlnyb2js715g0mg6bn3157dmskk-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 10.145311] systemd[1]: Reached target Host and Network Name Lookups. second # [ 10.145948] systemd[1]: Reached target User and Group Name Lookups. test # [ 10.102344] systemd[1]: Starting Name Service Cache Daemon (nsncd)... second # [ 10.151767] systemd[1]: Starting User Login Management... second # [ 10.153839] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test # [ 10.109956] vz5k34ilhns09vh81dvgw89nvkf1plwv-wifi-secrets[571]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 10.119603] systemd[1]: Starting Post-Boot Actions... second # [ 10.191440] systemd[1]: Finished Import lastlog data into lastlog2 database. test # [ 10.157989] systemd[1]: Started Reset console on configuration changes. second # [ 10.206528] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 10.202499] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... test # connecting to host... second # [ 10.302187] systemd[1]: Starting Hostname Service... test # [ 10.258023] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 10.305382] systemd-logind[581]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 10.310942] systemd-logind[581]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 10.311633] systemd-logind[581]: New seat seat0. second # [ 10.314734] systemd[1]: Started User Login Management. test # [ 10.268483] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.269212] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. second # [ 10.323407] systemd[1]: Starting linger-users.service... test # [ 10.279466] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.285320] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 10.293863] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test: Guest shell says: b'Spawning backdoor root shell...\n' test: connected to guest root shell test: (connecting took 10.59 seconds) test: (finished: waiting for the VM to finish booting, in 10.59 seconds) test # [ 10.302689] systemd[1]: Finished Post-Boot Actions. second # [ 10.401301] systemd[1]: linger-users.service: Deactivated successfully. second # [ 10.402002] systemd[1]: Finished linger-users.service. test # [ 10.368931] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 10.372761] systemd[1]: Started Name Service Cache Daemon (nsncd). test # [ 10.377566] nsncd[582]: Sep 18 20:03:58.199 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 10.378809] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test # [ 10.388599] systemd[1]: Reached target Host and Network Name Lookups. test # [ 10.392397] systemd[1]: Reached target User and Group Name Lookups. test # [ 10.398072] systemd[1]: Starting User Login Management... second # [ 10.447212] systemd[1]: Started Hostname Service. test # [ 10.437619] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 10.545631] systemd-networkd[534]: eth0: Link UP second # [ 10.546132] systemd-networkd[534]: eth0: Gained carrier test # [ 10.519598] systemd[1]: Starting Hostname Service... second # [ 10.566711] systemd[1]: Started Network Manager. second # [ 10.568846] systemd[1]: Reached target Network. second # [ 10.574409] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 10.580636] systemd[1]: Starting Permit User Sessions... test # [ 10.545816] systemd-logind[625]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 10.549938] systemd-logind[625]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 10.553832] systemd-logind[625]: New seat seat0. test # [ 10.554996] systemd[1]: Started User Login Management. test # [ 10.557770] systemd[1]: Starting linger-users.service... test # [ 10.636375] systemd[1]: linger-users.service: Deactivated successfully. test # [ 10.638147] systemd[1]: Finished linger-users.service. test # [ 10.654910] systemd[1]: Started Hostname Service. second # [ 10.744277] systemd[1]: Finished Permit User Sessions. second # [ 10.755710] systemd[1]: Started Getty on tty1. second # [ 10.759278] systemd[1]: Reached target Login Prompts. test # [ 10.753395] systemd-networkd[556]: eth0: Link UP test # [ 10.753885] systemd-networkd[556]: eth0: Gained carrier test # [ 10.773324] systemd[1]: Started Network Manager. test # [ 10.775357] systemd[1]: Reached target Network. test # [ 10.781064] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 10.833245] systemd-resolved[333]: eth0: Bus client set default route setting: yes test # [ 10.787334] systemd[1]: Starting Permit User Sessions... second # [ 10.838076] systemd-resolved[333]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 10.881300] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. second # [ 10.884296] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 10.912581] systemd-networkd[556]: eth1: Gained IPv6LL second # [ 10.977689] systemd-networkd[534]: eth1: Gained IPv6LL test # [ 10.950820] systemd[1]: Finished Permit User Sessions. test # [ 10.969517] systemd[1]: Started Getty on tty1. test # [ 10.972404] systemd[1]: Reached target Login Prompts. test # [ 10.977762] systemd-resolved[356]: eth0: Bus client set default route setting: yes second # [ 11.028392] systemd[1]: Reached target Multi-User System. test # [ 10.982750] systemd-resolved[356]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 11.032068] systemd[1]: Startup finished in 772ms (kernel) + 4.132s (initrd) + 6.124s (userspace) = 11.030s. test # [ 11.022028] NetworkManager[572]: [1789761838.8523] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty test # [ 11.076397] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. test # [ 11.078012] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 11.218078] systemd[1]: Reached target Multi-User System. test # [ 11.222601] systemd[1]: Startup finished in 724ms (kernel) + 4.283s (initrd) + 6.213s (userspace) = 11.221s. second # [ 11.365713] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 second # [ 11.609271] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test: (finished: waiting for unit NetworkManager.service, in 11.85 seconds) test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection second # [ 11.648674] systemd[1]: Starting Virtual Console Setup... second # [ 11.670751] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 11.671479] systemd[1]: Stopped Virtual Console Setup. second # [ 11.675937] systemd[1]: Starting Virtual Console Setup... second # [ 11.685818] systemd-logind[581]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) 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 # [ 11.678897] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds) test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.05 seconds) (finished: run the VM test script, in 12.44 seconds) test script finished in 12.51s cleanup kill QemuMachine (pid 15) second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13) kill QemuMachine (pid 14) test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13) (finished: cleanup, in 0.27 seconds)