build01: building 1nmpj8fdcxac60rggajmhx1nkm5v8vjm-vm-test-run-wifi.drv Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600.0 seconds run the VM test script additionally exposed symbols: second, test, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs second: starting vm test: starting vm test: QEMU running (pid 48) test # Disk image does not exist, creating the virtualisation disk image... test # Formatting '/build/vm-state-test/tmp.jOF4eLRqjc', fmt=raw size=1073741824 test # mke2fs 1.47.4 (6-Mar-2025) test # Discarding device blocks: 0/262144 done test # Creating filesystem with 262144 4k blocks and 65536 inodes test # Filesystem UUID: 2af24f21-5934-4bcf-8bcc-e81bcb2eefab test # Superblock backups stored on blocks: test # 32768, 98304, 163840, 229376 test # test # Allocating group tables: 0/8 done test # Writing inode tables: 0/8 done test # Creating journal (8192 blocks): done test # Writing superblocks and filesystem accounting information: 0/8 done test # test # Virtualisation disk image created. test # Starting virtiofs daemons... test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests second # Disk image does not exist, creating the virtualisation disk image... second: QEMU running (pid 47) second # Formatting '/build/vm-state-second/tmp.NRgY7pNB6a', fmt=raw size=1073741824 second # mke2fs 1.47.4 (6-Mar-2025) second # Discarding device blocks: 0/262144 done second # Creating filesystem with 262144 4k blocks and 65536 inodes second # Filesystem UUID: 12635fd7-15f7-42ad-91aa-f3e70fc0184f second # Superblock backups stored on blocks: second # 32768, 98304, 163840, 229376 second # second # Allocating group tables: 0/8 done second # Writing inode tables: 0/8 done second # Creating journal (8192 blocks): done second # Writing superblocks and filesystem accounting information: 0/8 done second # second # Virtualisation disk image created. second # Starting virtiofs daemons... second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1) second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection... second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests (finished: start all VMs, in 0.48 seconds) test: waiting for unit NetworkManager.service test: waiting for the VM to finish booting test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 test # [ 0.000000] KASLR enabled test # [ 0.000000] random: crng init done test # [ 0.000000] Machine model: linux,dummy-virt test # [ 0.000000] efi: UEFI not found. test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] test # [ 0.000000] Zone ranges: test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] DMA32 empty second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026 test # [ 0.000000] Normal empty second # [ 0.000000] KASLR enabled test # [ 0.000000] Device empty second # [ 0.000000] random: crng init done test # [ 0.000000] Movable zone start for each node second # [ 0.000000] Machine model: linux,dummy-virt test # [ 0.000000] Early memory node ranges second # [ 0.000000] efi: UEFI not found. test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f] test # [ 0.000000] psci: probing for conduit method from DT. second # [ 0.000000] Zone ranges: test # [ 0.000000] psci: PSCIv1.3 detected in firmware. second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs second # [ 0.000000] DMA32 empty second # [ 0.000000] Normal empty test # [ 0.000000] psci: Trusted OS migration not required second # [ 0.000000] Device empty test # [ 0.000000] psci: SMC Calling Convention v1.1 second # [ 0.000000] Movable zone start for each node second # [ 0.000000] Early memory node ranges test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 test # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff] test # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000 test # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] psci: probing for conduit method from DT. test # [ 0.000000] CPU features: detected: Spectre-BHB second # [ 0.000000] psci: PSCIv1.3 detected in firmware. test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs second # [ 0.000000] psci: Trusted OS migration not required test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) second # [ 0.000000] psci: SMC Calling Convention v1.1 test # [ 0.000000] CPU features: detected: ARM erratum 1418040 test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296 test # [ 0.000000] alternatives: applying boot alternatives second # [ 0.000000] Detected PIPT I-cache on CPU0 second # [ 0.000000] CPU features: detected: GICv3 CPU interface second # [ 0.000000] CPU features: detected: Spectre-v4 second # [ 0.000000] CPU features: detected: Spectre-BHB second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) second # [ 0.000000] CPU features: detected: ARM erratum 1418040 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/2qarjrgmq4v844l3yxqpspmfvvgifl3v-nixos-system-test-test/init regInfo=/nix/.ro-store/x7rz7p3xa6ipmryscq5jgklv99swn46q-closure-info/registration console=ttyAMA0,115200n8 console=tty0 second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/x7rz7p3xa6ipmryscq5jgklv99swn46q-closure-info/registration", will be passed to user space. second # [ 0.000000] alternatives: applying boot alternatives test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB test # [ 0.000000] software IO TLB: area num 1. second # [ 0.000000] 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/zcaykmk7yj15a72a7sy16f6hdjrmqrds-nixos-system-second-test/init regInfo=/nix/.ro-store/yjyhgsj0zjx42h3pqryn94mywiw2rjgd-closure-info/registration console=ttyAMA0,115200n8 console=tty0 test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) test # [ 0.000000] Fallback order for Node 0: 0 second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yjyhgsj0zjx42h3pqryn94mywiw2rjgd-closure-info/registration", will be passed to user space. test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000000] Policy zone: DMA second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 test # [ 0.000000] allocated 2097152 bytes of page_ext second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) test # [ 0.000000] ftrace: allocating 74966 entries in 294 pages second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB test # [ 0.000000] ftrace: allocated 294 pages with 4 groups second # [ 0.000000] software IO TLB: area num 1. test # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB) test # [ 0.000000] rcu: RCU event tracing is enabled. second # [ 0.000000] Fallback order for Node 0: 0 test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144 test # [ 0.000000] Trampoline variant of Tasks RCU enabled. second # [ 0.000000] Policy zone: DMA test # [ 0.000000] Rude variant of Tasks RCU enabled. second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off test # [ 0.000000] Tracing variant of Tasks RCU enabled. second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. second # [ 0.000000] allocated 2097152 bytes of page_ext test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 second # [ 0.000000] ftrace: allocating 74966 entries in 294 pages second # [ 0.000000] ftrace: allocated 294 pages with 4 groups test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] rcu: Hierarchical RCU implementation. second # [ 0.000000] rcu: RCU event tracing is 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.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1. test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] Trampoline variant of Tasks RCU enabled. test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 second # [ 0.000000] Rude variant of Tasks RCU enabled. test # [ 0.000000] GICv3: 256 SPIs implemented second # [ 0.000000] Tracing variant of Tasks RCU enabled. test # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 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] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] 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] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1) second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1. second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1) second # [ 0.000000] GICv3: 256 SPIs implemented test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 second # [ 0.000000] GICv3: 0 Extended SPIs implemented test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 second # [ 0.000000] Root IRQ handler: gic_handle_irq second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0 test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff] test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1) test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1) second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000 test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.000046] arm-pv: using stolen time PV second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000 test # [ 0.000373] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. test # [ 0.000631] Console: colour dummy device 80x25 test # [ 0.000648] printk: legacy console [tty0] enabled second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040 test # [ 0.000854] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.000859] pid_max: default: 32768 minimum: 301 second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt). test # [ 0.000940] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.001073] landlock: Up and running. second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns test # [ 0.001075] Yama: becoming mindful. second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns test # [ 0.001473] LSM support for eBPF active second # [ 0.000058] arm-pv: using stolen time PV test # [ 0.001638] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.000506] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) test # [ 0.001657] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) second # [ 0.000786] Console: colour dummy device 80x25 test # [ 0.004459] rcu: Hierarchical SRCU implementation. second # [ 0.000802] printk: legacy console [tty0] enabled test # [ 0.004467] rcu: Max phase no-delay instances is 1000. test # [ 0.005411] fsl-mc MSI: its@8080000 domain created second # [ 0.001022] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000) test # [ 0.005624] EFI services will not be available. second # [ 0.001027] pid_max: default: 32768 minimum: 301 test # [ 0.005713] smp: Bringing up secondary CPUs ... test # [ 0.005745] smp: Brought up 1 node, 1 CPU second # [ 0.001104] LSM: initializing lsm=capability,landlock,yama,bpf,ima test # [ 0.005748] SMP: Total of 1 processors activated. second # [ 0.001232] landlock: Up and running. second # [ 0.001235] Yama: becoming mindful. test # [ 0.005750] CPU: All CPU(s) started at EL1 second # [ 0.001649] LSM support for eBPF active test # [ 0.005762] CPU features: detected: 32-bit EL0 Support second # [ 0.001813] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.005765] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.005768] CPU features: detected: Common not Private translations second # [ 0.001834] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) test # [ 0.005770] CPU features: detected: CRC32 instructions second # [ 0.004653] rcu: Hierarchical SRCU implementation. second # [ 0.004660] rcu: Max phase no-delay instances is 1000. test # [ 0.005772] CPU features: detected: Data cache clean to Point of Persistence second # [ 0.005600] fsl-mc MSI: its@8080000 domain created test # [ 0.005774] CPU features: detected: Enhanced Virtualization Traps second # [ 0.005835] EFI services will not be available. test # [ 0.005784] CPU features: detected: RCpc load-acquire (LDAPR) second # [ 0.005920] smp: Bringing up secondary CPUs ... second # [ 0.005946] smp: Brought up 1 node, 1 CPU test # [ 0.005787] CPU features: detected: LSE atomic instructions second # [ 0.005949] SMP: Total of 1 processors activated. test # [ 0.005789] CPU features: detected: Privileged Access Never second # [ 0.005951] CPU: All CPU(s) started at EL1 test # [ 0.005790] CPU features: detected: PMUv3 second # [ 0.005963] CPU features: detected: 32-bit EL0 Support test # [ 0.005792] CPU features: detected: RAS Extension Support test # [ 0.005796] CPU features: detected: Speculative Store Bypassing Safe (SSBS) second # [ 0.005965] CPU features: detected: Data cache clean to the PoU not required for I/D coherence test # [ 0.005818] alternatives: applying system-wide alternatives second # [ 0.005969] CPU features: detected: Common not Private translations test # [ 0.007298] CPU features: detected: Hardware dirty bit management on CPU0 second # [ 0.005971] CPU features: detected: CRC32 instructions second # [ 0.005973] CPU features: detected: Data cache clean to Point of Persistence test # [ 0.007467] Memory: 891292K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 115948K reserved, 32768K cma-reserved) second # [ 0.005975] CPU features: detected: Enhanced Virtualization Traps test # [ 0.007926] devtmpfs: initialized second # [ 0.005985] CPU features: detected: RCpc load-acquire (LDAPR) test # [ 0.009478] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) second # [ 0.005987] CPU features: detected: LSE atomic instructions second # [ 0.005989] CPU features: detected: Privileged Access Never test # [ 0.009500] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). second # [ 0.005991] CPU features: detected: PMUv3 test # [ 0.009629] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL second # [ 0.005993] CPU features: detected: RAS Extension Support test # [ 0.009635] 0 pages in range for non-PLT usage second # [ 0.005996] CPU features: detected: Speculative Store Bypassing Safe (SSBS) test # [ 0.009635] 508224 pages in range for PLT usage test # [ 0.009723] pinctrl core: initialized pinctrl subsystem second # [ 0.006017] alternatives: applying system-wide alternatives test # [ 0.010464] DMI not present or invalid. second # [ 0.007435] CPU features: detected: Hardware dirty bit management on CPU0 test # [ 0.013706] NET: Registered PF_NETLINK/PF_ROUTE protocol family test # [ 0.016159] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations second # [ 0.007601] Memory: 891288K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 115972K reserved, 32768K cma-reserved) test # [ 0.016291] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations second # [ 0.008036] devtmpfs: initialized second # [ 0.009468] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.016443] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.016461] audit: initializing netlink subsys (disabled) second # [ 0.009490] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear). test # [ 0.017082] thermal_sys: Registered thermal governor 'fair_share' second # [ 0.009636] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL test # [ 0.017084] thermal_sys: Registered thermal governor 'bang_bang' second # [ 0.009641] 0 pages in range for non-PLT usage test # [ 0.017087] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.009642] 508224 pages in range for PLT usage test # [ 0.017089] thermal_sys: Registered thermal governor 'user_space' second # [ 0.009755] pinctrl core: initialized pinctrl subsystem second # [ 0.010552] DMI not present or invalid. test # [ 0.017091] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.013787] NET: Registered PF_NETLINK/PF_ROUTE protocol family test # [ 0.017126] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.016179] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations test # [ 0.017143] cpuidle: using governor ladder test # [ 0.017148] cpuidle: using governor menu second # [ 0.016310] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations test # [ 0.017279] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.016464] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations test # [ 0.017302] ASID allocator initialised with 32768 entries test # [ 0.017951] Serial: AMBA PL011 UART driver second # [ 0.016481] audit: initializing netlink subsys (disabled) second # [ 0.017020] thermal_sys: Registered thermal governor 'fair_share' test # [ 0.021350] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.017022] thermal_sys: Registered thermal governor 'bang_bang' test # [ 0.021511] printk: console [ttyAMA0] enabled second # [ 0.017025] thermal_sys: Registered thermal governor 'step_wise' second # [ 0.017027] thermal_sys: Registered thermal governor 'user_space' second # [ 0.017029] thermal_sys: Registered thermal governor 'power_allocator' second # [ 0.017072] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 second # [ 0.017086] cpuidle: using governor ladder second # [ 0.017091] cpuidle: using governor menu second # [ 0.017212] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. second # [ 0.017234] ASID allocator initialised with 32768 entries second # [ 0.017887] Serial: AMBA PL011 UART driver second # [ 0.021581] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1 second # [ 0.021756] printk: console [ttyAMA0] enabled test # [ 0.094590] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages test # [ 0.094604] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page test # [ 0.094607] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages test # [ 0.094611] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.094613] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.094615] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.094617] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.094619] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.095656] fbcon: Taking over console test # [ 0.095672] ACPI: Interpreter disabled. test # [ 0.098261] iommu: Default domain type: Translated test # [ 0.098266] iommu: DMA domain TLB invalidation policy: strict mode test # [ 0.098574] SCSI subsystem initialized second # [ 0.096876] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages second # [ 0.096889] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page second # [ 0.096892] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages second # [ 0.096896] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page test # [ 0.104589] usbcore: registered new interface driver usbfs second # [ 0.096898] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages test # [ 0.104620] usbcore: registered new interface driver hub second # [ 0.096900] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page test # [ 0.104631] usbcore: registered new device driver usb second # [ 0.096902] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages test # [ 0.104858] pps_core: LinuxPPS API ver. 1 registered second # [ 0.096904] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page test # [ 0.104861] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.097866] fbcon: Taking over console test # [ 0.104865] PTP clock support registered second # [ 0.097882] ACPI: Interpreter disabled. test # [ 0.104892] EDAC MC: Ver: 3.0.0 second # [ 0.098549] iommu: Default domain type: Translated test # [ 0.105050] scmi_core: SCMI protocol bus registered test # [ 0.105313] FPGA manager framework second # [ 0.098553] iommu: DMA domain TLB invalidation policy: strict mode second # [ 0.098818] SCSI subsystem initialized second # [ 0.100758] usbcore: registered new interface driver usbfs test # [ 0.110172] vgaarb: loaded second # [ 0.100788] usbcore: registered new interface driver hub test # [ 0.110409] clocksource: Switched to clocksource arch_sys_counter second # [ 0.100798] usbcore: registered new device driver usb second # [ 0.101018] pps_core: LinuxPPS API ver. 1 registered second # [ 0.101021] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti second # [ 0.101032] PTP clock support registered second # [ 0.101064] EDAC MC: Ver: 3.0.0 second # [ 0.110155] scmi_core: SCMI protocol bus registered second # [ 0.110519] FPGA manager framework second # [ 0.114211] vgaarb: loaded second # [ 0.114508] clocksource: Switched to clocksource arch_sys_counter test # [ 0.122880] VFS: Disk quotas dquot_6.6.0 test # [ 0.122909] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.124325] netfs: FS-Cache loaded test # [ 0.124456] pnp: PnP ACPI: disabled test # [ 0.126333] NET: Registered PF_INET protocol family second # [ 0.124773] VFS: Disk quotas dquot_6.6.0 second # [ 0.124798] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) test # [ 0.128248] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) second # [ 0.126282] netfs: FS-Cache loaded second # [ 0.126391] pnp: PnP ACPI: disabled second # [ 0.129653] NET: Registered PF_INET protocol family second # [ 0.129828] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) test # [ 0.152091] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.152117] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) test # [ 0.152129] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) test # [ 0.152167] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) test # [ 0.152234] TCP: Hash tables configured (established 8192 bind 8192) test # [ 0.152331] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) test # [ 0.152389] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.152436] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) test # [ 0.152560] NET: Registered PF_UNIX/PF_LOCAL protocol family test # [ 0.152602] NET: Registered PF_XDP protocol family test # [ 0.152626] PCI: CLS 0 bytes, default 64 test # [ 0.152958] Trying to unpack rootfs image as initramfs... second # [ 0.153760] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) test # [ 0.160536] kvm [1]: HYP mode not available second # [ 0.153783] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) second # [ 0.153795] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) second # [ 0.153833] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) second # [ 0.153899] TCP: Hash tables configured (established 8192 bind 8192) second # [ 0.153998] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear) second # [ 0.154055] UDP hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.154078] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear) second # [ 0.154210] NET: Registered PF_UNIX/PF_LOCAL protocol family second # [ 0.154243] NET: Registered PF_XDP protocol family second # [ 0.154263] PCI: CLS 0 bytes, default 64 second # [ 0.154547] Trying to unpack rootfs image as initramfs... second # [ 0.164730] kvm [1]: HYP mode not available test # [ 0.225382] Initialise system trusted keyrings test # [ 0.226457] workingset: timestamp_bits=42 max_order=18 bucket_order=0 second # [ 0.231130] Initialise system trusted keyrings second # [ 0.231255] workingset: timestamp_bits=42 max_order=18 bucket_order=0 test # [ 0.227758] squashfs: version 4.0 (2009/01/31) Phillip Lougher second # [ 0.231575] squashfs: version 4.0 (2009/01/31) Phillip Lougher test # [ 0.227801] 9p: Installing v9fs 9p2000 file system support second # [ 0.231611] 9p: Installing v9fs 9p2000 file system support second # [ 0.250458] Key type asymmetric registered second # [ 0.250475] Asymmetric key parser 'x509' registered test # [ 0.255582] Key type asymmetric registered test # [ 0.255599] Asymmetric key parser 'x509' registered test # [ 0.255656] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) test # [ 0.255752] io scheduler mq-deadline registered test # [ 0.255756] io scheduler kyber registered test # [ 0.258025] pl061_gpio 9030000.pl061: PL061 GPIO chip registered test # [ 0.258353] ledtrig-cpu: registered to indicate activity on CPUs test # [ 0.261532] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: test # [ 0.261550] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 test # [ 0.261578] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 test # [ 0.261583] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 test # [ 0.261616] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits test # [ 0.261646] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.258570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242) second # [ 0.258689] io scheduler mq-deadline registered test # [ 0.261735] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.258692] io scheduler kyber registered test # [ 0.261756] pci_bus 0000:00: root bus resource [bus 00-ff] test # [ 0.261759] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] test # [ 0.261762] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] test # [ 0.261764] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] test # [ 0.261832] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint test # [ 0.262278] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.263888] pl061_gpio 9030000.pl061: PL061 GPIO chip registered second # [ 0.264229] ledtrig-cpu: registered to indicate activity on CPUs second # [ 0.264528] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges: second # [ 0.264545] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000 second # [ 0.264562] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000 second # [ 0.264567] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 second # [ 0.264596] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits second # [ 0.264616] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] second # [ 0.264685] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00 second # [ 0.264705] pci_bus 0000:00: root bus resource [bus 00-ff] second # [ 0.264709] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] second # [ 0.264711] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] second # [ 0.264714] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] second # [ 0.264783] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint second # [ 0.265235] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint second # [ 0.265367] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.265377] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.265396] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.265406] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.265738] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.265875] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] second # [ 0.265885] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.265904] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.278453] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] second # [ 0.266227] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.278493] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.266357] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] test # [ 0.278521] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.266366] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.278530] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref] second # [ 0.266385] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.278887] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.279018] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f] test # [ 0.279027] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.279046] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.279380] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint test # [ 0.279507] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f] test # [ 0.279516] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.279535] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.279856] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.279983] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.279992] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.280011] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.280020] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.280342] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint test # [ 0.280470] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.280488] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.280814] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint test # [ 0.280942] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.280960] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.286798] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint test # [ 0.281274] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.286932] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f] test # [ 0.281397] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.286941] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.281576] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.286961] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.281703] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.286970] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref] test # [ 0.281721] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.287364] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint test # [ 0.282043] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.287496] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.282169] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.287515] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.282187] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.287847] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint second # [ 0.287977] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.287995] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.288277] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint second # [ 0.288402] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff] second # [ 0.288577] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.288706] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.288725] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.298534] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint second # [ 0.289055] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.298666] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.289185] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.298684] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.289203] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.299022] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint second # [ 0.289519] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint test # [ 0.299245] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] second # [ 0.289647] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.299255] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.289665] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.299273] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] second # [ 0.289988] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint test # [ 0.299601] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint second # [ 0.290205] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f] test # [ 0.299727] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] second # [ 0.290215] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.299736] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] second # [ 0.290234] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.299754] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.300199] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.300206] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.300209] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.300244] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.300275] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.300307] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.300340] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.300370] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.300401] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.300432] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.300462] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned test # [ 0.300492] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned test # [ 0.300578] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned test # [ 0.300609] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned test # [ 0.300621] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned test # [ 0.300633] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned test # [ 0.300645] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned test # [ 0.300658] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.310627] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint test # [ 0.300670] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.310762] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f] test # [ 0.300682] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned second # [ 0.310772] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff] test # [ 0.300695] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.310791] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref] test # [ 0.300708] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.311290] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned test # [ 0.300720] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned second # [ 0.311297] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned test # [ 0.300733] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.311300] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned test # [ 0.300745] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.311335] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned test # [ 0.300757] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned test # [ 0.300770] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.311366] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned test # [ 0.300782] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.311397] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned test # [ 0.300794] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned second # [ 0.311429] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned test # [ 0.300806] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned test # [ 0.300818] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.311459] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned test # [ 0.300844] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.311490] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned test # [ 0.300852] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.311520] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned test # [ 0.300854] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] test # [ 0.301408] pci 0000:00:07.0: enabling device (0000 -> 0002) second # [ 0.311550] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned second # [ 0.311580] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned second # [ 0.311641] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned second # [ 0.311670] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned second # [ 0.311682] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned second # [ 0.311694] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned second # [ 0.311707] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned second # [ 0.311720] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned second # [ 0.311732] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned second # [ 0.311744] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned second # [ 0.311757] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned second # [ 0.311866] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned second # [ 0.311880] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned second # [ 0.311892] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned second # [ 0.311904] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned second # [ 0.311916] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned second # [ 0.311928] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned second # [ 0.311941] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned second # [ 0.311953] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned second # [ 0.311965] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned second # [ 0.311977] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned second # [ 0.312002] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] second # [ 0.312010] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff] second # [ 0.312013] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff] second # [ 0.312572] pci 0000:00:07.0: enabling device (0000 -> 0002) test # [ 0.340026] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) test # [ 0.341968] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) second # [ 0.340223] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) second # [ 0.342149] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) test # [ 0.352666] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.354345] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.359595] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) test # [ 0.364096] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) second # [ 0.361229] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003) second # [ 0.364093] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002) second # [ 0.366053] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) test # [ 0.367088] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002) test # [ 0.368562] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.370146] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.368655] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002) test # [ 0.379650] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) test # [ 0.381246] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) second # [ 0.378963] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002) second # [ 0.380608] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002) second # [ 0.382166] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003) test # [ 0.392025] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) second # [ 0.391694] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003) test # [ 0.396010] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled test # [ 0.396962] msm_serial: driver initialized test # [ 0.397029] SuperH (H)SCI(F) driver initialized test # [ 0.397066] STM32 USART driver initialized second # [ 0.395670] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled second # [ 0.399809] msm_serial: driver initialized second # [ 0.399888] SuperH (H)SCI(F) driver initialized second # [ 0.399928] STM32 USART driver initialized test # [ 0.420740] loop: module loaded test # [ 0.420879] virtio_blk virtio2: 1/0/0 default/read/poll queues test # [ 0.421518] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) second # [ 0.424446] loop: module loaded second # [ 0.424576] virtio_blk virtio2: 1/0/0 default/read/poll queues second # [ 0.425172] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) test # [ 0.426837] megasas: 07.734.00.00-rc1 test # [ 0.427293] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] second # [ 0.428245] megasas: 07.734.00.00-rc1 second # [ 0.428721] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff] test # [ 0.439359] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.439375] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.440999] Using buffer write method test # [ 0.441047] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] second # [ 0.439466] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 second # [ 0.439479] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.441088] Using buffer write method test # [ 0.446359] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.446372] Intel/Sharp Extended Query Table at 0x0031 second # [ 0.441113] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff] second # [ 0.451008] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000 test # [ 0.456761] Using buffer write method test # [ 0.456776] Concatenating MTD devices: second # [ 0.451023] Intel/Sharp Extended Query Table at 0x0031 test # [ 0.456778] (0): "0.flash" second # [ 0.452467] Using buffer write method test # [ 0.456780] (1): "0.flash" second # [ 0.452480] Concatenating MTD devices: test # [ 0.456782] into device "0.flash" second # [ 0.452481] (0): "0.flash" second # [ 0.452483] (1): "0.flash" second # [ 0.452485] into device "0.flash" second # [ 0.666147] Freeing initrd memory: 26916K test # [ 0.666809] Freeing initrd memory: 26892K test # [ 0.674106] tun: Universal TUN/TAP device driver, 1.6 second # [ 0.673619] tun: Universal TUN/TAP device driver, 1.6 test # [ 0.676872] thunder_xcv, ver 1.0 test # [ 0.676888] thunder_bgx, ver 1.0 test # [ 0.676897] nicpf, ver 1.0 test # [ 0.677223] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.677227] e1000: Copyright (c) 1999-2006 Intel Corporation. test # [ 0.677240] e1000e: Intel(R) PRO/1000 Network Driver test # [ 0.677245] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. test # [ 0.677258] igb: Intel(R) Gigabit Ethernet Network Driver test # [ 0.677260] igb: Copyright (c) 2007-2014 Intel Corporation. test # [ 0.677273] igbvf: Intel(R) Gigabit Virtual Function Network Driver test # [ 0.677275] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.677338] sky2: driver version 1.30 second # [ 0.676306] thunder_xcv, ver 1.0 second # [ 0.676323] thunder_bgx, ver 1.0 test # [ 0.678164] usbcore: registered new interface driver usb-storage second # [ 0.676332] nicpf, ver 1.0 test # [ 0.678236] usbcore: registered new interface driver usbserial_generic second # [ 0.676675] e1000: Intel(R) PRO/1000 Network Driver test # [ 0.678242] usbserial: USB Serial support registered for generic second # [ 0.676680] e1000: Copyright (c) 1999-2006 Intel Corporation. second # [ 0.676692] e1000e: Intel(R) PRO/1000 Network Driver second # [ 0.676696] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. second # [ 0.676709] igb: Intel(R) Gigabit Ethernet Network Driver second # [ 0.676711] igb: Copyright (c) 2007-2014 Intel Corporation. second # [ 0.676723] igbvf: Intel(R) Gigabit Virtual Function Network Driver test # [ 0.685084] ehci-pci 0000:00:07.0: EHCI Host Controller second # [ 0.676726] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. test # [ 0.685121] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 second # [ 0.676795] sky2: driver version 1.30 test # [ 0.685302] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 second # [ 0.677629] usbcore: registered new interface driver usb-storage test # [ 0.685529] hv_vmbus: registering driver hyperv_keyboard second # [ 0.677719] usbcore: registered new interface driver usbserial_generic test # [ 0.686099] rtc-pl031 9010000.pl031: registered as rtc0 second # [ 0.677726] usbserial: USB Serial support registered for generic test # [ 0.686128] rtc-pl031 9010000.pl031: setting system clock to 2026-09-29T10:26:53 UTC (1790677613) second # [ 0.678035] hv_vmbus: registering driver hyperv_keyboard test # [ 0.686317] i2c_dev: i2c /dev entries driver second # [ 0.679049] ehci-pci 0000:00:07.0: EHCI Host Controller second # [ 0.679088] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1 second # [ 0.679426] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000 second # [ 0.687627] rtc-pl031 9010000.pl031: registered as rtc0 test # [ 0.691241] sdhci: Secure Digital Host Controller Interface driver second # [ 0.687660] rtc-pl031 9010000.pl031: setting system clock to 2026-09-29T10:26:53 UTC (1790677613) test # [ 0.691246] sdhci: Copyright(c) Pierre Ossman second # [ 0.687905] i2c_dev: i2c /dev entries driver test # [ 0.691396] Synopsys Designware Multimedia Card Interface Driver second # [ 0.689551] sdhci: Secure Digital Host Controller Interface driver second # [ 0.689555] sdhci: Copyright(c) Pierre Ossman test # [ 0.691574] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.692444] hid: raw HID events driver (C) Jiri Kosina second # [ 0.689709] Synopsys Designware Multimedia Card Interface Driver test # [ 0.692575] usbcore: registered new interface driver usbhid second # [ 0.689893] sdhci-pltfm: SDHCI platform and OF driver helper test # [ 0.692577] usbhid: USB HID core driver test # [ 0.693256] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available second # [ 0.693755] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 test # [ 0.694068] drop_monitor: Initializing network drop monitor service second # [ 0.694159] hub 1-0:1.0: USB hub found test # [ 0.694188] NET: Registered PF_INET6 protocol family second # [ 0.694178] hub 1-0:1.0: 6 ports detected test # [ 0.694475] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00 second # [ 0.695876] hid: raw HID events driver (C) Jiri Kosina test # [ 0.699685] hub 1-0:1.0: USB hub found second # [ 0.696014] usbcore: registered new interface driver usbhid test # [ 0.699705] hub 1-0:1.0: 6 ports detected second # [ 0.696017] usbhid: USB HID core driver test # [ 0.700463] Segment Routing with IPv6 test # [ 0.700474] In-situ OAM (IOAM) with IPv6 second # [ 0.696743] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available test # [ 0.700535] NET: Registered PF_PACKET protocol family second # [ 0.697559] drop_monitor: Initializing network drop monitor service test # [ 0.700604] 9pnet: Installing 9P2000 support second # [ 0.697713] NET: Registered PF_INET6 protocol family test # [ 0.700641] Key type dns_resolver registered second # [ 0.698330] Segment Routing with IPv6 second # [ 0.698341] In-situ OAM (IOAM) with IPv6 second # [ 0.698401] NET: Registered PF_PACKET protocol family second # [ 0.698461] 9pnet: Installing 9P2000 support second # [ 0.698498] Key type dns_resolver registered test # [ 0.705895] registered taskstats version 1 test # [ 0.706042] Loading compiled-in X.509 certificates second # [ 0.705825] registered taskstats version 1 second # [ 0.705962] Loading compiled-in X.509 certificates test # [ 0.711605] Demotion targets for Node 0: null test # [ 0.711687] Key type .fscrypt registered test # [ 0.711690] Key type fscrypt-provisioning registered test # [ 0.711801] ima: No TPM chip found, activating TPM-bypass! test # [ 0.711810] ima: Allocated hash algorithm: sha1 test # [ 0.711873] ima: No architecture policies found test # [ 0.712385] input: gpio-keys as /devices/platform/gpio-keys/input/input0 second # [ 0.711371] Demotion targets for Node 0: null second # [ 0.711450] Key type .fscrypt registered second # [ 0.711453] Key type fscrypt-provisioning registered second # [ 0.711558] ima: No TPM chip found, activating TPM-bypass! second # [ 0.711568] ima: Allocated hash algorithm: sha1 second # [ 0.711624] ima: No architecture policies found second # [ 0.712135] input: gpio-keys as /devices/platform/gpio-keys/input/input0 test # [ 0.724716] clk: Disabling unused clocks test # [ 0.724727] PM: genpd: Disabling unused power domains second # [ 0.724961] clk: Disabling unused clocks second # [ 0.724972] PM: genpd: Disabling unused power domains test # [ 0.727747] Freeing unused kernel memory: 4736K test # [ 0.727828] Run /init as init process second # [ 0.728061] Freeing unused kernel memory: 4736K second # [ 0.728148] Run /init as init process test # [ 0.748809] systemd[1]: Successfully made /usr/ read-only. second # [ 0.748909] systemd[1]: Successfully made /usr/ read-only. second # [ 0.938580] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 0.946494] usb 1-1: new high-speed USB device number 2 using ehci-pci test # [ 1.082917] systemd[1]: systemd 261.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.082947] systemd[1]: Detected virtualization qemu. test # [ 1.082987] systemd[1]: Detected architecture arm64. test # [ 1.082996] systemd[1]: Running in initrd. second # [ 1.083043] systemd[1]: systemd 261.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.083768] systemd[1]: Initializing machine ID from random generator. second # [ 1.083072] systemd[1]: Detected virtualization qemu. test # [ 1.084075] systemd[1]: Hostname set to . second # [ 1.083266] systemd[1]: Detected architecture arm64. second # [ 1.083279] systemd[1]: Running in initrd. second # [ 1.084997] systemd[1]: Initializing machine ID from random generator. second # [ 1.085294] systemd[1]: Hostname set to . test # [ 1.105899] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 second # [ 1.105294] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 test # [ 1.194620] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 second # [ 1.194680] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0 test # [ 1.314459] usb 1-2: new high-speed USB device number 3 using ehci-pci second # [ 1.314566] usb 1-2: new high-speed USB device number 3 using ehci-pci test # [ 1.371341] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 1.371358] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 1.445116] systemd[1]: Queued start job for default target Initrd Default Target. second # [ 1.443169] systemd[1]: Queued start job for default target Initrd Default Target. test # [ 1.452346] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.452540] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.452579] systemd[1]: Expecting device /dev/disk/by-label/nixos... test # [ 1.452610] systemd[1]: Reached target Path Units. test # [ 1.452629] systemd[1]: Reached target Slice Units. test # [ 1.452644] systemd[1]: Reached target Swaps. test # [ 1.452660] systemd[1]: Reached target Timer Units. second # [ 1.452744] systemd[1]: Created slice Slice /system/modprobe. test # [ 1.452818] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.452976] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 1.452970] systemd[1]: Listening on Journal Socket (/dev/log). test # [ 1.453122] systemd[1]: Listening on Journal Sockets. second # [ 1.453024] systemd[1]: Expecting device /dev/disk/by-label/nixos... test # [ 1.453219] systemd[1]: Listening on udev Control Socket. test # [ 1.453279] systemd[1]: Listening on udev Kernel Socket. second # [ 1.453059] systemd[1]: Reached target Path Units. test # [ 1.453299] systemd[1]: Reached target Socket Units. second # [ 1.453084] systemd[1]: Reached target Slice Units. test # [ 1.462815] systemd[1]: Starting Create List of Static Device Nodes... second # [ 1.453107] systemd[1]: Reached target Swaps. second # [ 1.453128] systemd[1]: Reached target Timer Units. test # [ 1.462893] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 1.453319] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 1.453503] systemd[1]: Listening on Journal Socket (/dev/log). second # [ 1.453639] systemd[1]: Listening on Journal Sockets. second # [ 1.453799] systemd[1]: Listening on udev Control Socket. second # [ 1.453882] systemd[1]: Listening on udev Kernel Socket. second # [ 1.453908] systemd[1]: Reached target Socket Units. second # [ 1.469859] systemd[1]: Starting Create List of Static Device Nodes... test # [ 1.471675] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.472492] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 second # [ 1.469950] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 1.472619] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 second # [ 1.477054] systemd[1]: Mounting Kernel Configuration File System... test # [ 1.484649] systemd[1]: Starting Journal Service... second # [ 1.482067] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 second # [ 1.482245] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0 test # [ 1.495393] systemd[1]: Starting Load Kernel Modules... test # [ 1.495476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 1.500032] systemd[1]: Starting Journal Service... second # [ 1.511635] systemd[1]: Starting Load Kernel Modules... second # [ 1.511726] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 1.522174] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.530733] systemd[1]: Starting Coldplug All udev Devices... test # [ 1.537390] systemd-journald[71]: Collecting audit messages is disabled. test # [ 1.539188] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.534708] systemd[1]: Finished Create List of Static Device Nodes. second # [ 1.535743] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.537248] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 1.547340] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 1.578671] systemd[1]: Mounted Kernel Configuration File System. second # [ 1.578997] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 1.580312] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 1.591359] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. test # [ 1.591821] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 1.592656] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 1.592894] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. second # [ 1.596074] systemd-journald[71]: Collecting audit messages is disabled. test # [ 1.606508] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev test # [ 1.617123] systemd[1]: Started Journal Service. second # [ 1.614553] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev test # [ 1.616316] systemd-modules-load[72]: Inserted module 'dm_mod' test # [ 1.624181] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 test # [ 1.624387] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.624389] [drm] features: -context_init test # [ 1.624847] [drm] number of scanouts: 1 test # [ 1.624856] [drm] number of cap sets: 0 test # [ 1.621749] systemd-modules-load[72]: Module 'virtio_balloon' is built in second # [ 1.626912] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.627033] systemd[1]: Reached target Preparation for Local File Systems. test # [ 1.624206] systemd-modules-load[72]: Module 'virtio_console' is built in second # [ 1.627053] systemd[1]: Reached target Local File Systems. second # [ 1.628183] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.635436] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic test # [ 1.635447] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 test # [ 1.629418] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 1.636266] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0 second # [ 1.636472] [drm] features: -virgl +edid -resource_blob -host_visible test # [ 1.632262] systemd[1]: Reached target Preparation for Local File Systems. second # [ 1.636474] [drm] features: -context_init second # [ 1.636956] [drm] number of scanouts: 1 second # [ 1.636966] [drm] number of cap sets: 0 test # [ 1.636225] systemd[1]: Reached target Local File Systems. test # [ 1.636862] systemd[1]: Starting Create System Files and Directories... second # [ 1.643358] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic second # [ 1.643366] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0 test # [ 1.642703] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 1.654143] Console: switching to colour frame buffer device 160x50 second # [ 1.663298] Console: switching to colour frame buffer device 160x50 test # [ 1.678211] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.672936] systemd-modules-load[72]: Inserted module 'virtio_gpu' second # [ 1.673628] systemd-modules-load[72]: Inserted module 'dm_mod' test # [ 1.673601] systemd-modules-load[72]: Module 'virtio_rng' is built in second # [ 1.674423] systemd-modules-load[72]: Module 'virtio_balloon' is built in second # [ 1.675206] systemd-modules-load[72]: Module 'virtio_console' is built in second # [ 1.682675] systemd[1]: Started Journal Service. test # [ 1.680105] systemd[1]: Finished Load Kernel Modules. second # [ 1.683995] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device test # [ 1.680642] systemd[1]: Starting Apply Kernel Variables... second # [ 1.689507] systemd[1]: Starting Create System Files and Directories... second # [ 1.692132] systemd-modules-load[72]: Inserted module 'virtio_gpu' second # [ 1.692790] systemd-modules-load[72]: Module 'virtio_rng' is built in test # [ 1.693104] systemd[1]: Finished Create System Files and Directories. second # [ 1.696882] systemd[1]: Finished Load Kernel Modules. test # [ 1.704765] systemd[1]: Finished Apply Kernel Variables. second # [ 1.712257] systemd-udevd[77]: Using default interface naming scheme 'v261'. second # [ 1.713304] systemd[1]: Starting Apply Kernel Variables... test # [ 1.713045] systemd-udevd[80]: Using default interface naming scheme 'v261'. second # [ 1.731556] systemd[1]: Finished Apply Kernel Variables. second # [ 1.736159] systemd[1]: Finished Create System Files and Directories. test # [ 1.737295] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 1.744264] systemd[1]: Started Rule-based Manager for Device Events and Files. test # [ 1.784488] systemd[1]: Starting Virtual Console Setup... second # [ 1.792080] systemd[1]: Starting Virtual Console Setup... test # [ 1.832347] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 1.834539] systemd[1]: Finished Virtual Console Setup. second # [ 1.837328] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 1.838404] systemd[1]: Finished Virtual Console Setup. test # [ 2.038419] systemd[1]: Finished Coldplug All udev Devices. test # [ 2.039033] systemd[1]: Reached target System Initialization. test # [ 2.039565] systemd[1]: Reached target Basic System. second # [ 2.057008] systemd[1]: Finished Coldplug All udev Devices. second # [ 2.057623] systemd[1]: Reached target System Initialization. second # [ 2.058161] systemd[1]: Reached target Basic System. test # [ 2.151389] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.154012] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.172558] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line. second # [ 2.173903] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line. test # [ 2.219428] systemd[1]: Found device /dev/disk/by-label/nixos. test # [ 2.222977] systemd[1]: Reached target Initrd Root Device. test # [ 2.226162] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... second # [ 2.236617] systemd[1]: Found device /dev/disk/by-label/nixos. second # [ 2.245030] systemd[1]: Reached target Initrd Root Device. second # [ 2.248651] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos... test # [ 2.269078] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks second # [ 2.285132] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks test # [ 2.290200] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. second # [ 2.292055] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos. second # [ 2.518643] systemd[1]: Mounting /sysroot... test # [ 2.522956] systemd[1]: Mounting /sysroot... second # [ 2.546944] systemd[1]: Mounted /sysroot. second # [ 2.551479] EXT4-fs (vda): mounted filesystem 12635fd7-15f7-42ad-91aa-f3e70fc0184f r/w with ordered data mode. Quota mode: none. second # [ 2.549536] systemd[1]: Reached target Initrd Root File System. test # [ 2.560040] EXT4-fs (vda): mounted filesystem 2af24f21-5934-4bcf-8bcc-e81bcb2eefab r/w with ordered data mode. Quota mode: none. test # [ 2.554065] systemd[1]: Mounted /sysroot. second # [ 2.552641] systemd[1]: Starting Mountpoints Configured in the Real Root... test # [ 2.555476] systemd[1]: Reached target Initrd Root File System. test # [ 2.559204] systemd[1]: Starting Mountpoints Configured in the Real Root... second # [ 2.575020] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload. test # [ 2.577964] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload. second # [ 2.580950] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.581144] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)... test # [ 2.583762] systemd[1]: Reloading... second # [ 2.584421] systemd[1]: Reloading... test # [ 2.744988] systemd[1]: Reloading finished in 162 ms. second # [ 2.745688] systemd[1]: Reloading finished in 161 ms. test # [ 2.764092] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace... test # [ 2.766487] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace... test # [ 2.768894] systemd[1]: initrd-parse-etc.service: Deactivated successfully. test # [ 2.770341] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.771500] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace... test # [ 2.771586] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.773880] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace... second # [ 2.777061] systemd[1]: initrd-parse-etc.service: Deactivated successfully. second # [ 2.779819] systemd[1]: Finished Mountpoints Configured in the Real Root. second # [ 2.781278] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies. second # [ 2.982201] fuse: init (API version 7.45) test # [ 3.005726] fuse: init (API version 7.45) second # [ 3.009561] virtiofs virtio6: discovered new tag: nix-store second # [ 3.010090] virtiofs virtio6: virtio_fs_setup_dax: No cache capability test # [ 3.015836] virtiofs virtio6: discovered new tag: nix-store test # [ 3.016351] virtiofs virtio6: virtio_fs_setup_dax: No cache capability second # [ 3.016057] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory second # [ 3.020277] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory test # [ 3.020279] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory test # [ 3.024220] (udev-worker)[91]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory second # [ 3.034823] virtiofs virtio7: discovered new tag: shared second # [ 3.035366] virtiofs virtio7: virtio_fs_setup_dax: No cache capability test # [ 3.041593] virtiofs virtio7: discovered new tag: shared test # [ 3.042110] virtiofs virtio7: virtio_fs_setup_dax: No cache capability second # [ 3.039364] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[ 3.045234] virtiofs virtio8: discovered new tag: xchg second # [ 3.045751] virtiofs virtio8: virtio_fs_setup_dax: No cache capability second # test # [ 3.042973] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.052121] virtiofs virtio8: discovered new tag: xchg test # [ 3.052652] virtiofs virtio8: virtio_fs_setup_dax: No cache capability second # [ 3.045522] systemd[1]: Stopped Virtual Console Setup. second # [ 3.046287] systemd[1]: Stopping Virtual Console Setup... test # [ 3.045981] systemd[1]: Stopped Virtual Console Setup. test # [ 3.048324] systemd[1]: Stopping Virtual Console Setup... second # [ 3.048927] systemd[1]: Starting Virtual Console Setup... test # [ 3.052329] systemd[1]: Starting Virtual Console Setup... second # [ 3.071731] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones. second # [ 3.074228] systemd[1]: Finished Virtual Console Setup. test # [ 3.075522] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones. test # [ 3.078073] systemd[1]: Finished Virtual Console Setup. second # [ 3.520290] systemd[1]: Mounting /sysroot/nix/.ro-store... test # [ 3.526259] systemd[1]: Mounting /sysroot/nix/.ro-store... second # [ 3.532302] systemd[1]: Mounting /sysroot/nix/.rw-store... second # [ 3.535687] systemd[1]: Mounting /sysroot/run... test # [ 3.537799] systemd[1]: Mounting /sysroot/nix/.rw-store... test # [ 3.540128] systemd[1]: Mounting /sysroot/run... second # [ 3.547961] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.552298] systemd[1]: Mounting /sysroot/tmp/shared... test # [ 3.571102] systemd[1]: Mounting /sysroot/tmp/xchg... second # [ 3.572340] systemd[1]: Mounting /sysroot/tmp/xchg... test # [ 3.593170] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.597525] systemd[1]: Mounted /sysroot/nix/.rw-store. second # [ 3.604385] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.606978] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.610255] systemd[1]: Mounted /sysroot/run. test # [ 3.610299] systemd[1]: Mounted /sysroot/nix/.ro-store. test # [ 3.612344] systemd[1]: Mounted /sysroot/run. test # [ 3.612925] systemd[1]: Mounted /sysroot/tmp/shared. second # [ 3.615106] systemd[1]: Mounted /sysroot/tmp/shared. test # [ 3.618721] systemd[1]: Mounted /sysroot/tmp/xchg. second # [ 3.620238] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 3.622409] systemd[1]: Mounted /sysroot/tmp/xchg. test # [ 3.626537] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.627706] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.629694] systemd[1]: Mounting /sysroot/nix/store... second # [ 3.638427] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 3.639514] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.656852] systemd[1]: Mounted /sysroot/nix/store. test # [ 3.658159] systemd[1]: Reached target Initrd File Systems. test # [ 3.659484] systemd[1]: Starting Find NixOS closure... test # [ 3.664209] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... test # [ 3.679257] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. test # [ 3.688958] systemd[1]: Finished Find NixOS closure. test # [ 3.689897] systemd[1]: Reached target Initrd Default Target. test # [ 3.691696] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... test # [ 3.705781] systemd[1]: Stopped target Initrd Default Target. test # [ 3.706682] systemd[1]: Stopped target Basic System. test # [ 3.707363] systemd[1]: Stopped target Initrd Root Device. test # [ 3.708215] systemd[1]: Stopped target Path Units. test # [ 3.710621] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. test # [ 3.711454] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. test # [ 3.712233] systemd[1]: Stopped target Slice Units. test # [ 3.713075] systemd[1]: Stopped target Socket Units. test # [ 3.714193] systemd[1]: Stopped target System Initialization. test # [ 3.715436] systemd[1]: Stopped target Swaps. test # [ 3.717629] systemd[1]: Stopped target Timer Units. test # [ 3.720639] systemd[1]: dbus.socket: Deactivated successfully. test # [ 3.722387] systemd[1]: Closed D-Bus System Message Bus Socket. test # [ 3.722952] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. test # [ 3.729046] systemd[1]: Stopped Find NixOS closure. test # [ 3.729521] systemd[1]: Starting rw-sysroot-nix-store.service... test # [ 3.730009] systemd[1]: systemd-sysctl.service: Deactivated successfully. test # [ 3.730542] systemd[1]: Stopped Apply Kernel Variables. test # [ 3.731014] systemd[1]: systemd-modules-load.service: Deactivated successfully. test # [ 3.731590] systemd[1]: Stopped Load Kernel Modules. test # [ 3.733632] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. test # [ 3.734360] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. test # [ 3.735019] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. test # [ 3.735626] systemd[1]: Stopped Create System Files and Directories. test # [ 3.736270] systemd[1]: Stopped target Local File Systems. test # [ 3.736749] systemd[1]: Stopped target Preparation for Local File Systems. test # [ 3.737314] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. test # [ 3.738137] systemd[1]: Stopped Coldplug All udev Devices. test # [ 3.738624] systemd[1]: Stopping Rule-based Manager for Device Events and Files... test # [ 3.739282] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 3.739876] systemd[1]: Stopped Virtual Console Setup. test # [ 3.747580] systemd[1]: initrd-cleanup.service: Deactivated successfully. test # [ 3.748307] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. test # [ 3.749183] systemd[1]: systemd-udevd.service: Deactivated successfully. test # [ 3.752213] systemd[1]: Stopped Rule-based Manager for Device Events and Files. test # [ 3.752894] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. test # [ 3.753528] systemd[1]: Closed udev Control Socket. test # [ 3.755030] systemd[1]: Starting Cleanup udev Database... test # [ 3.755589] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. test # [ 3.757863] systemd[1]: Stopped Create Static Device Nodes in /dev. test # [ 3.758495] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. test # [ 3.759361] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. test # [ 3.760119] systemd[1]: kmod-static-nodes.service: Deactivated successfully. test # [ 3.760755] systemd[1]: Stopped Create List of Static Device Nodes. test # [ 3.761331] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. test # [ 3.761961] systemd[1]: Finished rw-sysroot-nix-store.service. test # [ 3.779739] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. test # [ 3.782025] systemd[1]: Finished Cleanup udev Database. test # [ 3.782522] systemd[1]: Reached target Switch Root. test # [ 3.783185] systemd[1]: Starting NixOS Activation... test # [ 3.855418] initrd-nixos-activation-start[172]: booting system configuration /nix/store/2qarjrgmq4v844l3yxqpspmfvvgifl3v-nixos-system-test-test test # [ 3.882859] initrd-nixos-activation-start[172]: running activation script... test # [ 4.123202] initrd-nixos-activation-start[195]: setting up /etc... test # [ 4.222459] initrd-nixos-activation-start[195]: setting up age secrets... test # [ 4.343429] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. test # [ 4.344307] systemd[1]: Finished NixOS Activation. test # [ 4.348107] systemd[1]: Starting Switch Root... test # [ 4.364672] systemd[1]: Switching root. second # [ 4.519135] systemd[1]: Mounting /sysroot/nix/store... test # [ 4.542680] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). second # [ 4.566047] systemd[1]: Mounted /sysroot/nix/store. second # [ 4.568651] systemd[1]: Reached target Initrd File Systems. second # [ 4.572177] systemd[1]: Starting Find NixOS closure... second # [ 4.580268] systemd[1]: Starting Create Volatile Files and Directories in the Real Root... second # [ 4.625180] systemd[1]: Finished Create Volatile Files and Directories in the Real Root. second # [ 4.630781] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully. second # [ 4.642581] systemd[1]: Finished Find NixOS closure. second # [ 4.645812] systemd[1]: Reached target Initrd Default Target. second # [ 4.647523] systemd[1]: Starting Cleaning Up and Shutting Down Daemons... second # [ 4.681224] systemd[1]: Stopped target Initrd Default Target. second # [ 4.682616] systemd[1]: Stopped target Basic System. second # [ 4.684354] systemd[1]: Stopped target Initrd Root Device. second # [ 4.688218] systemd[1]: Stopped target Path Units. second # [ 4.689085] systemd[1]: systemd-ask-password-console.path: Deactivated successfully. second # [ 4.692121] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. second # [ 4.693270] systemd[1]: Stopped target Slice Units. second # [ 4.693953] systemd[1]: Stopped target Socket Units. second # [ 4.695570] systemd[1]: Stopped target System Initialization. second # [ 4.699103] systemd[1]: Stopped target Swaps. second # [ 4.699715] systemd[1]: Stopped target Timer Units. second # [ 4.700646] systemd[1]: dbus.socket: Deactivated successfully. second # [ 4.702685] systemd[1]: Closed D-Bus System Message Bus Socket. second # [ 4.704337] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully. second # [ 4.714189] systemd[1]: Stopped Find NixOS closure. second # [ 4.714845] systemd[1]: Starting rw-sysroot-nix-store.service... second # [ 4.715615] systemd[1]: systemd-sysctl.service: Deactivated successfully. second # [ 4.720261] systemd[1]: Stopped Apply Kernel Variables. second # [ 4.721020] systemd[1]: systemd-modules-load.service: Deactivated successfully. second # [ 4.721797] systemd[1]: Stopped Load Kernel Modules. second # [ 4.722355] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully. second # [ 4.723188] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root. second # [ 4.730169] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. second # [ 4.730970] systemd[1]: Stopped Create System Files and Directories. second # [ 4.731670] systemd[1]: Stopped target Local File Systems. second # [ 4.735751] systemd[1]: Stopped target Preparation for Local File Systems. second # [ 4.740417] systemd[1]: systemd-udev-trigger.service: Deactivated successfully. second # [ 4.741097] systemd[1]: Stopped Coldplug All udev Devices. second # [ 4.741594] systemd[1]: Stopping Rule-based Manager for Device Events and Files... second # [ 4.742238] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. second # [ 4.742902] systemd[1]: Stopped Virtual Console Setup. second # [ 4.743373] systemd[1]: initrd-cleanup.service: Deactivated successfully. second # [ 4.743963] systemd[1]: Finished Cleaning Up and Shutting Down Daemons. second # [ 4.745327] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully. second # [ 4.745997] systemd[1]: Finished rw-sysroot-nix-store.service. second # [ 4.746535] systemd[1]: systemd-udevd.service: Deactivated successfully. second # [ 4.747134] systemd[1]: Stopped Rule-based Manager for Device Events and Files. second # [ 4.747778] systemd[1]: systemd-udevd-control.socket: Deactivated successfully. second # [ 4.748595] systemd[1]: Closed udev Control Socket. second # [ 4.749073] systemd[1]: Starting Cleanup udev Database... second # [ 4.749570] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. second # [ 4.750254] systemd[1]: Stopped Create Static Device Nodes in /dev. second # [ 4.750812] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. second # [ 4.751520] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully. second # [ 4.756155] systemd[1]: kmod-static-nodes.service: Deactivated successfully. second # [ 4.760114] systemd[1]: Stopped Create List of Static Device Nodes. second # [ 4.773755] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. second # [ 4.775315] systemd[1]: Finished Cleanup udev Database. second # [ 4.775889] systemd[1]: Reached target Switch Root. second # [ 4.779800] systemd[1]: Starting NixOS Activation... second # [ 4.855789] initrd-nixos-activation-start[171]: booting system configuration /nix/store/zcaykmk7yj15a72a7sy16f6hdjrmqrds-nixos-system-second-test second # [ 4.883336] initrd-nixos-activation-start[171]: running activation script... test # [ 5.079625] systemd[1]: systemd 261.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.079721] systemd[1]: Detected virtualization qemu. test # [ 5.079779] systemd[1]: Detected architecture arm64. test # [ 5.079893] systemd[1]: Detected first boot. test # [ 5.091837] systemd[1]: Initializing machine ID from random generator. second # [ 5.105594] initrd-nixos-activation-start[194]: setting up /etc... second # [ 5.206866] initrd-nixos-activation-start[194]: setting up age secrets... second # [ 5.296694] systemd[1]: initrd-nixos-activation.service: Deactivated successfully. second # [ 5.298463] systemd[1]: Finished NixOS Activation. second # [ 5.299224] systemd[1]: Starting Switch Root... second # [ 5.312900] systemd[1]: Switching root. test # [ 5.380004] systemd[1]: bpf-restrict-fs: LSM BPF program attached second # [ 5.486830] systemd-journald[71]: Received SIGTERM from PID 1 (systemd). test # [ 5.513775] systemd[1]: Applying preset policy. test # [ 5.685715] systemd[1]: Populated /etc with preset unit settings. test # [ 5.913908] systemd[1]: initrd-switch-root.service: Deactivated successfully. test # [ 5.914195] systemd[1]: Stopped initrd-switch-root.service. test # [ 5.916720] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. test # [ 5.917654] systemd[1]: Created slice Slice /system/getty. test # [ 5.918228] systemd[1]: Created slice User and Session Slice. test # [ 5.921048] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. test # [ 5.921625] systemd[1]: Started Forward Password Requests to Wall Directory Watch. test # [ 5.922188] systemd[1]: Expecting device /dev/hvc0... test # [ 5.924965] systemd[1]: Expecting device /dev/ttyAMA0... test # [ 5.925461] systemd[1]: Reached target Local Encrypted Volumes. test # [ 5.926045] systemd[1]: Stopped target initrd-fs.target. test # [ 5.928629] systemd[1]: Stopped target initrd-root-fs.target. test # [ 5.929096] systemd[1]: Stopped target initrd-switch-root.target. test # [ 5.929659] systemd[1]: Reached target Virtual Machines and Containers. test # [ 5.930222] systemd[1]: Reached target Path Units. test # [ 5.933121] systemd[1]: Reached target Remote File Systems. test # [ 5.933588] systemd[1]: Reached target Slice Units. test # [ 5.934134] systemd[1]: Reached target Swaps. test # [ 5.937963] systemd[1]: Listening on Query the User Interactively for a Password. test # [ 5.941235] systemd[1]: Listening on Process Core Dump Socket. test # [ 5.943453] systemd[1]: Listening on Credential Encryption/Decryption. test # [ 5.945214] systemd[1]: Listening on Factory Reset Management. test # [ 5.945760] systemd[1]: Listening on Hostname Service Socket. test # [ 5.950343] systemd[1]: Starting Journal Log Access Socket... test # [ 5.952774] systemd[1]: Listening on Journal Audit Socket. test # [ 5.955438] systemd[1]: Listening on Console Output Muting Service Socket. test # [ 5.956118] systemd[1]: Listening on Network Management Metrics Varlink Socket. test # [ 5.956820] systemd[1]: Listening on Network Management Varlink Socket. test # [ 5.957501] systemd[1]: Listening on Network Management Netlink Socket. test # [ 5.958256] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. test # [ 5.962273] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os test # [ 5.964319] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki test # [ 5.969224] systemd[1]: Listening on Disk Repartitioning Service Socket. test # [ 5.969763] systemd[1]: Listening on Resolve Monitor Varlink Socket. test # [ 5.970307] systemd[1]: Listening on Resolve Service Varlink Socket. test # [ 5.972411] systemd[1]: Listening on udev Control Socket. test # [ 5.973001] systemd[1]: Listening on udev Varlink Socket. test # [ 5.976087] systemd[1]: Mounting Huge Pages File System... test # [ 5.977840] systemd[1]: Mounting POSIX Message Queue File System... test # [ 5.989905] systemd[1]: Mounting Kernel Debug File System... test # [ 5.997339] systemd[1]: Mounting Kernel Trace File System... test # [ 6.002517] systemd[1]: Starting Create List of Static Device Nodes... test # [ 6.007232] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 6.016759] systemd[1]: Mounting Kernel Configuration File System... test # [ 6.018991] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm test # [ 6.025052] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore test # [ 6.025681] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 6.015870] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE) second # [ 6.015964] systemd[1]: Detected virtualization qemu. second # [ 6.016023] systemd[1]: Detected architecture arm64. second # [ 6.016143] systemd[1]: Detected first boot. second # [ 6.029300] systemd[1]: Initializing machine ID from random generator. test # [ 6.038382] systemd[1]: Mounting FUSE Control File System... test # [ 6.041265] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 6.066655] systemd[1]: Starting Journal Service... test # [ 6.080430] systemd[1]: Starting Load Kernel Modules... test # [ 6.100336] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 6.114403] systemd[1]: Starting Remount Root and Kernel File Systems... test # [ 6.116330] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 6.131856] systemd[1]: Starting Coldplug All udev Devices... test # [ 6.133921] systemd[1]: Listening on Journal Log Access Socket. test # [ 6.134298] systemd[1]: Mounted Huge Pages File System. test # [ 6.143020] systemd[1]: Mounted POSIX Message Queue File System. test # [ 6.143512] systemd[1]: Mounted Kernel Debug File System. test # [ 6.143877] systemd[1]: Mounted Kernel Trace File System. test # [ 6.148473] systemd[1]: Finished Create List of Static Device Nodes. test # [ 6.151509] systemd[1]: Finished Load Kernel Modules. test # [ 6.164690] systemd[1]: Starting Firewall... test # [ 6.180162] systemd[1]: Starting Apply Kernel Variables... test # [ 6.196994] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... test # [ 6.202571] systemd-journald[320]: Collecting audit messages is enabled. test # [ 6.206478] EXT4-fs (vda): re-mounted 2af24f21-5934-4bcf-8bcc-e81bcb2eefab. test # [ 6.222742] systemd[1]: Queued start job for default target Multi-User System. test # [ 6.224101] systemd[1]: systemd-journald.service: Deactivated successfully. test # [ 6.224907] systemd-modules-load[321]: Module 'atkbd' is built in test # [ 6.225580] systemd-modules-load[321]: Module 'ctr' is built in test # [ 6.226231] systemd-modules-load[321]: Module 'loop' is built in test # [ 6.240985] systemd[1]: Started Journal Service. test # [ 6.241879] systemd[1]: Mounted Kernel Configuration File System. test # [ 6.242512] systemd[1]: Finished Remount Root and Kernel File Systems. test # [ 6.255371] systemd-oomd[322]: No swap; memory pressure usage will be degraded test # [ 6.269467] systemd[1]: Listening on Disk Image Download Service Socket. test # [ 6.273535] systemd[1]: Starting Flush Journal to Persistent Storage... test # [ 6.274214] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore test # [ 6.284167] systemd[1]: Starting Load/Save OS Random Seed... test # [ 6.284767] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 6.285549] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. test # [ 6.307049] systemd[1]: Mounted FUSE Control File System. second # [ 6.315719] systemd[1]: bpf-restrict-fs: LSM BPF program attached test # [ 6.355352] systemd-journald[320]: Received client request to flush runtime journal. test # [ 6.412432] systemd[1]: Finished Apply Kernel Variables. test # [ 6.413121] systemd[1]: Starting Network Name Resolution... test # [ 6.420243] systemd[1]: Finished Load/Save OS Random Seed. test # [ 6.420938] systemd[1]: Reached target First Boot Complete. test # [ 6.421457] systemd[1]: Finished Flush Journal to Persistent Storage. second # [ 6.451965] systemd[1]: Applying preset policy. test # [ 6.490237] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. test # [ 6.496591] systemd[1]: Starting Create Static Device Nodes in /dev... test # [ 6.525042] systemd-resolved[345]: Positive Trust Anchors: test # [ 6.525640] systemd-resolved[345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d test # [ 6.526552] systemd-resolved[345]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 test # [ 6.527423] systemd-resolved[345]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test second # [ 6.636446] systemd[1]: Populated /etc with preset unit settings. test # [ 6.716772] systemd-resolved[345]: Using system hostname 'test'. test # [ 6.720211] systemd[1]: Started Network Name Resolution. test # [ 6.755940] systemd[1]: Finished Coldplug All udev Devices. test # [ 6.758196] systemd[1]: Finished Create Static Device Nodes in /dev. test # [ 6.758828] systemd[1]: Reached target Preparation for Local File Systems. test # [ 6.760279] systemd[1]: Starting Rule-based Manager for Device Events and Files... test # [ 6.811495] systemd-udevd[363]: Using default interface naming scheme 'v261'. second # [ 6.861820] systemd[1]: initrd-switch-root.service: Deactivated successfully. second # [ 6.862125] systemd[1]: Stopped initrd-switch-root.service. second # [ 6.864637] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. second # [ 6.865703] systemd[1]: Created slice Slice /system/getty. second # [ 6.868472] systemd[1]: Created slice User and Session Slice. second # [ 6.869067] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. second # [ 6.869662] systemd[1]: Started Forward Password Requests to Wall Directory Watch. second # [ 6.870242] systemd[1]: Expecting device /dev/hvc0... second # [ 6.873656] systemd[1]: Expecting device /dev/ttyAMA0... second # [ 6.874145] systemd[1]: Reached target Local Encrypted Volumes. second # [ 6.876209] systemd[1]: Stopped target initrd-fs.target. second # [ 6.876713] systemd[1]: Stopped target initrd-root-fs.target. second # [ 6.877338] systemd[1]: Stopped target initrd-switch-root.target. second # [ 6.877988] systemd[1]: Reached target Virtual Machines and Containers. second # [ 6.881154] systemd[1]: Reached target Path Units. second # [ 6.881642] systemd[1]: Reached target Remote File Systems. second # [ 6.882202] systemd[1]: Reached target Slice Units. second # [ 6.884582] systemd[1]: Reached target Swaps. test # [ 6.883439] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 6.887128] systemd[1]: Listening on Query the User Interactively for a Password. second # [ 6.889307] systemd[1]: Listening on Process Core Dump Socket. second # [ 6.892068] systemd[1]: Listening on Credential Encryption/Decryption. second # [ 6.893733] systemd[1]: Listening on Factory Reset Management. second # [ 6.894263] systemd[1]: Listening on Hostname Service Socket. second # [ 6.898303] systemd[1]: Starting Journal Log Access Socket... second # [ 6.900781] systemd[1]: Listening on Journal Audit Socket. second # [ 6.903600] systemd[1]: Listening on Console Output Muting Service Socket. second # [ 6.904266] systemd[1]: Listening on Network Management Metrics Varlink Socket. second # [ 6.904970] systemd[1]: Listening on Network Management Varlink Socket. second # [ 6.905609] systemd[1]: Listening on Network Management Netlink Socket. second # [ 6.906310] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket. second # [ 6.910450] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os second # [ 6.912736] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki test # [ 6.908709] systemd[1]: Mounting /run/wrappers... second # [ 6.917556] systemd[1]: Listening on Disk Repartitioning Service Socket. second # [ 6.918095] systemd[1]: Listening on Resolve Monitor Varlink Socket. second # [ 6.920255] systemd[1]: Listening on Resolve Service Varlink Socket. second # [ 6.920767] systemd[1]: Listening on udev Control Socket. second # [ 6.921337] systemd[1]: Listening on udev Varlink Socket. second # [ 6.924819] systemd[1]: Mounting Huge Pages File System... second # [ 6.927273] systemd[1]: Mounting POSIX Message Queue File System... second # [ 6.934916] systemd[1]: Mounting Kernel Debug File System... second # [ 6.945020] systemd[1]: Mounting Kernel Trace File System... second # [ 6.956057] systemd[1]: Starting Create List of Static Device Nodes... second # [ 6.958363] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 6.972154] systemd[1]: Mounting Kernel Configuration File System... second # [ 6.975604] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm second # [ 6.975974] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore second # [ 6.976240] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 7.001690] systemd[1]: Mounting FUSE Control File System... second # [ 7.002084] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67 test # [ 7.016199] systemd[1]: Mounted /run/wrappers. test # [ 7.017616] systemd[1]: Reached target Local File Systems. second # [ 7.022823] systemd[1]: Starting Journal Service... test # [ 7.022026] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 7.024188] systemd[1]: Starting register-nix-paths.service... test # [ 7.028410] systemd[1]: Starting Create SUID/SGID Wrappers... test # [ 7.029024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. second # [ 7.034609] systemd[1]: Starting Load Kernel Modules... test # [ 7.032818] systemd[1]: Starting Save Transient machine-id to Disk... second # [ 7.041301] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer... test # [ 7.041539] systemd[1]: Starting Create System Files and Directories... test # [ 7.047331] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs second # [ 7.059973] systemd[1]: Starting Remount Root and Kernel File Systems... second # [ 7.060357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os second # [ 7.072413] systemd[1]: Starting Coldplug All udev Devices... second # [ 7.074383] systemd[1]: Listening on Journal Log Access Socket. second # [ 7.087428] systemd[1]: Mounted Huge Pages File System. second # [ 7.087932] systemd[1]: Mounted POSIX Message Queue File System. second # [ 7.088296] systemd[1]: Mounted Kernel Debug File System. second # [ 7.088635] systemd[1]: Mounted Kernel Trace File System. test # [ 7.089268] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 7.098202] systemd[1]: Finished Create List of Static Device Nodes. second # [ 7.102914] systemd[1]: Mounted Kernel Configuration File System. second # [ 7.103438] systemd[1]: Mounted FUSE Control File System. second # [ 7.116636] systemd[1]: Starting Create Static Device Nodes in /dev gracefully... second # [ 7.142965] systemd-journald[298]: Collecting audit messages is enabled. second # [ 7.151853] systemd[1]: Finished Load Kernel Modules. second # [ 7.149721] systemd[1]: Queued start job for default target Multi-User System. second # [ 7.154476] systemd[1]: systemd-journald.service: Deactivated successfully. second # [ 7.155321] systemd-modules-load[299]: Module 'atkbd' is built in second # [ 7.158675] systemd[1]: Starting Firewall... second # [ 7.163896] systemd[1]: Starting Apply Kernel Variables... second # [ 7.164371] systemd[1]: Started Journal Service. second # [ 7.155993] systemd-modules-load[299]: Module 'ctr' is built in second # [ 7.163866] systemd-modules-load[299]: Module 'loop' is built in test # [ 7.164160] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 7.171453] systemd[1]: Finished Save Transient machine-id to Disk. second # [ 7.177430] systemd-oomd[300]: No swap; memory pressure usage will be degraded second # [ 7.191476] EXT4-fs (vda): re-mounted 12635fd7-15f7-42ad-91aa-f3e70fc0184f. second # [ 7.198232] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer. second # [ 7.209964] systemd[1]: Finished Remount Root and Kernel File Systems. second # [ 7.212701] systemd[1]: Listening on Disk Image Download Service Socket. second # [ 7.219278] systemd[1]: Starting Flush Journal to Persistent Storage... second # [ 7.219924] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore second # [ 7.228779] systemd[1]: Starting Load/Save OS Random Seed... test # [ 7.228311] systemd[1]: Finished Create System Files and Directories. second # [ 7.229317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os test # [ 7.234384] systemd[1]: Starting Rebuild Journal Catalog... test # [ 7.243422] systemd[1]: Starting Record System Boot/Shutdown in UTMP... second # [ 7.299087] systemd-journald[298]: Received client request to flush runtime journal. test # [ 7.309992] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. second # [ 7.349091] systemd[1]: Finished Create Static Device Nodes in /dev gracefully. second # [ 7.349924] systemd[1]: Starting Create Static Device Nodes in /dev... second # [ 7.350659] systemd[1]: Finished Load/Save OS Random Seed. second # [ 7.351176] systemd[1]: Reached target First Boot Complete. second # [ 7.360703] systemd[1]: Finished Apply Kernel Variables. second # [ 7.363032] systemd[1]: Starting Network Name Resolution... second # [ 7.363606] systemd[1]: Finished Flush Journal to Persistent Storage. test # [ 7.391670] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 7.425524] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 7.429826] systemd[1]: Finished Rebuild Journal Catalog. test # [ 7.434821] systemd[1]: Starting Update is Completed... test # [ 7.508417] systemd[1]: Finished Update is Completed. second # [ 7.540849] systemd-resolved[328]: Positive Trust Anchors: second # [ 7.541451] systemd-resolved[328]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d second # [ 7.542326] systemd-resolved[328]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 second # [ 7.543246] systemd-resolved[328]: 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.613939] systemd[1]: Finished Create Static Device Nodes in /dev. second # [ 7.614608] systemd[1]: Reached target Preparation for Local File Systems. second # [ 7.618070] systemd[1]: Starting Rule-based Manager for Device Events and Files... second # [ 7.687792] systemd[1]: Finished Coldplug All udev Devices. second # [ 7.708066] systemd-resolved[328]: Using system hostname 'second'. second # [ 7.711038] systemd[1]: Started Network Name Resolution. test # [ 7.712668] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line. test # [ 7.717145] (udev-worker)[395]: Network interface NamePolicy= disabled on kernel command line. second # [ 7.731443] systemd-udevd[335]: Using default interface naming scheme 'v261'. second # [ 7.804502] systemd[1]: Started Rule-based Manager for Device Events and Files. second # [ 7.860583] systemd[1]: Mounting /run/wrappers... test # [ 7.913850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. test # [ 7.914599] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 7.931237] systemd[1]: Mounted /run/wrappers. second # [ 7.932250] systemd[1]: Reached target Local File Systems. second # [ 7.936260] systemd[1]: Listening on Boot Loader Control Service Socket. test # [ 7.940130] systemd[1]: Finished register-nix-paths.service. second # [ 7.941012] systemd[1]: Starting register-nix-paths.service... test # [ 7.940752] systemd[1]: Reached target System Initialization. test # [ 7.941302] systemd[1]: Started Discard unused filesystem blocks once a week. test # [ 7.941942] systemd[1]: Started Daily Cleanup of Temporary Directories. test # [ 7.942560] systemd[1]: Reached target Timer Units. second # [ 7.945619] systemd[1]: Starting Create SUID/SGID Wrappers... second # [ 7.946400] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met. test # [ 7.947856] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 7.950374] systemd[1]: Starting Save Transient machine-id to Disk... test # [ 7.951281] systemd[1]: Listening on Nix Daemon Socket. test # [ 7.955804] systemd[1]: Listening on Authorization Manager Agent Helper. test # [ 7.957649] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. test # [ 7.958441] systemd[1]: Reached target Socket Units. second # [ 7.960088] systemd[1]: Starting Create System Files and Directories... second # [ 7.960737] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs test # [ 7.961403] systemd[1]: Starting D-Bus System Message Bus... test # [ 7.993888] mousedev: PS/2 mouse device common for all mice second # [ 8.001790] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse second # [ 8.091636] systemd[1]: etc-machine\x2did.mount: Deactivated successfully. test # [ 8.097044] dbus-broker-launch[517]: Looking up NSS user entry for 'systemd-timesync'... second # [ 8.102370] systemd[1]: Finished Save Transient machine-id to Disk. test # [ 8.103385] dbus-broker-launch[517]: NSS returned no entry for 'systemd-timesync' test # [ 8.106175] dbus-broker-launch[517]: Invalid user-name in /nix/store/nqlq2n3zxbzbrsy4c4zkc32fs9qsli5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" second # [ 8.140760] systemd[1]: Finished Create System Files and Directories. test # [ 8.139858] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' test # [ 8.145003] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 8.150870] systemd[1]: Starting Rebuild Journal Catalog... test # [ 8.149551] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nb29m4n6sgq50sylz05ljdr1lnihyh37-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 8.159020] systemd[1]: Starting Record System Boot/Shutdown in UTMP... test # [ 8.157879] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' test # [ 8.159624] systemd[1]: Started D-Bus System Message Bus. test # [ 8.166749] systemd[1]: Reached target Basic System. test # [ 8.167258] systemd[1]: Starting Network Manager Script Dispatcher Service... test # [ 8.167883] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 8.176169] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... test # [ 8.207611] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 8.222894] systemd[1]: Condition check resulted in /dev/hvc0 being skipped. test # [ 8.224445] dbus-broker-launch[517]: Ready test # [ 8.229867] systemd[1]: Started backdoor.service. test # [ 8.241662] systemd[1]: Starting Import lastlog data into lastlog2 database... test # [ 8.273610] systemd[1]: Starting Name Service Cache Daemon (nsncd)... second # [ 8.293900] systemd[1]: Finished Record System Boot/Shutdown in UTMP. test # [ 8.302066] systemd[1]: Starting Post-Boot Actions... second # [ 8.330907] systemd[1]: Finished Rebuild Journal Catalog. test # [ 8.333465] 1hd0ky5xzfyzxxy55l8ycm4nknsipgaa-wifi-secrets[535]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all second # [ 8.334933] systemd[1]: Starting Update is Completed... second # [ 8.335508] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped. test # [ 8.347292] pg2l5f6p367x80lzljwyzc6ry5wwfygh-wifi-secrets[537]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one test # [ 8.357961] systemd[1]: Started Reset console on configuration changes. test # [ 8.364688] systemd[1]: Finished Firewall. test # [ 8.365192] systemd[1]: Reached target Preparation for Network. test # [ 8.365848] systemd[1]: Listening on Network Management Resolve Hook Socket. test # [ 8.411074] ffziadw2s3h3i1y9l1d32yxiwmhp3y27-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open second # [ 8.415379] systemd[1]: Finished Update is Completed. test # [ 8.420489] systemd[1]: Starting Network Manager... test # [ 8.451621] systemd[1]: Starting Network Management... test # [ 8.454588] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. test # [ 8.460666] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 8.461347] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully. test # [ 8.462135] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # [ 8.462761] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully. test # [ 8.463542] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. test # connecting to host... test # [ 8.503031] systemd[1]: Started Name Service Cache Daemon (nsncd). test # [ 8.503912] nsncd[544]: Sep 29 10:27:01.326 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" test: Guest shell says: b'Spawning backdoor root shell...\n' test # [ 8.537665] systemd[1]: Finished Post-Boot Actions. test: connected to guest root shell test: (connecting took 8.83 seconds) test: (finished: waiting for the VM to finish booting, in 8.83 seconds) test # [ 8.567345] systemd[1]: Started Network Manager Script Dispatcher Service. test # [ 8.569226] systemd[1]: Reached target Host and Network Name Lookups. test # [ 8.576390] systemd[1]: Reached target User and Group Name Lookups. test # [ 8.590719] systemd[1]: Starting User Login Management... test # [ 8.599768] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 8.602826] (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line. second # [ 8.608365] (udev-worker)[361]: Network interface NamePolicy= disabled on kernel command line. test # [ 8.753751] systemd-logind[595]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) test # [ 8.762739] systemd-logind[595]: Watching system buttons on /dev/input/event0 (gpio-keys) test # [ 8.763499] systemd-logind[595]: New seat seat0. test # [ 8.769168] systemd[1]: Started User Login Management. test # [ 8.772410] systemd[1]: Starting linger-users.service... second # [ 8.791968] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully. second # [ 8.795695] systemd[1]: Finished Create SUID/SGID Wrappers. second # [ 8.841815] systemd[1]: Finished register-nix-paths.service. second # [ 8.842853] systemd[1]: Reached target System Initialization. second # [ 8.845582] systemd[1]: Started Discard unused filesystem blocks once a week. second # [ 8.846257] systemd[1]: Started Daily Cleanup of Temporary Directories. second # [ 8.846857] systemd[1]: Reached target Timer Units. second # [ 8.847338] systemd[1]: Listening on D-Bus System Message Bus Socket. second # [ 8.847931] systemd[1]: Listening on Nix Daemon Socket. test # [ 8.865970] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 8.858505] systemd[1]: Listening on Authorization Manager Agent Helper. second # [ 8.860249] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. second # [ 8.861067] systemd[1]: Reached target Socket Units. second # [ 8.864087] systemd[1]: Starting D-Bus System Message Bus... test # [ 8.864970] systemd[1]: Starting Hostname Service... test # [ 8.898210] systemd[1]: linger-users.service: Deactivated successfully. second # [ 8.905037] mousedev: PS/2 mouse device common for all mice test # [ 8.901068] systemd[1]: Finished linger-users.service. test # [ 8.933129] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' test # [ 8.933635] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' test # [ 8.990669] systemd[1]: Started Hostname Service. second # [ 8.992754] dbus-broker-launch[491]: Looking up NSS user entry for 'systemd-timesync'... second # [ 8.999047] dbus-broker-launch[491]: NSS returned no entry for 'systemd-timesync' test # [ 8.998468] systemd-networkd[566]: /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.001730] dbus-broker-launch[491]: Invalid user-name in /nix/store/nqlq2n3zxbzbrsy4c4zkc32fs9qsli5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" test # [ 9.007274] systemd-networkd[566]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section. test # [ 9.016159] systemd-networkd[566]: lo: Link UP test # [ 9.016618] systemd-networkd[566]: lo: Gained carrier test # [ 9.017084] systemd[1]: Started Network Management. test # [ 9.025165] systemd-networkd[566]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test # [ 9.032713] systemd-networkd[566]: eth1: Configuring with /etc/systemd/network/40-eth1.network. test # [ 9.033480] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 9.033044] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service' test # [ 9.034134] systemd-networkd[566]: eth1: Link UP test # [ 9.034545] systemd-networkd[566]: eth1: Gained carrier test # [ 9.035010] systemd-networkd[566]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.037655] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service' second # [ 9.042458] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nb29m4n6sgq50sylz05ljdr1lnihyh37-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service' second # [ 9.046027] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service' second # [ 9.055370] systemd[1]: Started D-Bus System Message Bus. second # [ 9.055939] systemd[1]: Reached target Basic System. second # [ 9.059417] systemd[1]: Starting Network Manager Script Dispatcher Service... second # [ 9.061733] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager... second # [ 9.065274] systemd[1]: Started backdoor.service. second # [ 9.083066] systemd[1]: Starting Import lastlog data into lastlog2 database... second # [ 9.096301] systemd[1]: Starting Name Service Cache Daemon (nsncd)... test # [ 9.101839] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test # [ 9.105805] systemd-networkd[566]: eth0: Link UP test # [ 9.106286] systemd-networkd[566]: eth0: Gained carrier second # [ 9.119316] dbus-broker-launch[491]: Ready test # [ 9.124714] systemd[1]: Started Network Manager. second # [ 9.126868] systemd[1]: Starting Post-Boot Actions... test # [ 9.127677] systemd[1]: Reached target Network. test # [ 9.132223] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 9.146539] systemd[1]: Started Reset console on configuration changes. test # [ 9.147205] systemd[1]: Starting Permit User Sessions... second # [ 9.237636] 1hd0ky5xzfyzxxy55l8ycm4nknsipgaa-wifi-secrets[509]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all test # [ 9.258664] systemd[1]: Finished Permit User Sessions. second # connecting to host... second # [ 9.276610] systemd[1]: Finished Post-Boot Actions. test # [ 9.276750] systemd[1]: Started Getty on tty1. test # [ 9.277228] systemd[1]: Reached target Login Prompts. second # [ 9.287120] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully. second # [ 9.292295] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager. second # [ 9.309348] systemd[1]: Started Name Service Cache Daemon (nsncd). second # [ 9.309961] systemd[1]: Reached target Host and Network Name Lookups. second # [ 9.310513] systemd[1]: Reached target User and Group Name Lookups. second # [ 9.317547] nsncd[513]: Sep 29 10:27:02.131 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" second # [ 9.328764] systemd[1]: Starting User Login Management... test # [ 9.340409] systemd-resolved[345]: eth0: Bus client set default route setting: yes test # [ 9.344497] systemd-resolved[345]: eth0: Bus client set DNS server list to: 10.0.2.3 second # [ 9.376948] systemd[1]: Started Network Manager Script Dispatcher Service. second # [ 9.381428] systemd[1]: Finished Firewall. second # [ 9.384155] systemd[1]: Reached target Preparation for Network. second # [ 9.384785] systemd[1]: Listening on Network Management Resolve Hook Socket. test # [ 9.386708] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully. second # [ 9.390681] systemd[1]: Starting Network Manager... test # [ 9.391868] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created. second # [ 9.393700] systemd[1]: Starting Network Management... second # [ 9.429752] systemd[1]: Finished Import lastlog data into lastlog2 database. second # [ 9.483949] systemd-logind[549]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard) second # [ 9.488159] systemd-logind[549]: Watching system buttons on /dev/input/event0 (gpio-keys) second # [ 9.492622] systemd-logind[549]: New seat seat0. second # [ 9.493081] systemd[1]: Started User Login Management. second # [ 9.502114] systemd[1]: Starting linger-users.service... test # [ 9.539562] systemd[1]: Reached target Multi-User System. test # [ 9.542072] systemd[1]: Startup finished in 722ms (kernel) + 3.881s (initrd) + 4.937s (userspace) = 9.541s. second # [ 9.562653] systemd[1]: linger-users.service: Deactivated successfully. second # [ 9.567946] systemd[1]: Finished linger-users.service. test # [ 9.604538] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input3 second # [ 9.646219] cfg80211: Loading compiled-in X.509 certificates for regulatory database second # [ 9.722068] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' second # [ 9.723387] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' second # [ 9.732266] systemd[1]: Starting Hostname Service... second # [ 9.762807] systemd-networkd[564]: /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.769034] systemd-networkd[564]: /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.778632] systemd-networkd[564]: lo: Link UP second # [ 9.779121] systemd-networkd[564]: lo: Gained carrier second # [ 9.784088] systemd[1]: Started Network Management. second # [ 9.791777] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network second # [ 9.802488] systemd-networkd[564]: eth1: Configuring with /etc/systemd/network/40-eth1.network. second # [ 9.803256] systemd[1]: Starting Enable Persistent Storage in systemd-networkd... second # [ 9.803898] systemd-networkd[564]: eth1: Link UP second # [ 9.810520] systemd-networkd[564]: eth1: Gained carrier second # [ 9.811019] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network test: (finished: waiting for unit NetworkManager.service, in 10.12 seconds) test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.02 seconds) test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open second # [ 9.873562] systemd[1]: Started Hostname Service. second # [ 9.890485] systemd[1]: Finished Enable Persistent Storage in systemd-networkd. test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.05 seconds) test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection second # [ 9.941687] systemd-networkd[564]: eth0: Link UP second # [ 9.942197] systemd-networkd[564]: eth0: Gained carrier test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds) (finished: run the VM test script, in 10.70 seconds) second # [ 9.961146] systemd[1]: Started Network Manager. second # [ 9.963314] systemd[1]: Reached target Network. second # [ 9.970062] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created... second # [ 9.975777] systemd[1]: Starting Permit User Sessions... test script finished in 10.76s cleanup kill QemuMachine (pid 47) second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) test # [ 10.027630] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down test # [ 10.034285] systemd[1]: Starting Virtual Console Setup... test # [ 10.060068] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. test # [ 10.063438] systemd[1]: Stopped Virtual Console Setup. test # [ 10.068661] systemd[1]: Starting Virtual Console Setup... test # [ 10.085382] systemd-logind[595]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard) kill QemuMachine (pid 48) test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14) test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down (finished: cleanup, in 0.26 seconds)