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: QEMU running (pid 15) second # Formatting '/build/vm-state-second/tmp.7xQVLuzLR7', fmt=raw size=1073741824 test # Disk image does not exist, creating the virtualisation disk image... second # mke2fs 1.47.4 (6-Mar-2025) test # Formatting '/build/vm-state-test/tmp.a05SQrng04', fmt=raw size=1073741824 second # Discarding device blocks: 0/262144 done test # mke2fs 1.47.4 (6-Mar-2025) second # Creating filesystem with 262144 4k blocks and 65536 inodes second # Filesystem UUID: 101b7992-002c-4d68-b56e-3d08df224ebb test: QEMU running (pid 14) test # Discarding device blocks: 0/262144 done second # Superblock backups stored on blocks: test # Creating filesystem with 262144 4k blocks and 65536 inodes second # 32768, 98304, 163840, 229376 test # Filesystem UUID: 6860c9da-8862-410c-a7e5-28a7cb01be49 second # test # Superblock backups stored on blocks: second # Allocating group tables: 0/8 done second # Writing inode tables: 0/8 done (finished: start all VMs, in 0.55 seconds) test # 32768, 98304, 163840, 229376 second # Creating journal (8192 blocks): done second # Writing superblocks and filesystem accounting information: 0/8 done test # second # test # Allocating group tables: 0/8 done second # Virtualisation disk image created. test # Writing inode tables: 0/8 done test: waiting for unit NetworkManager.service test # Creating journal (8192 blocks): done test: waiting for the VM to finish booting test # Writing superblocks and filesystem accounting information: 0/8 done test # test # Virtualisation disk image created. second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] second # [ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 UTC 2026 second # [ 0.000000] KASLR enabled second # [ 0.000000] random: crng init done second # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.000000] efi: UEFI not found. second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT 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. test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] 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 test # [ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 UTC 2026 test # [ 0.000000] KASLR enabled second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) test # [ 0.000000] random: crng init done test # [ 0.000000] Machine model: linux,dummy-virt second # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296 test # [ 0.000000] efi: UEFI not found. second # [ 0.000000] Detected PIPT I-cache on CPU0 test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT second # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: Spectre-v4 test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] CPU features: detected: Spectre-BHB test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf] second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR test # [ 0.000000] Zone ranges: second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] CPU features: detected: ARM erratum 1418040 test # [ 0.000000] DMA32 empty test # [ 0.000000] Normal empty second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion test # [ 0.000000] Device empty test # [ 0.000000] Movable zone start for each node second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] Early memory node ranges second # [ 0.000000] alternatives: applying boot alternatives test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 test # [ 0.000000] psci: probing for conduit method from DT. test # [ 0.000000] psci: PSCIv1.3 detected in firmware. 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/mqrk7mnq0c1pzx0a17lgr5i6b2pcqsp4-nixos-system-second-test/init regInfo=/nix/store/avbnw62jw7nn32fiz75sxki9d24y7x3p-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs test # [ 0.000000] psci: Trusted OS migration not required test # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/avbnw62jw7nn32fiz75sxki9d24y7x3p-closure-info/registration", will be passed to user space. second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) test # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296 test # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB test # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] software IO TLB: area num 1. test # [ 0.000000] CPU features: detected: Spectre-BHB second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) second # [ 0.000000] Fallback order for Node 0: 0 test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 second # [ 0.000000] Policy zone: DMA test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) test # [ 0.000000] CPU features: detected: ARM erratum 1418040 second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing second # [ 0.000000] ftrace: allocating 74760 entries in 294 pages test # [ 0.000000] alternatives: applying boot alternatives 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. 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/b6rjxxx5hzzmb6gdicva5bdla102ph72-nixos-system-test-test/init regInfo=/nix/store/7r6sdpmanjc7vcdw8ir1rmcx2mipx22i-closure-info/registration console=ttyAMA0,115200n8 console=tty0 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 test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/7r6sdpmanjc7vcdw8ir1rmcx2mipx22i-closure-info/registration", will be passed to user space. second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB second # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] software IO TLB: area num 1. second # [ 0.000000] Root IRQ handler: gic_handle_irq test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) 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 test # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000000] Policy zone: DMA second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 test # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 test # [ 0.000000] ftrace: allocating 74760 entries in 294 pages second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. test # [ 0.000000] ftrace: allocated 294 pages with 4 groups test # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns test # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). test # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns test # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. second # [ 0.000063] arm-pv: using stolen time PV test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 second # [ 0.000418] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000741] Console: colour dummy device 80x25 test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000760] printk: legacy console [tty0] enabled test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000978] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.000983] pid_max: default: 32768 minimum: 301 test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.001066] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.001194] landlock: Up and running. test # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.001196] Yama: becoming mindful. test # [ 0.000000] GICv3: 0 Extended SPIs implemented second # [ 0.001582] LSM support for eBPF active test # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.001735] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 second # [ 0.001755] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.003610] cacheinfo: Unable to detect cache hierarchy for CPU 0 test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] second # [ 0.004463] rcu: Hierarchical SRCU implementation. second # [ 0.004469] rcu: Max phase no-delay instances is 1000. test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.005412] fsl-mc MSI: its@8080000 domain created second # [ 0.005562] EFI services will not be available. test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) second # [ 0.005653] smp: Bringing up secondary CPUs ... second # [ 0.005688] smp: Brought up 1 node, 1 CPU test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 second # [ 0.005691] SMP: Total of 1 processors activated. second # [ 0.005692] CPU: All CPU(s) started at EL1 test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 second # [ 0.005706] CPU features: detected: 32-bit EL0 Support test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.005709] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.005711] CPU features: detected: Common not Private translations test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 second # [ 0.005713] CPU features: detected: CRC32 instructions test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.005715] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.005718] CPU features: detected: Enhanced Virtualization Traps test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.005730] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns second # [ 0.005732] CPU features: detected: LSE atomic instructions test # [ 0.000078] arm-pv: using stolen time PV second # [ 0.005734] CPU features: detected: Privileged Access Never second # [ 0.005736] CPU features: detected: PMUv3 test # [ 0.000717] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.005738] CPU features: detected: RAS Extension Support test # [ 0.001042] Console: colour dummy device 80x25 second # [ 0.005741] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.001062] printk: legacy console [tty0] enabled second # [ 0.005762] alternatives: applying system-wide alternatives test # [ 0.001301] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) second # [ 0.007138] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.001307] pid_max: default: 32768 minimum: 301 test # [ 0.001396] LSM: initializing lsm=capability,landlock,yama,bpf,ima second # [ 0.007346] Memory: 893156K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114100K reserved, 32768K cma-reserved) test # [ 0.001546] landlock: Up and running. second # [ 0.007841] devtmpfs: initialized test # [ 0.001548] Yama: becoming mindful. test # [ 0.002144] LSM support for eBPF active second # [ 0.009405] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.002338] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.009427] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.002359] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.009567] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.009570] 0 pages in range for non-PLT usage test # [ 0.004544] cacheinfo: Unable to detect cache hierarchy for CPU 0 second # [ 0.009571] 508336 pages in range for PLT usage test # [ 0.005334] rcu: Hierarchical SRCU implementation. second # [ 0.009689] pinctrl core: initialized pinctrl subsystem test # [ 0.005339] rcu: Max phase no-delay instances is 1000. second # [ 0.010836] DMI not present or invalid. test # [ 0.006375] fsl-mc MSI: its@8080000 domain created second # [ 0.014764] NET: Registered PF_NETLINK/PF_ROUTE protocol family test # [ 0.006537] EFI services will not be available. test # [ 0.006640] smp: Bringing up secondary CPUs ... second # [ 0.017243] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.006676] smp: Brought up 1 node, 1 CPU second # [ 0.017394] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.006679] SMP: Total of 1 processors activated. test # [ 0.006681] CPU: All CPU(s) started at EL1 second # [ 0.017566] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.006693] CPU features: detected: 32-bit EL0 Support second # [ 0.017584] audit: initializing netlink subsys (disabled) test # [ 0.006696] CPU features: detected: Data cache clean to the PoU not required for I/D coherence second # [ 0.018192] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.018195] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.006699] CPU features: detected: Common not Private translations test # [ 0.006701] CPU features: detected: CRC32 instructions second # [ 0.018198] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.018200] thermal_sys: Registered thermal governor 'user_space' test # [ 0.006703] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.018202] thermal_sys: Registered thermal governor 'power_allocator' test # [ 0.006705] CPU features: detected: Enhanced Virtualization Traps test # [ 0.006716] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.018232] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 test # [ 0.006718] CPU features: detected: LSE atomic instructions second # [ 0.018248] cpuidle: using governor ladder second # [ 0.018253] cpuidle: using governor menu test # [ 0.006720] CPU features: detected: Privileged Access Never test # [ 0.006722] CPU features: detected: PMUv3 second # [ 0.018367] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. test # [ 0.006724] CPU features: detected: RAS Extension Support second # [ 0.018389] ASID allocator initialised with 32768 entries second # [ 0.019091] Serial: AMBA PL011 UART driver test # [ 0.006728] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.006750] alternatives: applying system-wide alternatives second # [ 0.022214] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.022352] printk: console [ttyAMA0] enabled test # [ 0.008229] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.008425] Memory: 893172K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114088K reserved, 32768K cma-reserved) test # [ 0.008962] devtmpfs: initialized test # [ 0.010720] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.010749] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.010917] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL test # [ 0.010920] 0 pages in range for non-PLT usage test # [ 0.010921] 508336 pages in range for PLT usage test # [ 0.011064] pinctrl core: initialized pinctrl subsystem test # [ 0.011943] DMI not present or invalid. test # [ 0.015699] NET: Registered PF_NETLINK/PF_ROUTE protocol family test # [ 0.018376] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.018538] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.018711] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.018730] audit: initializing netlink subsys (disabled) test # [ 0.019403] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.019405] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.097159] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.019409] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.097180] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.019411] thermal_sys: Registered thermal governor 'user_space' second # [ 0.097188] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.019413] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.097191] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.019449] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.097194] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.019462] cpuidle: using governor ladder test # [ 0.019468] cpuidle: using governor menu second # [ 0.097197] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.019586] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.097201] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.019611] ASID allocator initialised with 32768 entries second # [ 0.097204] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.020370] Serial: AMBA PL011 UART driver test # [ 0.023780] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 test # [ 0.023967] printk: console [ttyAMA0] enabled second # [ 0.102262] fbcon: Taking over console second # [ 0.102280] ACPI: Interpreter disabled. second # [ 0.103536] iommu: Default domain type: Translated second # [ 0.103547] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.104757] SCSI subsystem initialized test # [ 0.104334] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.104357] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.104364] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.104367] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.104371] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.104374] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.104377] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages second # [ 0.113400] usbcore: registered new interface driver usbfs second # [ 0.113441] usbcore: registered new interface driver hub test # [ 0.104380] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page second # [ 0.113460] usbcore: registered new device driver usb second # [ 0.113675] pps_core: LinuxPPS API ver. 1 registered second # [ 0.113680] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.113686] PTP clock support registered second # [ 0.113716] EDAC MC: Ver: 3.0.0 test # [ 0.109596] fbcon: Taking over console test # [ 0.109616] ACPI: Interpreter disabled. second # [ 0.116902] scmi_core: SCMI protocol bus registered test # [ 0.110943] iommu: Default domain type: Translated test # [ 0.110954] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.117715] FPGA manager framework test # [ 0.112149] SCSI subsystem initialized second # [ 0.118741] vgaarb: loaded second # [ 0.119191] clocksource: Switched to clocksource arch_sys_counter test # [ 0.112747] usbcore: registered new interface driver usbfs test # [ 0.112781] usbcore: registered new interface driver hub test # [ 0.112798] usbcore: registered new device driver usb test # [ 0.113008] pps_core: LinuxPPS API ver. 1 registered test # [ 0.113015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti test # [ 0.113021] PTP clock support registered test # [ 0.113057] EDAC MC: Ver: 3.0.0 test # [ 0.116085] scmi_core: SCMI protocol bus registered test # [ 0.116932] FPGA manager framework second # [ 0.124584] VFS: Disk quotas dquot_6.6.0 second # [ 0.124620] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) second # [ 0.127027] netfs: FS-Cache loaded second # [ 0.127147] pnp: PnP ACPI: disabled test # [ 0.124758] vgaarb: loaded second # [ 0.129597] NET: Registered PF_INET protocol family test # [ 0.125241] clocksource: Switched to clocksource arch_sys_counter second # [ 0.129787] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.130564] VFS: Disk quotas dquot_6.6.0 test # [ 0.130603] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.133148] netfs: FS-Cache loaded test # [ 0.133605] pnp: PnP ACPI: disabled test # [ 0.135753] NET: Registered PF_INET protocol family test # [ 0.135945] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) second # [ 0.153849] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.153881] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.153895] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.153933] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.154001] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.154094] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.154153] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.154180] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.154319] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.154356] NET: Registered PF_XDP protocol family second # [ 0.154377] PCI: CLS 0 bytes, default 64 second # [ 0.154575] Trying to unpack rootfs image as initramfs... second # [ 0.165717] kvm [1]: HYP mode not available test # [ 0.160319] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.160353] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) test # [ 0.160367] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.160407] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.160476] TCP: Hash tables configured (established 8192 bind 8192) test # [ 0.160571] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.160603] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.160626] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.160746] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.160778] NET: Registered PF_XDP protocol family test # [ 0.160801] PCI: CLS 0 bytes, default 64 test # [ 0.161024] Trying to unpack rootfs image as initramfs... test # [ 0.175581] kvm [1]: HYP mode not available second # [ 0.239569] Initialise system trusted keyrings second # [ 0.240095] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.241080] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.241606] 9p: Installing v9fs 9p2000 file system support test # [ 0.241975] Initialise system trusted keyrings test # [ 0.242504] workingset: timestamp_bits=42 max_order=18 bucket_order=0 test # [ 0.243491] squashfs: version 4.0 (2009/01/31) Phillip Lougher test # [ 0.244029] 9p: Installing v9fs 9p2000 file system support second # [ 0.261057] Key type asymmetric registered second # [ 0.261085] Asymmetric key parser 'x509' registered second # [ 0.261151] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.267254] io scheduler mq-deadline registered second # [ 0.267274] io scheduler kyber registered test # [ 0.263400] Key type asymmetric registered test # [ 0.263430] Asymmetric key parser 'x509' registered second # [ 0.270201] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.263495] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.270992] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.271862] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: test # [ 0.269310] io scheduler mq-deadline registered test # [ 0.269330] io scheduler kyber registered second # [ 0.271889] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.271904] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.271910] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.271940] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.271968] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] test # [ 0.272333] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.272039] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 test # [ 0.273156] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.272058] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.272063] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.272067] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.272070] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.272147] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.272570] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.272684] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.272694] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.274083] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.272713] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.274113] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.272724] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.274127] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.272995] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.273103] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] test # [ 0.274133] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.273114] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.274168] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.273133] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.274196] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.273399] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.274272] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.273506] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] test # [ 0.274290] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.273516] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.274295] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.273535] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.274299] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.273809] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.274303] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.273916] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.274381] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.273926] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.274784] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.273945] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.274901] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.274216] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.274912] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.274322] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] test # [ 0.274932] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.274332] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.274945] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.274352] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.275224] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.274618] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.275336] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.274727] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] test # [ 0.275346] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.274737] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.275367] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.274756] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.275644] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint second # [ 0.275033] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.275141] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] test # [ 0.275755] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f] second # [ 0.275151] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.275766] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.275173] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.275785] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.275184] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.276069] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.276182] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.276193] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.276212] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.276504] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint test # [ 0.276615] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f] test # [ 0.276625] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.276645] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.276920] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.277033] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f] test # [ 0.277044] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.277064] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.303631] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.303759] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.303778] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.304052] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.304164] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.304183] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.304447] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.304556] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.304719] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.304870] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.304882] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.305081] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.305364] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.305475] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.305486] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.305505] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.305894] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned second # [ 0.305905] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned second # [ 0.305910] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned second # [ 0.305943] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned second # [ 0.305973] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned second # [ 0.306004] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned second # [ 0.306034] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned second # [ 0.306064] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned second # [ 0.306095] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.309480] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.306130] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.309615] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f] test # [ 0.309628] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.306159] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.309652] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.306188] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.309664] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.306244] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.309967] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.306273] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.310083] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.306287] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.310102] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.306341] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.310384] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint second # [ 0.306357] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.310497] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.306371] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned test # [ 0.310515] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.306387] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned test # [ 0.310764] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.306403] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned test # [ 0.310873] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.306418] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned test # [ 0.311040] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.306433] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned test # [ 0.311282] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.306448] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned test # [ 0.311295] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.306463] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned test # [ 0.311316] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.306477] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned test # [ 0.311599] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.306492] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned test # [ 0.311712] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.306507] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.311723] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.306521] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned test # [ 0.311742] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.306535] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.306556] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned test # [ 0.312148] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned second # [ 0.306571] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned test # [ 0.312158] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned second # [ 0.306586] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned test # [ 0.312163] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned second # [ 0.306600] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned test # [ 0.312196] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned second # [ 0.306615] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned second # [ 0.306633] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] test # [ 0.312226] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned second # [ 0.306639] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] test # [ 0.312257] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned second # [ 0.306643] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] test # [ 0.312286] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.312315] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.312346] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.312378] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.312407] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.312436] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.312500] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.312532] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.312546] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.312560] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.312575] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.312591] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned test # [ 0.312606] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned test # [ 0.312621] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned second # [ 0.343389] pci 0000:00:0a.0: enabling device (0000 -> 0002) test # [ 0.312640] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned test # [ 0.312655] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned test # [ 0.312670] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned test # [ 0.312685] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned test # [ 0.312700] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned test # [ 0.312714] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned test # [ 0.312729] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned test # [ 0.312744] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned test # [ 0.312759] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned test # [ 0.312780] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned test # [ 0.312795] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned test # [ 0.312810] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned test # [ 0.312825] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned test # [ 0.312840] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned second # [ 0.349763] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.312860] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] test # [ 0.312866] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] test # [ 0.312870] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] test # [ 0.313560] pci 0000:00:0a.0: enabling device (0000 -> 0002) test # [ 0.352286] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.359732] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) second # [ 0.361410] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.362431] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.365128] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) second # [ 0.371291] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.373045] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) second # [ 0.374715] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) second # [ 0.376738] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) second # [ 0.378641] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.374293] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) test # [ 0.376298] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003) test # [ 0.379581] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003) second # [ 0.388363] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.390312] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) test # [ 0.385365] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003) test # [ 0.387890] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) second # [ 0.400366] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.394181] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) test # [ 0.396026] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.404310] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.405779] msm_serial: driver initialized second # [ 0.405852] SuperH (H)SCI(F) driver initialized second # [ 0.405898] STM32 USART driver initialized test # [ 0.406258] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.413953] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.426521] loop: module loaded second # [ 0.426656] virtio_blk virtio5: 1/0/0 default/read/poll queues test # [ 0.415513] msm_serial: driver initialized test # [ 0.415604] SuperH (H)SCI(F) driver initialized test # [ 0.415641] STM32 USART driver initialized second # [ 0.431302] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.435601] megasas: 07.734.00.00-rc1 second # [ 0.436037] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.438622] loop: module loaded test # [ 0.438763] virtio_blk virtio5: 1/0/0 default/read/poll queues test # [ 0.439339] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.448184] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.448234] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.449970] Using buffer write method second # [ 0.450036] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.445664] megasas: 07.734.00.00-rc1 test # [ 0.446167] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] second # [ 0.459701] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.459727] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.461178] Using buffer write method second # [ 0.461211] Concatenating MTD devices: second # [ 0.461216] (0): "0.flash" second # [ 0.461219] (1): "0.flash" second # [ 0.461221] into device "0.flash" test # [ 0.462234] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.462311] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.464044] Using buffer write method test # [ 0.464112] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] test # [ 0.479008] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.479039] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.481108] Using buffer write method test # [ 0.481141] Concatenating MTD devices: test # [ 0.481146] (0): "0.flash" test # [ 0.481149] (1): "0.flash" test # [ 0.481151] into device "0.flash" second # [ 0.654869] Freeing initrd memory: 25492K second # [ 0.661759] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.664437] thunder_xcv, ver 1.0 second # [ 0.664466] thunder_bgx, ver 1.0 second # [ 0.664478] nicpf, ver 1.0 second # [ 0.664760] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.664768] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.664788] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.664793] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.664806] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.664810] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.664821] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.664825] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. second # [ 0.664914] sky2: driver version 1.30 second # [ 0.665667] usbcore: registered new interface driver usb-storage second # [ 0.665790] usbcore: registered new interface driver usbserial_generic second # [ 0.665799] usbserial: USB Serial support registered for generic second # [ 0.666074] hv_vmbus: registering driver hyperv_keyboard second # [ 0.673559] ehci-pci 0000:00:0a.0: EHCI Host Controller second # [ 0.673599] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 second # [ 0.673833] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 second # [ 0.675543] rtc-pl031 9010000.pl031: registered as rtc0 second # [ 0.675587] rtc-pl031 9010000.pl031: setting system clock to 2026-09-02T15:08:19 UTC (1788361699) second # [ 0.675790] i2c_dev: i2c /dev entries driver test # [ 0.671212] Freeing initrd memory: 25480K second # [ 0.678590] sdhci: Secure Digital Host Controller Interface driver second # [ 0.678604] sdhci: Copyright(c) Pierre Ossman second # [ 0.678734] Synopsys Designware Multimedia Card Interface Driver second # [ 0.678892] sdhci-pltfm: SDHCI platform and OF driver helper second # [ 0.681639] hid: raw HID events driver (C) Jiri Kosina second # [ 0.681804] usbcore: registered new interface driver usbhid second # [ 0.681809] usbhid: USB HID core driver second # [ 0.683561] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 test # [ 0.678069] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.683927] hub 1-0:1.0: USB hub found second # [ 0.683957] hub 1-0:1.0: 6 ports detected second # [ 0.685323] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.686064] drop_monitor: Initializing network drop monitor service test # [ 0.680758] thunder_xcv, ver 1.0 second # [ 0.686234] NET: Registered PF_INET6 protocol family test # [ 0.680792] thunder_bgx, ver 1.0 test # [ 0.680804] nicpf, ver 1.0 test # [ 0.681129] e1000: Intel(R) PRO/1000 Network Driver second # [ 0.688575] Segment Routing with IPv6 test # [ 0.681138] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.688612] In-situ OAM (IOAM) with IPv6 test # [ 0.681158] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.688669] NET: Registered PF_PACKET protocol family test # [ 0.681163] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.681177] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.681180] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.681191] igbvf: Intel(R) Gigabit Virtual Function Network Driver second # [ 0.689817] 9pnet: Installing 9P2000 support test # [ 0.681194] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.685638] sky2: driver version 1.30 second # [ 0.691852] Key type dns_resolver registered test # [ 0.686486] usbcore: registered new interface driver usb-storage second # [ 0.695064] registered taskstats version 1 test # [ 0.686586] usbcore: registered new interface driver usbserial_generic test # [ 0.686595] usbserial: USB Serial support registered for generic test # [ 0.686888] hv_vmbus: registering driver hyperv_keyboard test # [ 0.687960] ehci-pci 0000:00:0a.0: EHCI Host Controller test # [ 0.687997] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1 test # [ 0.688222] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000 test # [ 0.692140] rtc-pl031 9010000.pl031: registered as rtc0 second # [ 0.695567] Loading compiled-in X.509 certificates test # [ 0.692183] rtc-pl031 9010000.pl031: setting system clock to 2026-09-02T15:08:19 UTC (1788361699) test # [ 0.692364] i2c_dev: i2c /dev entries driver second # [ 0.700607] Demotion targets for Node 0: null second # [ 0.700690] Key type .fscrypt registered second # [ 0.700697] Key type fscrypt-provisioning registered second # [ 0.700800] ima: No TPM chip found, activating TPM-bypass! second # [ 0.700813] ima: Allocated hash algorithm: sha1 second # [ 0.700865] ima: No architecture policies found test # [ 0.695561] sdhci: Secure Digital Host Controller Interface driver test # [ 0.695580] sdhci: Copyright(c) Pierre Ossman second # [ 0.703851] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.695717] Synopsys Designware Multimedia Card Interface Driver test # [ 0.695891] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.696838] hid: raw HID events driver (C) Jiri Kosina test # [ 0.697001] usbcore: registered new interface driver usbhid test # [ 0.697006] usbhid: USB HID core driver test # [ 0.700083] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00 test # [ 0.700954] hub 1-0:1.0: USB hub found test # [ 0.701319] hub 1-0:1.0: 6 ports detected test # [ 0.702459] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available test # [ 0.703285] drop_monitor: Initializing network drop monitor service test # [ 0.703465] NET: Registered PF_INET6 protocol family test # [ 0.705754] Segment Routing with IPv6 test # [ 0.705782] In-situ OAM (IOAM) with IPv6 test # [ 0.705848] NET: Registered PF_PACKET protocol family second # [ 0.714249] clk: Disabling unused clocks test # [ 0.706943] 9pnet: Installing 9P2000 support second # [ 0.714274] PM: genpd: Disabling unused power domains test # [ 0.708661] Key type dns_resolver registered second # [ 0.717598] Freeing unused kernel memory: 4736K second # [ 0.717694] Run /init as init process test # [ 0.712389] registered taskstats version 1 test # [ 0.712554] Loading compiled-in X.509 certificates test # [ 0.718060] Demotion targets for Node 0: null test # [ 0.718161] Key type .fscrypt registered test # [ 0.718168] Key type fscrypt-provisioning registered test # [ 0.718315] ima: No TPM chip found, activating TPM-bypass! test # [ 0.718330] ima: Allocated hash algorithm: sha1 test # [ 0.718397] ima: No architecture policies found test # [ 0.721148] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.737667] systemd[1]: Successfully made /usr/ read-only. test # [ 0.732235] clk: Disabling unused clocks test # [ 0.732267] PM: genpd: Disabling unused power domains test # [ 0.735574] Freeing unused kernel memory: 4736K test # [ 0.735672] Run /init as init process test # [ 0.755748] systemd[1]: Successfully made /usr/ read-only. second # [ 0.931243] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 0.949292] usb 1-1: new high-speed USB device number 2 using ehci-pci second # [ 1.072457] 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.079654] systemd[1]: Detected virtualization qemu. second # [ 1.081072] systemd[1]: Detected architecture arm64. second # [ 1.082381] systemd[1]: Running in initrd. second # [ 1.084767] systemd[1]: Initializing machine ID from random generator. second # [ 1.087016] systemd[1]: Hostname set to . second # [ 1.094014] 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.090468] 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.097655] systemd[1]: Detected virtualization qemu. test # [ 1.099196] systemd[1]: Detected architecture arm64. test # [ 1.100537] systemd[1]: Running in initrd. test # [ 1.102672] systemd[1]: Initializing machine ID from random generator. test # [ 1.104786] systemd[1]: Hostname set to . test # [ 1.114124] 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.179430] 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.199727] systemd[1]: Queued start job for default target Initrd Default Target. test # [ 1.201470] 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.208985] systemd[1]: Created slice Slice /system/modprobe. second # [ 1.209925] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 1.211029] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.212217] systemd[1]: Reached target Path Units. second # [ 1.212869] systemd[1]: Reached target Slice Units. second # [ 1.213563] systemd[1]: Reached target Swaps. second # [ 1.214195] systemd[1]: Reached target Timer Units. second # [ 1.215073] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.216271] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.217260] systemd[1]: Listening on Journal Sockets. second # [ 1.218105] systemd[1]: Listening on udev Control Socket. second # [ 1.218993] systemd[1]: Listening on udev Kernel Socket. second # [ 1.219823] systemd[1]: Reached target Socket Units. test # [ 1.213451] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.222004] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.222826] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.223626] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.224434] systemd[1]: Expecting device /dev/disk/by-label/nixos... second # [ 1.230760] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 1.225055] systemd[1]: Reached target Path Units. test # [ 1.225554] systemd[1]: Reached target Slice Units. test # [ 1.226046] systemd[1]: Reached target Swaps. test # [ 1.226476] systemd[1]: Reached target Timer Units. test # [ 1.227107] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.231780] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.227924] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.234461] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.228645] systemd[1]: Listening on Journal Sockets. test # [ 1.229353] systemd[1]: Listening on udev Control Socket. test # [ 1.230018] systemd[1]: Listening on udev Kernel Socket. test # [ 1.230578] systemd[1]: Reached target Socket Units. test # [ 1.232290] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.237794] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 1.244310] systemd[1]: Starting Journal Service... test # [ 1.238473] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.244761] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.253404] systemd[1]: Starting Journal Service... second # [ 1.265184] systemd[1]: Starting Load Kernel Modules... second # [ 1.265254] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 1.271416] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.269479] systemd[1]: Starting Load Kernel Modules... test # [ 1.270336] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 1.284200] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.285019] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 1.287384] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 1.280406] systemd[1]: Starting Coldplug All udev Devices... second # [ 1.288222] systemd[1]: Mounted Kernel Configuration File System. test # [ 1.282423] systemd[1]: Finished Create List of Static Device Nodes. test # [ 1.283803] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 1.290874] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 1.291643] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.295624] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 1.299668] usb 1-2: new high-speed USB device number 3 using ehci-pci test # [ 1.301539] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 1.302517] systemd-journald[73]: Collecting audit messages is disabled. test # [ 1.321327] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.333161] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. second # [ 1.336476] systemd-journald[73]: Collecting audit messages is disabled. second # [ 1.347669] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.341739] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.349251] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.350891] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.345595] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.351527] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. second # [ 1.363595] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 second # [ 1.363808] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.361342] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev second # [ 1.363814] [drm] features: -context_init second # [ 1.364277] [drm] number of scanouts: 1 second # [ 1.364292] [drm] number of cap sets: 0 second # [ 1.379539] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic second # [ 1.379558] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 test # [ 1.375998] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0 test # [ 1.376227] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.376233] [drm] features: -context_init test # [ 1.376721] [drm] number of scanouts: 1 test # [ 1.376739] [drm] number of cap sets: 0 test # [ 1.389619] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic test # [ 1.389637] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0 second # [ 1.399298] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.399629] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.399662] systemd[1]: Reached target Local File Systems. second # [ 1.402538] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.397719] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 1.398126] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.398164] systemd[1]: Reached target Local File Systems. test # [ 1.400979] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 1.406899] Console: switching to colour frame buffer device 160x50 second # [ 1.409940] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.399443] systemd-modules-load[74]: Inserted module 'dm_mod' test # [ 1.409621] systemd[1]: Started Journal Service. test # [ 1.400906] systemd-modules-load[74]: Module 'virtio_balloon' is built in second # [ 1.422151] systemd[1]: Finished Load Kernel Modules. test # [ 1.417633] Console: switching to colour frame buffer device 160x50 second # [ 1.427652] systemd[1]: Starting Apply Kernel Variables... test # [ 1.421357] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.414230] systemd-modules-load[74]: Module 'virtio_console' is built in test # [ 1.420208] systemd-modules-load[74]: Inserted module 'virtio_gpu' test # [ 1.421307] systemd-modules-load[74]: Module 'virtio_rng' is built in second # [ 1.436352] systemd-modules-load[74]: Inserted module 'dm_mod' second # [ 1.437105] systemd-modules-load[74]: Module 'virtio_balloon' is built in second # [ 1.445403] systemd[1]: Started Journal Service. second # [ 1.441876] systemd-modules-load[74]: Module 'virtio_console' is built in second # [ 1.444577] systemd-modules-load[74]: Inserted module 'virtio_gpu' test # [ 1.438343] systemd[1]: Starting Create System Files and Directories... second # [ 1.455144] 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.455368] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0 test # [ 1.444116] systemd[1]: Finished Load Kernel Modules. second # [ 1.460370] systemd-modules-load[74]: Module 'virtio_rng' is built in second # [ 1.461132] systemd[1]: Starting Create System Files and Directories... test # [ 1.461078] systemd[1]: Starting Apply Kernel Variables... test # [ 1.475983] 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.477684] systemd[1]: Finished Apply Kernel Variables. test # [ 1.483740] 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.491226] systemd-udevd[80]: Using default interface naming scheme 'v260'. test # [ 1.492725] systemd[1]: Finished Create System Files and Directories. test # [ 1.493537] systemd[1]: Finished Apply Kernel Variables. test # [ 1.495908] systemd-udevd[81]: Using default interface naming scheme 'v260'. second # [ 1.508856] systemd[1]: Finished Create System Files and Directories. second # [ 1.520762] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 1.518865] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.574732] systemd[1]: Starting Virtual Console Setup... test # [ 1.580616] systemd[1]: Starting Virtual Console Setup... second # [ 1.624443] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.626868] systemd[1]: Finished Virtual Console Setup. test # [ 1.628437] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.630812] systemd[1]: Finished Virtual Console Setup. second # [ 1.896094] systemd[1]: Finished Coldplug All udev Devices. second # [ 1.896673] systemd[1]: Reached target System Initialization. second # [ 1.897194] systemd[1]: Reached target Basic System. test # [ 1.896795] systemd[1]: Finished Coldplug All udev Devices. test # [ 1.897393] systemd[1]: Reached target System Initialization. test # [ 1.897889] systemd[1]: Reached target Basic System. second # [ 1.987515] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.009473] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.006801] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.010709] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.053026] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.055129] systemd[1]: Reached target Initrd Root Device. second # [ 2.057613] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.065268] systemd[1]: Found device /dev/disk/by-label/nixos. test # [ 2.069618] systemd[1]: Reached target Initrd Root Device. test # [ 2.075482] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.102305] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.116418] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.131499] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. test # [ 2.124776] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. second # [ 2.278854] systemd[1]: Mounting /sysroot... test # [ 2.273608] systemd[1]: Mounting /sysroot... second # [ 2.314242] EXT4-fs (vda): mounted filesystem 101b7992-002c-4d68-b56e-3d08df224ebb r/w with ordered data mode. Quota mode: none. test # [ 2.310996] EXT4-fs (vda): mounted filesystem 6860c9da-8862-410c-a7e5-28a7cb01be49 r/w with ordered data mode. Quota mode: none. second # [ 2.312116] systemd[1]: Mounted /sysroot. second # [ 2.313659] systemd[1]: Reached target Initrd Root File System. test # [ 2.305999] systemd[1]: Mounted /sysroot. test # [ 2.307337] systemd[1]: Reached target Initrd Root File System. second # [ 2.317242] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.311951] systemd[1]: Starting Mountpoints Configured in the Real Root... second # [ 2.339349] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.331640] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload. second # [ 2.344854] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.336646] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.339443] systemd[1]: Reloading... second # [ 2.347923] systemd[1]: Reloading... second # [ 2.531788] systemd[1]: Reloading finished in 184 ms. test # [ 2.525086] systemd[1]: Reloading finished in 185 ms. second # [ 2.539852] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace... second # [ 2.542630] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace... second # [ 2.544819] systemd[1]: Starting Load Kernel Module 9pnet_virtio... test # [ 2.536900] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace... test # [ 2.540319] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace... test # [ 2.544115] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 2.552700] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.553917] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.556111] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. test # [ 2.548061] systemd[1]: initrd-parse-etc.service: Deactivated successfully. test # [ 2.550830] systemd[1]: Finished Mountpoints Configured in the Real Root. test # [ 2.552121] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.567875] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 2.569060] systemd[1]: Finished Load Kernel Module 9pnet_virtio. test # [ 2.568126] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 2.569173] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 2.902304] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory second # [ 2.904720] (udev-worker)[100]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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.905409] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory test # [ 2.908058] (udev-worker)[93]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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.919159] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 2.921091] systemd[1]: Stopped Virtual Console Setup. second # [ 2.927290] systemd[1]: Stopping Virtual Console Setup... second # [ 2.927807] systemd[1]: Starting Virtual Console Setup... test # [ 2.924706] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 2.926348] systemd[1]: Stopped Virtual Console Setup. test # [ 2.927179] systemd[1]: Stopping Virtual Console Setup... test # [ 2.933718] systemd[1]: Starting Virtual Console Setup... second # [ 2.947436] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 2.950055] systemd[1]: Finished Virtual Console Setup. test # [ 2.955118] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 2.957644] systemd[1]: Finished Virtual Console Setup. test # [ 3.274591] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 3.283024] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 3.292323] systemd[1]: Mounting /sysroot/nix/.rw-store... test # [ 3.285092] systemd[1]: Mounting /sysroot/nix/.rw-store... test # [ 3.286007] systemd[1]: Mounting /sysroot/run... test # [ 3.292923] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.300169] systemd[1]: Mounting /sysroot/tmp/xchg... second # [ 3.308326] systemd[1]: Mounting /sysroot/run... second # [ 3.312254] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.313758] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.327893] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.328227] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.330312] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.333075] systemd[1]: Mounted /sysroot/run. test # [ 3.336848] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 3.340970] systemd[1]: Mounted /sysroot/tmp/xchg. second # [ 3.364336] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.356814] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.357891] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.364321] systemd[1]: Mounting /sysroot/nix/store... second # [ 3.374059] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.375151] systemd[1]: Mounted /sysroot/run. second # [ 3.387862] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.391642] systemd[1]: Mounted /sysroot/tmp/shared. second # [ 3.392591] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.398490] systemd[1]: Mounted /sysroot/nix/store. test # [ 3.399285] systemd[1]: Reached target Initrd File Systems. test # [ 3.401106] systemd[1]: Starting Find NixOS closure... test # [ 3.408190] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 3.419844] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.420863] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.421857] systemd[1]: Mounting /sysroot/nix/store... test # [ 3.427552] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.438821] systemd[1]: Finished Find NixOS closure. test # [ 3.440174] systemd[1]: Reached target Initrd Default Target. second # [ 3.448723] systemd[1]: Mounted /sysroot/nix/store. test # [ 3.441136] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 3.449527] systemd[1]: Reached target Initrd File Systems. second # [ 3.451063] systemd[1]: Starting Find NixOS closure... second # [ 3.456158] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... test # [ 3.462551] systemd[1]: initrd-cleanup.service: Deactivated successfully. test # [ 3.463439] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.465723] systemd[1]: Stopped target Initrd Default Target. test # [ 3.466595] systemd[1]: Stopped target Basic System. test # [ 3.468217] systemd[1]: Stopped target Initrd Root Device. test # [ 3.468806] systemd[1]: Stopped target Path Units. test # [ 3.470039] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.470744] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.471411] systemd[1]: Stopped target Slice Units. test # [ 3.471895] systemd[1]: Stopped target Socket Units. second # [ 3.480501] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.476213] systemd[1]: Stopped target System Initialization. test # [ 3.476765] systemd[1]: Stopped target Swaps. test # [ 3.477183] systemd[1]: Stopped target Timer Units. test # [ 3.477622] systemd[1]: dbus.socket: Deactivated successfully. test # [ 3.478121] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.478638] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 3.479299] systemd[1]: Stopped Find NixOS closure. test # [ 3.479728] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 3.492376] systemd[1]: Finished Find NixOS closure. test # [ 3.484248] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.484895] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 3.493607] systemd[1]: Reached target Initrd Default Target. second # [ 3.495469] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 3.487710] systemd[1]: Stopped Apply Kernel Variables. test # [ 3.490068] systemd[1]: systemd-modules-load.service: Deactivated successfully. test # [ 3.491935] systemd[1]: Stopped Load Kernel Modules. test # [ 3.494244] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 3.495525] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 3.496441] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. test # [ 3.497121] systemd[1]: Stopped Create System Files and Directories. test # [ 3.497695] systemd[1]: Stopped target Local File Systems. test # [ 3.498206] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 3.498831] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.499477] systemd[1]: Stopped Coldplug All udev Devices. test # [ 3.499990] systemd[1]: Stopping Rule-based Manager for Device Events and Files... test # [ 3.510504] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.519415] systemd[1]: Stopped target Initrd Default Target. test # [ 3.512381] systemd[1]: Stopped Virtual Console Setup. second # [ 3.520849] systemd[1]: Stopped target Basic System. second # [ 3.521711] systemd[1]: Stopped target Initrd Root Device. second # [ 3.524259] systemd[1]: Stopped target Path Units. test # [ 3.516509] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 3.524911] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.517277] systemd[1]: Stopped Rule-based Manager for Device Events and Files. test # [ 3.517967] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 3.518629] systemd[1]: Closed udev Control Socket. second # [ 3.526985] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.519098] systemd[1]: Starting Cleanup udev Database... second # [ 3.527745] systemd[1]: Stopped target Slice Units. test # [ 3.520330] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 3.529793] systemd[1]: Stopped target Socket Units. test # [ 3.522047] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 3.530541] systemd[1]: Stopped target System Initialization. test # [ 3.523253] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 3.532145] systemd[1]: Stopped target Swaps. test # [ 3.525321] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 3.526130] systemd[1]: kmod-static-nodes.service: Deactivated successfully. test # [ 3.526820] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 3.527424] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 3.536125] systemd[1]: Stopped target Timer Units. test # [ 3.528185] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 3.536659] systemd[1]: dbus.socket: Deactivated successfully. test # [ 3.528776] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.537227] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.529422] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.537785] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 3.538514] systemd[1]: Stopped Find NixOS closure. second # [ 3.539066] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 3.543082] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.544486] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 3.547036] systemd[1]: Stopped Apply Kernel Variables. second # [ 3.550981] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 3.553619] systemd[1]: Stopped Load Kernel Modules. second # [ 3.554206] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 3.554948] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. second # [ 3.555671] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. test # [ 3.550400] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.551581] systemd[1]: Finished Cleanup udev Database. second # [ 3.560347] systemd[1]: Stopped Create System Files and Directories. second # [ 3.562461] systemd[1]: Stopped target Local File Systems. second # [ 3.563127] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 3.555068] systemd[1]: Reached target Switch Root. second # [ 3.563830] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.555880] systemd[1]: Starting NixOS Activation... second # [ 3.572192] systemd[1]: Stopped Coldplug All udev Devices. second # [ 3.572779] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 3.573610] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 3.574405] systemd[1]: Stopped Virtual Console Setup. second # [ 3.574907] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 3.575495] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. second # [ 3.581148] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 3.582662] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 3.584129] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 3.588299] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 3.588921] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 3.589587] systemd[1]: Closed udev Control Socket. second # [ 3.590061] systemd[1]: Starting Cleanup udev Database... second # [ 3.590574] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 3.591263] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 3.591831] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 3.594811] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. second # [ 3.595570] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 3.596341] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 3.596946] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.597621] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 3.618406] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. second # [ 3.620896] systemd[1]: Finished Cleanup udev Database. second # [ 3.621470] systemd[1]: Reached target Switch Root. second # [ 3.622540] systemd[1]: Starting NixOS Activation... test # [ 3.758118] initrd-nixos-activation-start[179]: booting system configuration /nix/store/b6rjxxx5hzzmb6gdicva5bdla102ph72-nixos-system-test-test test # [ 3.827333] initrd-nixos-activation-start[179]: running activation script... second # [ 3.841716] initrd-nixos-activation-start[179]: booting system configuration /nix/store/mqrk7mnq0c1pzx0a17lgr5i6b2pcqsp4-nixos-system-second-test second # [ 3.916134] initrd-nixos-activation-start[179]: running activation script... test # [ 4.312907] initrd-nixos-activation-start[202]: setting up /etc... second # [ 4.432502] initrd-nixos-activation-start[202]: setting up /etc... test # [ 4.602073] initrd-nixos-activation-start[202]: setting up age secrets... second # [ 4.716286] initrd-nixos-activation-start[202]: setting up age secrets... test # [ 4.867813] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 4.869872] systemd[1]: Finished NixOS Activation. test # [ 4.872067] systemd[1]: Starting Switch Root... test # [ 4.885201] systemd[1]: Switching root. second # [ 4.937986] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 4.939806] systemd[1]: Finished NixOS Activation. test # [ 4.942344] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). second # [ 4.944150] systemd[1]: Starting Switch Root... second # [ 4.961356] systemd[1]: Switching root. second # [ 5.017182] systemd-journald[73]: Received SIGTERM from PID 1 (systemd). test # [ 5.541115] 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.548968] systemd[1]: Detected virtualization qemu. test # [ 5.550944] systemd[1]: Detected architecture arm64. test # [ 5.553308] systemd[1]: Detected first boot. test # [ 5.558631] systemd[1]: Initializing machine ID from random generator. second # [ 5.618931] 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.626639] systemd[1]: Detected virtualization qemu. second # [ 5.629043] systemd[1]: Detected architecture arm64. second # [ 5.630302] systemd[1]: Detected first boot. second # [ 5.636219] systemd[1]: Initializing machine ID from random generator. test # [ 5.859000] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 5.940605] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 6.057418] systemd[1]: Applying preset policy. second # [ 6.124518] systemd[1]: Applying preset policy. test # [ 6.520783] systemd[1]: Populated /etc with preset unit settings. second # [ 6.570460] systemd[1]: Populated /etc with preset unit settings. test # [ 7.040084] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 7.040980] systemd[1]: Stopped initrd-switch-root.service. test # [ 7.043252] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 7.045502] systemd[1]: Created slice Slice /system/getty. test # [ 7.047134] systemd[1]: Created slice User and Session Slice. test # [ 7.048421] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 7.050078] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 7.051468] systemd[1]: Expecting device /dev/hvc0... test # [ 7.052521] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 7.053733] systemd[1]: Reached target Local Encrypted Volumes. test # [ 7.055356] systemd[1]: Stopped target initrd-fs.target. test # [ 7.056865] systemd[1]: Stopped target initrd-root-fs.target. test # [ 7.058428] systemd[1]: Stopped target initrd-switch-root.target. test # [ 7.060029] systemd[1]: Reached target Virtual Machines and Containers. test # [ 7.061702] systemd[1]: Reached target Path Units. test # [ 7.063170] systemd[1]: Reached target Remote File Systems. test # [ 7.064676] systemd[1]: Reached target Slice Units. test # [ 7.066197] systemd[1]: Reached target Swaps. test # [ 7.071050] systemd[1]: Listening on Process Core Dump Socket. test # [ 7.075045] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 7.080024] systemd[1]: Starting Journal Log Access Socket... test # [ 7.081750] systemd[1]: Listening on Journal Audit Socket. test # [ 7.082963] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 7.084752] systemd[1]: Listening on Network Management Netlink Socket. test # [ 7.086649] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 7.088257] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.090556] systemd[1]: Listening on udev Control Socket. test # [ 7.095605] systemd[1]: Mounting Huge Pages File System... second # [ 7.106559] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 7.099929] systemd[1]: Mounting POSIX Message Queue File System... second # [ 7.107519] systemd[1]: Stopped initrd-switch-root.service. second # [ 7.110294] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 7.105031] systemd[1]: Mounting Kernel Debug File System... second # [ 7.112329] systemd[1]: Created slice Slice /system/getty. second # [ 7.113377] systemd[1]: Created slice User and Session Slice. second # [ 7.114189] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 7.116282] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 7.117574] systemd[1]: Expecting device /dev/hvc0... second # [ 7.118596] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 7.119721] systemd[1]: Reached target Local Encrypted Volumes. test # [ 7.113783] systemd[1]: Mounting Kernel Trace File System... second # [ 7.121413] systemd[1]: Stopped target initrd-fs.target. second # [ 7.122880] systemd[1]: Stopped target initrd-root-fs.target. second # [ 7.124513] systemd[1]: Stopped target initrd-switch-root.target. second # [ 7.126135] systemd[1]: Reached target Virtual Machines and Containers. second # [ 7.127840] systemd[1]: Reached target Path Units. second # [ 7.128733] systemd[1]: Reached target Remote File Systems. second # [ 7.130295] systemd[1]: Reached target Slice Units. test # [ 7.123934] systemd[1]: Starting Create List of Static Device Nodes... second # [ 7.131840] systemd[1]: Reached target Swaps. second # [ 7.137051] systemd[1]: Listening on Process Core Dump Socket. test # [ 7.134762] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 7.141237] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 7.136750] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 7.146511] systemd[1]: Starting Journal Log Access Socket... second # [ 7.148749] systemd[1]: Listening on Journal Audit Socket. second # [ 7.149844] systemd[1]: Listening on Network Management Metrics Varlink Socket. second # [ 7.151731] systemd[1]: Listening on Network Management Netlink Socket. second # [ 7.152984] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 7.147482] systemd[1]: Mounting Kernel Configuration File System... second # [ 7.154055] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki second # [ 7.156501] systemd[1]: Listening on udev Control Socket. test # [ 7.152593] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 7.160819] systemd[1]: Mounting Huge Pages File System... test # [ 7.155638] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 7.165185] systemd[1]: Mounting POSIX Message Queue File System... second # [ 7.172260] systemd[1]: Mounting Kernel Debug File System... test # [ 7.166884] systemd[1]: Starting Load Kernel Module fuse... test # [ 7.168822] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 second # [ 7.181019] systemd[1]: Mounting Kernel Trace File System... second # [ 7.192487] systemd[1]: Starting Create List of Static Device Nodes... second # [ 7.201190] systemd[1]: Starting Load Kernel Module 9pnet_virtio... second # [ 7.202690] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 7.211128] systemd[1]: Mounting Kernel Configuration File System... second # [ 7.212241] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 7.215482] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 7.227367] systemd[1]: Starting Load Kernel Module fuse... second # [ 7.229112] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 7.254860] systemd[1]: Starting Journal Service... test # [ 7.283445] systemd[1]: Starting Load Kernel Modules... second # [ 7.304282] systemd[1]: Starting Journal Service... test # [ 7.316913] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 7.323816] fuse: init (API version 7.45) second # [ 7.332614] systemd[1]: Starting Load Kernel Modules... test # [ 7.342007] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 7.346079] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 7.360196] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... second # [ 7.367523] fuse: init (API version 7.45) second # [ 7.378207] systemd[1]: Starting Remount Root and Kernel File Systems... second # [ 7.381937] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.375844] systemd[1]: Starting Coldplug All udev Devices... test # [ 7.389126] systemd[1]: Listening on Journal Log Access Socket. test # [ 7.395423] systemd[1]: Mounted Huge Pages File System. second # [ 7.402082] systemd[1]: Starting Coldplug All udev Devices... test # [ 7.403666] systemd[1]: Mounted POSIX Message Queue File System. test # [ 7.408018] systemd[1]: Mounted Kernel Debug File System. test # [ 7.413720] systemd-journald[322]: Collecting audit messages is enabled. second # [ 7.427048] systemd[1]: Listening on Journal Log Access Socket. test # [ 7.420870] systemd[1]: Started Journal Service. second # [ 7.429013] systemd[1]: Mounted Huge Pages File System. second # [ 7.432443] systemd[1]: Mounted POSIX Message Queue File System. test # [ 7.407984] systemd[1]: Queued start job for default target Multi-User System. second # [ 7.435347] systemd[1]: Mounted Kernel Debug File System. test # [ 7.423737] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 7.438607] systemd[1]: Mounted Kernel Trace File System. test # [ 7.431023] systemd[1]: Mounted Kernel Trace File System. test # [ 7.431659] systemd[1]: Finished Create List of Static Device Nodes. test # [ 7.434486] systemd-modules-load[323]: Module 'atkbd' is built in second # [ 7.448609] systemd[1]: Finished Create List of Static Device Nodes. second # [ 7.449282] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. test # [ 7.440185] systemd-modules-load[323]: Module 'ctr' is built in second # [ 7.455780] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 7.456401] systemd[1]: Mounted Kernel Configuration File System. second # [ 7.456869] systemd[1]: modprobe@fuse.service: Deactivated successfully. test # [ 7.444829] systemd-modules-load[323]: Module 'loop' is built in test # [ 7.447209] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully. second # [ 7.464779] systemd[1]: Finished Load Kernel Module fuse. test # [ 7.451596] systemd[1]: Finished Load Kernel Module 9pnet_virtio. second # [ 7.473001] systemd[1]: Finished Load Kernel Modules. test # [ 7.460351] systemd[1]: Mounted Kernel Configuration File System. test # [ 7.461135] systemd[1]: modprobe@fuse.service: Deactivated successfully. test # [ 7.471139] EXT4-fs (vda): re-mounted 6860c9da-8862-410c-a7e5-28a7cb01be49. second # [ 7.480269] systemd[1]: Mounting FUSE Control File System... test # [ 7.469058] systemd[1]: Finished Load Kernel Module fuse. test # [ 7.469675] systemd[1]: Mounting FUSE Control File System... test # [ 7.470188] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 7.470832] systemd[1]: Finished Load Kernel Modules. second # [ 7.483978] systemd-journald[302]: Collecting audit messages is enabled. second # [ 7.494956] systemd[1]: Starting Firewall... test # [ 7.482690] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 7.491327] systemd[1]: Queued start job for default target Multi-User System. second # [ 7.495414] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 7.504286] systemd-modules-load[303]: Module 'atkbd' is built in second # [ 7.504965] systemd-modules-load[303]: Module 'ctr' is built in second # [ 7.505599] systemd-modules-load[303]: Module 'loop' is built in test # [ 7.497409] systemd-oomd[324]: No swap; memory pressure usage will be degraded second # [ 7.519330] EXT4-fs (vda): re-mounted 101b7992-002c-4d68-b56e-3d08df224ebb. second # [ 7.522192] systemd[1]: Starting Apply Kernel Variables... test # [ 7.513922] systemd[1]: Starting Firewall... test # [ 7.515764] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 7.519848] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 7.528886] systemd[1]: Starting Load/Save OS Random Seed... second # [ 7.544756] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 7.552762] systemd[1]: Started Journal Service. test # [ 7.542351] systemd[1]: Starting Apply Kernel Variables... test # [ 7.544621] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki second # [ 7.565509] systemd-oomd[304]: No swap; memory pressure usage will be degraded second # [ 7.570989] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 7.573286] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. second # [ 7.584456] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 7.613140] systemd[1]: Mounted FUSE Control File System. second # [ 7.627573] systemd[1]: Mounted FUSE Control File System. second # [ 7.642116] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 7.642870] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore second # [ 7.651349] systemd[1]: Starting Load/Save OS Random Seed... second # [ 7.653351] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki test # [ 7.659140] systemd-journald[322]: Received client request to flush runtime journal. second # [ 7.702658] systemd[1]: Finished Apply Kernel Variables. second # [ 7.716541] systemd[1]: Starting Network Name Resolution... second # [ 7.754361] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 7.765094] systemd-journald[302]: Received client request to flush runtime journal. second # [ 7.877938] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 7.887380] systemd[1]: Finished Load/Save OS Random Seed. test # [ 7.881253] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 7.890687] systemd[1]: Reached target First Boot Complete. second # [ 7.894020] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 7.886563] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 7.889705] systemd[1]: Finished Apply Kernel Variables. second # [ 7.902284] systemd-resolved[332]: Positive Trust Anchors: test # [ 7.895946] systemd[1]: Starting Network Name Resolution... second # [ 7.905427] systemd-resolved[332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 7.900982] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 7.901583] systemd[1]: Reached target Preparation for Local File Systems. test # [ 7.902174] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 7.902809] systemd[1]: Finished Load/Save OS Random Seed. second # [ 7.910764] systemd-resolved[332]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 7.903319] systemd[1]: Reached target First Boot Complete. test # [ 7.908841] systemd-resolved[353]: Positive Trust Anchors: second # [ 7.916545] systemd-resolved[332]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test test # [ 7.911387] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 7.918405] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 7.924271] systemd-resolved[353]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test second # [ 7.951064] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 7.951831] systemd[1]: Reached target Preparation for Local File Systems. second # [ 7.952990] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 7.947876] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 7.969506] systemd-udevd[356]: Using default interface naming scheme 'v260'. test # [ 8.035619] systemd[1]: Mounting /run/wrappers... second # [ 8.057245] systemd-udevd[346]: Using default interface naming scheme 'v260'. second # [ 8.103919] systemd[1]: Mounting /run/wrappers... test # [ 8.108250] systemd[1]: Mounted /run/wrappers. test # [ 8.110277] systemd[1]: Reached target Local File Systems. test # [ 8.116823] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 8.130741] systemd[1]: Starting register-nix-paths.service... test # [ 8.135057] systemd[1]: Starting Create SUID/SGID Wrappers... test # [ 8.135709] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 8.145423] systemd[1]: Starting Save Transient machine-id to Disk... test # [ 8.150486] systemd[1]: Starting Create System Files and Directories... second # [ 8.169712] systemd-resolved[332]: Using system hostname 'second'. second # [ 8.178043] systemd[1]: Mounted /run/wrappers. second # [ 8.180678] systemd[1]: Started Network Name Resolution. test # [ 8.175315] systemd-resolved[353]: Using system hostname 'test'. second # [ 8.185094] systemd[1]: Reached target Local File Systems. second # [ 8.189039] systemd[1]: Listening on Boot Loader Control Service Socket. second # [ 8.193246] systemd[1]: Starting register-nix-paths.service... test # [ 8.190046] systemd[1]: Started Network Name Resolution. second # [ 8.200186] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 8.200933] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 8.205313] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 8.223069] systemd[1]: Starting Create System Files and Directories... test # [ 8.373875] systemd[1]: Finished Create System Files and Directories. second # [ 8.382566] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. second # [ 8.389275] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 8.386303] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 8.403480] systemd[1]: Starting Rebuild Journal Catalog... test # [ 8.416303] systemd[1]: Starting Record System Boot/Shutdown in UTMP... test # [ 8.424689] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 8.439236] systemd[1]: Finished Create System Files and Directories. test # [ 8.435334] systemd[1]: Finished Save Transient machine-id to Disk. second # [ 8.454693] systemd[1]: Starting Rebuild Journal Catalog... second # [ 8.467868] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 8.504573] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 8.577606] systemd[1]: Finished Record System Boot/Shutdown in UTMP. second # [ 8.614876] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 8.650687] systemd[1]: Finished Rebuild Journal Catalog. test # [ 8.669369] systemd[1]: Starting Update is Completed... second # [ 8.703619] systemd[1]: Finished Rebuild Journal Catalog. second # [ 8.711842] systemd[1]: Starting Update is Completed... test # [ 8.758582] systemd[1]: Finished Update is Completed. second # [ 8.798259] systemd[1]: Finished Update is Completed. test # [ 8.831952] systemd[1]: Finished Coldplug All udev Devices. second # [ 8.917076] systemd[1]: Finished Coldplug All udev Devices. test # [ 9.006514] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 9.065424] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 9.304612] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. second # [ 9.350165] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 9.349502] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. test # [ 9.351880] systemd[1]: Finished Create SUID/SGID Wrappers. test # [ 9.362340] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 9.397486] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 9.400270] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 9.421984] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. second # [ 9.632618] systemd[1]: Finished Firewall. second # [ 9.633142] systemd[1]: Reached target Preparation for Network. second # [ 9.636638] systemd[1]: Starting Network Management... test # [ 9.657796] (udev-worker)[412]: Network interface NamePolicy= disabled on kernel command line. test # [ 9.672378] (udev-worker)[411]: Network interface NamePolicy= disabled on kernel command line. test # [ 9.683882] systemd[1]: Finished Firewall. test # [ 9.687351] systemd[1]: Reached target Preparation for Network. second # [ 9.697348] systemd[1]: Finished register-nix-paths.service. second # [ 9.698000] systemd[1]: Reached target System Initialization. second # [ 9.700945] systemd[1]: Started Discard unused filesystem blocks once a week. test # [ 9.693007] systemd[1]: Starting Network Management... second # [ 9.702060] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 9.694051] systemd[1]: Finished register-nix-paths.service. test # [ 9.695822] systemd[1]: Reached target System Initialization. test # [ 9.698446] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 9.706934] systemd[1]: Reached target Timer Units. second # [ 9.707455] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 9.707976] systemd[1]: Listening on Nix Daemon Socket. test # [ 9.699950] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 9.704938] systemd[1]: Reached target Timer Units. test # [ 9.705414] systemd[1]: Listening on D-Bus System Message Bus Socket. test # [ 9.705959] systemd[1]: Listening on Nix Daemon Socket. second # [ 9.719024] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 9.719727] systemd[1]: Listening on Hostname Service Socket. second # [ 9.721738] systemd[1]: Reached target Socket Units. test # [ 9.716798] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 9.723542] systemd[1]: Starting D-Bus System Message Bus... test # [ 9.720552] systemd[1]: Listening on Hostname Service Socket. test # [ 9.721181] systemd[1]: Reached target Socket Units. test # [ 9.729353] systemd[1]: Starting D-Bus System Message Bus... second # [ 9.748762] (udev-worker)[399]: Network interface NamePolicy= disabled on kernel command line. second # [ 9.753129] (udev-worker)[403]: Network interface NamePolicy= disabled on kernel command line. second # [ 9.891708] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.919311] dbus-broker-launch[537]: Looking up NSS user entry for 'systemd-timesync'... test # [ 9.923006] mousedev: PS/2 mouse device common for all mice second # [ 9.933927] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 9.934439] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' test # [ 9.940221] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.942033] dbus-broker-launch[537]: NSS returned no entry for 'systemd-timesync' second # [ 9.943074] dbus-broker-launch[537]: Invalid user-name in /nix/store/9cz1qgn4ivxnbvda5bdsmwsp7adkc73c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"[ 9.952826] mousedev: PS/2 mouse device common for all mice second # test # [ 9.945180] dbus-broker-launch[557]: Looking up NSS user entry for 'systemd-timesync'... test # [ 9.962244] dbus-broker-launch[557]: NSS returned no entry for 'systemd-timesync' test # [ 9.963674] dbus-broker-launch[557]: Invalid user-name in /nix/store/9cz1qgn4ivxnbvda5bdsmwsp7adkc73c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 9.987432] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 9.987946] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 9.987456] 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.993182] 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.998460] systemd-networkd[534]: lo: Link UP second # [ 10.003263] systemd-networkd[534]: lo: Gained carrier second # [ 10.007162] systemd[1]: Started Network Management. second # [ 10.010785] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' second # [ 10.020321] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 10.032135] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 10.034142] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 10.027473] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' second # [ 10.039176] systemd-networkd[534]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 10.030832] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 10.043998] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' test # [ 10.037262] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 10.049048] systemd-networkd[534]: eth1: Link UP second # [ 10.050651] systemd-networkd[534]: eth1: Gained carrier test # [ 10.041404] dbus-broker-launch[557]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 10.052753] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 10.050376] systemd[1]: Started D-Bus System Message Bus. second # [ 10.057718] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' test # [ 10.054009] 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 # [ 10.065138] systemd[1]: Started D-Bus System Message Bus. test # [ 10.059823] 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 # [ 10.067146] systemd-networkd[556]: lo: Link UP test # [ 10.070346] systemd-networkd[556]: lo: Gained carrier test # [ 10.074778] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 10.080826] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 10.081765] systemd-networkd[556]: eth1: Link UP test # [ 10.085589] systemd-networkd[556]: eth1: Gained carrier test # [ 10.088737] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 10.098762] dbus-broker-launch[537]: Ready second # [ 10.102662] systemd[1]: Reached target Basic System. second # [ 10.109271] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 10.115386] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 10.120770] systemd[1]: Starting Network Manager... test # [ 10.112916] dbus-broker-launch[557]: Ready test # [ 10.115748] systemd[1]: Started Network Management. test # [ 10.119241] systemd[1]: Reached target Basic System. second # [ 10.128585] systemd[1]: Started backdoor.service. test # [ 10.124076] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 10.127736] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 10.140808] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 10.155707] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 10.152969] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 10.170679] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 10.169218] systemd[1]: Starting Network Manager... second # [ 10.205343] systemd[1]: Starting Post-Boot Actions... test # [ 10.209484] systemd[1]: Started backdoor.service. test # [ 10.227700] systemd[1]: Starting Import lastlog data into lastlog2 database... second # [ 10.247525] systemd[1]: Started Reset console on configuration changes. second # [ 10.254797] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[549]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 10.263184] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 10.288722] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[566]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets second # [ 10.308577] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. second # [ 10.321043] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. test # [ 10.319253] systemd[1]: Starting Post-Boot Actions... second # [ 10.328783] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.327858] 8g1rx2i5wgny7771myl54j8za1ccscsg-wifi-secrets[567]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 10.344268] 1n5h5lsamc1iqqvp1xy6dcsj8mpn1yiw-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets test # [ 10.355032] systemd[1]: Started Reset console on configuration changes. second # [ 10.383223] systemd[1]: Finished Post-Boot Actions. second # connecting to host... test # [ 10.390628] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... test # [ 10.400860] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 10.418941] nsncd[553]: Sep 02 15:08:29.249 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" second # [ 10.424209] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 10.429215] systemd[1]: Reached target Host and Network Name Lookups. second # [ 10.429801] systemd[1]: Reached target User and Group Name Lookups. second # [ 10.439012] systemd[1]: Starting User Login Management... test # [ 10.448752] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.451122] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. test # [ 10.460623] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 10.461277] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 10.462142] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # connecting to host... second # [ 10.481880] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 10.494587] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 10.518137] nsncd[572]: Sep 02 15:08:29.334 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test # [ 10.527453] systemd[1]: Started Name Service Cache Daemon (nsncd). test: Guest shell says: b'Spawning backdoor root shell...\n' test: connected to guest root shell test: (connecting took 11.01 seconds) test: (finished: waiting for the VM to finish booting, in 11.01 seconds) test # [ 10.547769] systemd[1]: Finished Post-Boot Actions. second # [ 10.594665] systemd[1]: Starting Hostname Service... test # [ 10.583392] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test # [ 10.586524] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 10.590096] systemd[1]: Reached target Host and Network Name Lookups. test # [ 10.592958] systemd[1]: Reached target User and Group Name Lookups. second # [ 10.609374] systemd-logind[582]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 10.610284] systemd-logind[582]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 10.610960] systemd-logind[582]: New seat seat0. test # [ 10.603255] systemd[1]: Starting User Login Management... second # [ 10.617367] systemd[1]: Started User Login Management. second # [ 10.624482] systemd[1]: Starting linger-users.service... test # [ 10.641364] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 10.709840] systemd[1]: linger-users.service: Deactivated successfully. second # [ 10.710476] systemd[1]: Finished linger-users.service. test # [ 10.712484] systemd[1]: Starting Hostname Service... second # [ 10.731054] systemd[1]: Started Hostname Service. test # [ 10.775997] systemd-logind[616]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 10.782506] systemd-logind[616]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 10.783187] systemd-logind[616]: New seat seat0. test # [ 10.790493] systemd[1]: Started User Login Management. test # [ 10.795302] systemd[1]: Starting linger-users.service... second # [ 10.833834] systemd-networkd[534]: eth0: Link UP second # [ 10.834343] systemd-networkd[534]: eth0: Gained carrier second # [ 10.857480] systemd[1]: Started Network Manager. second # [ 10.859199] systemd[1]: Reached target Network. second # [ 10.864418] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... test # [ 10.861757] systemd[1]: linger-users.service: Deactivated successfully. second # [ 10.870376] systemd[1]: Starting Permit User Sessions... test # [ 10.865113] systemd[1]: Finished linger-users.service. test # [ 10.878469] systemd[1]: Started Hostname Service. test # [ 10.976046] systemd-networkd[556]: eth0: Link UP test # [ 10.976587] systemd-networkd[556]: eth0: Gained carrier test # [ 10.996615] systemd[1]: Started Network Manager. test # [ 10.997234] systemd[1]: Reached target Network. test # [ 11.002288] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... test # [ 11.007724] systemd[1]: Starting Permit User Sessions... second # [ 11.028779] systemd[1]: Finished Permit User Sessions. second # [ 11.041852] systemd[1]: Started Getty on tty1. second # [ 11.042365] systemd[1]: Reached target Login Prompts. second # [ 11.086371] systemd-resolved[332]: eth0: Bus client set default route setting: yes second # [ 11.087093] systemd-resolved[332]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 11.159588] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. second # [ 11.161463] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 11.160295] systemd[1]: Finished Permit User Sessions. test # [ 11.180765] systemd[1]: Started Getty on tty1. test # [ 11.181471] systemd[1]: Reached target Login Prompts. test # [ 11.188294] systemd-resolved[353]: eth0: Bus client set default route setting: yes test # [ 11.196206] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.3 test # [ 11.229037] NetworkManager[569]: [1788361710.0431] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty second # [ 11.304405] systemd[1]: Reached target Multi-User System. second # [ 11.308334] systemd[1]: Startup finished in 717ms (kernel) + 4.391s (initrd) + 6.197s (userspace) = 11.306s. test # [ 11.312104] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. test # [ 11.312886] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. test # [ 11.499086] systemd[1]: Reached target Multi-User System. test # [ 11.502925] systemd[1]: Startup finished in 733ms (kernel) + 4.297s (initrd) + 6.470s (userspace) = 11.500s. second # [ 11.700636] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 test # [ 11.783271] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input3 second # [ 11.809786] systemd-networkd[534]: eth1: Gained IPv6LL test: (finished: waiting for unit NetworkManager.service, in 12.28 seconds) test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.05 seconds) test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets second # [ 11.945460] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds) test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection second # [ 11.992341] systemd[1]: Starting Virtual Console Setup... test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds) (finished: run the VM test script, in 12.96 seconds) second # [ 12.013806] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 12.017184] systemd[1]: Stopped Virtual Console Setup. second # [ 12.018274] systemd-logind[582]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) second # [ 12.021844] systemd[1]: Starting Virtual Console Setup... test # [ 12.065188] systemd-networkd[556]: eth1: Gained IPv6LL test script finished in 13.03s cleanup kill QemuMachine (pid 15) second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13) test # [ 12.119170] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. test # [ 12.161193] systemd[1]: Starting Virtual Console Setup... test # [ 12.185435] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 12.187847] systemd[1]: Stopped Virtual Console Setup. test # [ 12.190240] systemd-logind[616]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) test # [ 12.193972] systemd[1]: Starting Virtual Console Setup... kill QemuMachine (pid 14) test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13) (finished: cleanup, in 0.25 seconds)