vm-test-run-wifi
checks.aarch64-linux.wifi
· build #176
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600.0 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 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_ssh10start all VMs11second: starting vm12test: starting vm13test: QEMU running (pid 48)14second # Disk image does not exist, creating the virtualisation disk image...15second: QEMU running (pid 47)16second # Formatting '/build/vm-state-second/tmp.lZVOVKgMB4', fmt=raw size=107374182417test # Disk image does not exist, creating the virtualisation disk image...18second # mke2fs 1.47.4 (6-Mar-2025)19test # Formatting '/build/vm-state-test/tmp.NWCDNfsbo3', fmt=raw size=107374182420second # Discarding device blocks: 0/262144 done21(finished: start all VMs, in 0.51 seconds)22second # Creating filesystem with 262144 4k blocks and 65536 inodes23test: waiting for unit NetworkManager.service24second # Filesystem UUID: 5945dc3c-f424-4c4b-a92a-a34ca44e4b3a25test: waiting for the VM to finish booting26second # Superblock backups stored on blocks:27test # mke2fs 1.47.4 (6-Mar-2025)28second # 32768, 98304, 163840, 22937629test # Discarding device blocks: 0/262144 done30second # 31test # Creating filesystem with 262144 4k blocks and 65536 inodes32second # Allocating group tables: 0/8 done33test # Filesystem UUID: 8a2f08a8-9d27-4e1c-aeb4-f444db10633934second # Writing inode tables: 0/8 done35test # Superblock backups stored on blocks:36second # Creating journal (8192 blocks): done37test # 32768, 98304, 163840, 22937638second # Writing superblocks and filesystem accounting information: 0/8 done39test # 40second # 41test # Allocating group tables: 0/8 done42second # Virtualisation disk image created.43test # Writing inode tables: 0/8 done44second # Starting virtiofs daemons...45test # Creating journal (8192 blocks): done46second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)47test # Writing superblocks and filesystem accounting information: 0/8 done48second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether49test # 50second # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...51test # Virtualisation disk image created.52second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)53test # Starting virtiofs daemons...54second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether55test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)56second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)57test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58second # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether59test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)60second # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...61test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62second # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...63test # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...64second # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests65test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)66second # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests67test # [2026-09-21T10:20:55Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests69test # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...70test # [2026-09-21T10:20:55Z INFO virtiofsd] Waiting for vhost-user socket connection...71test # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests72test # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests73test # [2026-09-21T10:20:55Z INFO virtiofsd] Client connected, servicing requests74second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]75second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202676second # [ 0.000000] KASLR enabled77second # [ 0.000000] random: crng init done78second # [ 0.000000] Machine model: linux,dummy-virt79second # [ 0.000000] efi: UEFI not found.80second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT81second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]82second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]83second # [ 0.000000] Zone ranges:84second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]85second # [ 0.000000] DMA32 empty86test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]87second # [ 0.000000] Normal empty88second # [ 0.000000] Device empty89second # [ 0.000000] Movable zone start for each node90second # [ 0.000000] Early memory node ranges91test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202692test # [ 0.000000] KASLR enabled93second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]94test # [ 0.000000] random: crng init done95test # [ 0.000000] Machine model: linux,dummy-virt96second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]97test # [ 0.000000] efi: UEFI not found.98second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000099test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT100second # [ 0.000000] psci: probing for conduit method from DT.101second # [ 0.000000] psci: PSCIv1.3 detected in firmware.102test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]103second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs104test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]105second # [ 0.000000] psci: Trusted OS migration not required106test # [ 0.000000] Zone ranges:107second # [ 0.000000] psci: SMC Calling Convention v1.1108test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]109test # [ 0.000000] DMA32 empty110test # [ 0.000000] Normal empty111second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)112test # [ 0.000000] Device empty113second # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296114test # [ 0.000000] Movable zone start for each node115second # [ 0.000000] Detected PIPT I-cache on CPU0116test # [ 0.000000] Early memory node ranges117second # [ 0.000000] CPU features: detected: GICv3 CPU interface118test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]119second # [ 0.000000] CPU features: detected: Spectre-v4120test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]121second # [ 0.000000] CPU features: detected: Spectre-BHB122test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000123second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR124test # [ 0.000000] psci: probing for conduit method from DT.125second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)126test # [ 0.000000] psci: PSCIv1.3 detected in firmware.127second # [ 0.000000] CPU features: detected: ARM erratum 1418040128test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs129test # [ 0.000000] psci: Trusted OS migration not required130second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion131test # [ 0.000000] psci: SMC Calling Convention v1.1132second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing133second # [ 0.000000] alternatives: applying boot alternatives134test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)135test # [ 0.000000] percpu: Embedded 76 pages/cpu s186712 r8192 d116392 u311296136test # [ 0.000000] Detected PIPT I-cache on CPU0137test # [ 0.000000] CPU features: detected: GICv3 CPU interface138test # [ 0.000000] CPU features: detected: Spectre-v4139test # [ 0.000000] CPU features: detected: Spectre-BHB140second # [ 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/hg1709ady9mg653qjmzpdz2jn5b23ni1-nixos-system-second-test/init regInfo=/nix/store/y7zhqbry9lxxkj3223dzlcr95kymhl5k-closure-info/registration console=ttyAMA0,115200n8 console=tty0141test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)143test # [ 0.000000] CPU features: detected: ARM erratum 1418040144second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/y7zhqbry9lxxkj3223dzlcr95kymhl5k-closure-info/registration", will be passed to user space.145test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion146second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes147test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing148second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)149test # [ 0.000000] alternatives: applying boot alternatives150second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)151second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB152second # [ 0.000000] software IO TLB: area num 1.153second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)154second # [ 0.000000] Fallback order for Node 0: 0155test # [ 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/x7nsl2qv5glbry1qfmi2vql7jyx5zqy5-nixos-system-test-test/init regInfo=/nix/store/d0y2kly6l6ki399gxwi3gr056c71zms7-closure-info/registration console=ttyAMA0,115200n8 console=tty0156second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144157second # [ 0.000000] Policy zone: DMA158second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off159test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/d0y2kly6l6ki399gxwi3gr056c71zms7-closure-info/registration", will be passed to user space.160second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1161test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes162second # [ 0.000000] allocated 2097152 bytes of page_ext163second # [ 0.000000] ftrace: allocating 74950 entries in 294 pages164test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)165second # [ 0.000000] ftrace: allocated 294 pages with 4 groups166test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)167second # [ 0.000000] rcu: Hierarchical RCU implementation.168second # [ 0.000000] rcu: RCU event tracing is enabled.169test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB170test # [ 0.000000] software IO TLB: area num 1.171second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.172test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)173second # [ 0.000000] Trampoline variant of Tasks RCU enabled.174test # [ 0.000000] Fallback order for Node 0: 0175second # [ 0.000000] Rude variant of Tasks RCU enabled.176second # [ 0.000000] Tracing variant of Tasks RCU enabled.177test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144178test # [ 0.000000] Policy zone: DMA179second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.180test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off181second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1182test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1183test # [ 0.000000] allocated 2097152 bytes of page_ext184second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.185test # [ 0.000000] ftrace: allocating 74950 entries in 294 pages186second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.187test # [ 0.000000] ftrace: allocated 294 pages with 4 groups188test # [ 0.000000] rcu: Hierarchical RCU implementation.189second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.190test # [ 0.000000] rcu: RCU event tracing is enabled.191second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0192test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.193second # [ 0.000000] GICv3: 256 SPIs implemented194test # [ 0.000000] Trampoline variant of Tasks RCU enabled.195second # [ 0.000000] GICv3: 0 Extended SPIs implemented196test # [ 0.000000] Rude variant of Tasks RCU enabled.197second # [ 0.000000] Root IRQ handler: gic_handle_irq198test # [ 0.000000] Tracing variant of Tasks RCU enabled.199second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI200second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0201test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.202second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000203test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1204second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]205test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.206second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)207test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)209test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.210second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000211test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0212second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000213test # [ 0.000000] GICv3: 256 SPIs implemented214test # [ 0.000000] GICv3: 0 Extended SPIs implemented215second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.216test # [ 0.000000] Root IRQ handler: gic_handle_irq217test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI218second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns219test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0220second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040221test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000222second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).223test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]224second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns225test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)226second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns227test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)228second # [ 0.000048] arm-pv: using stolen time PV229test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000230second # [ 0.000292] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)231test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000232second # [ 0.000550] Console: colour dummy device 80x25233second # [ 0.000565] printk: legacy console [tty0] enabled234test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.235second # [ 0.000774] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)236test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns237second # [ 0.000779] pid_max: default: 32768 minimum: 301238test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040239second # [ 0.000851] LSM: initializing lsm=capability,landlock,yama,bpf,ima240test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).241second # [ 0.000962] landlock: Up and running.242second # [ 0.000965] Yama: becoming mindful.243second # [ 0.001251] LSM support for eBPF active244test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns245test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns246second # [ 0.001406] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)247test # [ 0.000047] arm-pv: using stolen time PV248second # [ 0.001425] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)249test # [ 0.000317] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)250second # [ 0.004158] rcu: Hierarchical SRCU implementation.251test # [ 0.000565] Console: colour dummy device 80x25252second # [ 0.004164] rcu: Max phase no-delay instances is 1000.253test # [ 0.000581] printk: legacy console [tty0] enabled254second # [ 0.005159] fsl-mc MSI: its@8080000 domain created255second # [ 0.005308] EFI services will not be available.256test # [ 0.000777] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)257second # [ 0.005396] smp: Bringing up secondary CPUs ...258test # [ 0.000782] pid_max: default: 32768 minimum: 301259second # [ 0.005422] smp: Brought up 1 node, 1 CPU260second # [ 0.005425] SMP: Total of 1 processors activated.261test # [ 0.000860] LSM: initializing lsm=capability,landlock,yama,bpf,ima262second # [ 0.005427] CPU: All CPU(s) started at EL1263test # [ 0.000984] landlock: Up and running.264test # [ 0.000986] Yama: becoming mindful.265second # [ 0.005437] CPU features: detected: 32-bit EL0 Support266test # [ 0.001403] LSM support for eBPF active267second # [ 0.005440] CPU features: detected: Data cache clean to the PoU not required for I/D coherence268test # [ 0.001575] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)269second # [ 0.005443] CPU features: detected: Common not Private translations270test # [ 0.001595] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)271second # [ 0.005445] CPU features: detected: CRC32 instructions272test # [ 0.004291] rcu: Hierarchical SRCU implementation.273second # [ 0.005447] CPU features: detected: Data cache clean to Point of Persistence274test # [ 0.004297] rcu: Max phase no-delay instances is 1000.275second # [ 0.005450] CPU features: detected: Enhanced Virtualization Traps276test # [ 0.005280] fsl-mc MSI: its@8080000 domain created277test # [ 0.005455] EFI services will not be available.278second # [ 0.005460] CPU features: detected: RCpc load-acquire (LDAPR)279test # [ 0.005539] smp: Bringing up secondary CPUs ...280second # [ 0.005463] CPU features: detected: LSE atomic instructions281test # [ 0.005567] smp: Brought up 1 node, 1 CPU282second # [ 0.005465] CPU features: detected: Privileged Access Never283test # [ 0.005569] SMP: Total of 1 processors activated.284second # [ 0.005466] CPU features: detected: PMUv3285test # [ 0.005571] CPU: All CPU(s) started at EL1286second # [ 0.005468] CPU features: detected: RAS Extension Support287test # [ 0.005582] CPU features: detected: 32-bit EL0 Support288second # [ 0.005472] CPU features: detected: Speculative Store Bypassing Safe (SSBS)289test # [ 0.005585] CPU features: detected: Data cache clean to the PoU not required for I/D coherence290second # [ 0.005492] alternatives: applying system-wide alternatives291test # [ 0.005588] CPU features: detected: Common not Private translations292second # [ 0.006841] CPU features: detected: Hardware dirty bit management on CPU0293test # [ 0.005590] CPU features: detected: CRC32 instructions294test # [ 0.005592] CPU features: detected: Data cache clean to Point of Persistence295second # [ 0.007040] Memory: 891416K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115832K reserved, 32768K cma-reserved)296test # [ 0.005595] CPU features: detected: Enhanced Virtualization Traps297second # [ 0.007473] devtmpfs: initialized298test # [ 0.005604] CPU features: detected: RCpc load-acquire (LDAPR)299second # [ 0.008910] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)300test # [ 0.005606] CPU features: detected: LSE atomic instructions301test # [ 0.005608] CPU features: detected: Privileged Access Never302second # [ 0.008932] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).303test # [ 0.005610] CPU features: detected: PMUv3304second # [ 0.009058] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL305test # [ 0.005612] CPU features: detected: RAS Extension Support306second # [ 0.009061] 0 pages in range for non-PLT usage307second # [ 0.009062] 508272 pages in range for PLT usage308test # [ 0.005616] CPU features: detected: Speculative Store Bypassing Safe (SSBS)309second # [ 0.009160] pinctrl core: initialized pinctrl subsystem310test # [ 0.005635] alternatives: applying system-wide alternatives311second # [ 0.009890] DMI not present or invalid.312test # [ 0.007006] CPU features: detected: Hardware dirty bit management on CPU0313second # [ 0.013166] NET: Registered PF_NETLINK/PF_ROUTE protocol family314second # [ 0.015826] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations315test # [ 0.007169] Memory: 891452K/1048576K available (24448K kernel code, 7094K rwdata, 26372K rodata, 4736K init, 1107K bss, 115816K reserved, 32768K cma-reserved)316second # [ 0.015960] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations317test # [ 0.007557] devtmpfs: initialized318second # [ 0.016132] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations319test # [ 0.009080] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)320second # [ 0.016148] audit: initializing netlink subsys (disabled)321test # [ 0.009104] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).322second # [ 0.016689] thermal_sys: Registered thermal governor 'fair_share'323test # [ 0.009229] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL324second # [ 0.016691] thermal_sys: Registered thermal governor 'bang_bang'325test # [ 0.009232] 0 pages in range for non-PLT usage326second # [ 0.016694] thermal_sys: Registered thermal governor 'step_wise'327test # [ 0.009232] 508272 pages in range for PLT usage328second # [ 0.016696] thermal_sys: Registered thermal governor 'user_space'329test # [ 0.009332] pinctrl core: initialized pinctrl subsystem330test # [ 0.010072] DMI not present or invalid.331second # [ 0.016698] thermal_sys: Registered thermal governor 'power_allocator'332test # [ 0.013572] NET: Registered PF_NETLINK/PF_ROUTE protocol family333second # [ 0.016726] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1334test # [ 0.015967] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations335second # [ 0.016745] cpuidle: using governor ladder336second # [ 0.016750] cpuidle: using governor menu337test # [ 0.016121] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations338second # [ 0.016865] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.339second # [ 0.016884] ASID allocator initialised with 32768 entries340test # [ 0.016274] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations341second # [ 0.017528] Serial: AMBA PL011 UART driver342test # [ 0.016290] audit: initializing netlink subsys (disabled)343second # [ 0.020591] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1344test # [ 0.016805] thermal_sys: Registered thermal governor 'fair_share'345second # [ 0.020748] printk: console [ttyAMA0] enabled346test # [ 0.016807] thermal_sys: Registered thermal governor 'bang_bang'347test # [ 0.016811] thermal_sys: Registered thermal governor 'step_wise'348test # [ 0.016812] thermal_sys: Registered thermal governor 'user_space'349test # [ 0.016814] thermal_sys: Registered thermal governor 'power_allocator'350test # [ 0.016835] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1351test # [ 0.016854] cpuidle: using governor ladder352test # [ 0.016858] cpuidle: using governor menu353test # [ 0.016963] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.354test # [ 0.016982] ASID allocator initialised with 32768 entries355test # [ 0.017612] Serial: AMBA PL011 UART driver356test # [ 0.020739] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1357test # [ 0.020868] printk: console [ttyAMA0] enabled358second # [ 0.094098] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages359second # [ 0.094116] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page360second # [ 0.094120] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages361second # [ 0.094126] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page362second # [ 0.094129] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages363second # [ 0.094132] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page364second # [ 0.094135] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages365second # [ 0.094138] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page366test # [ 0.093314] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages367test # [ 0.093332] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page368test # [ 0.093336] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages369test # [ 0.093342] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page370second # [ 0.099134] fbcon: Taking over console371second # [ 0.099149] ACPI: Interpreter disabled.372test # [ 0.093345] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages373second # [ 0.100333] iommu: Default domain type: Translated374test # [ 0.093348] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page375second # [ 0.100343] iommu: DMA domain TLB invalidation policy: strict mode376test # [ 0.093351] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages377second # [ 0.101487] SCSI subsystem initialized378test # [ 0.093354] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page379second # [ 0.102057] usbcore: registered new interface driver usbfs380second # [ 0.102096] usbcore: registered new interface driver hub381second # [ 0.102105] usbcore: registered new device driver usb382test # [ 0.098344] fbcon: Taking over console383test # [ 0.098359] ACPI: Interpreter disabled.384second # [ 0.103541] pps_core: LinuxPPS API ver. 1 registered385test # [ 0.099582] iommu: Default domain type: Translated386second # [ 0.103553] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>387test # [ 0.099592] iommu: DMA domain TLB invalidation policy: strict mode388second # [ 0.103559] PTP clock support registered389second # [ 0.103600] EDAC MC: Ver: 3.0.0390test # [ 0.100750] SCSI subsystem initialized391second # [ 0.110335] scmi_core: SCMI protocol bus registered392test # [ 0.105712] usbcore: registered new interface driver usbfs393test # [ 0.105747] usbcore: registered new interface driver hub394test # [ 0.105758] usbcore: registered new device driver usb395second # [ 0.111067] FPGA manager framework396test # [ 0.105960] pps_core: LinuxPPS API ver. 1 registered397second # [ 0.112024] vgaarb: loaded398test # [ 0.105966] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>399second # [ 0.112417] clocksource: Switched to clocksource arch_sys_counter400test # [ 0.105972] PTP clock support registered401test # [ 0.106002] EDAC MC: Ver: 3.0.0402test # [ 0.108990] scmi_core: SCMI protocol bus registered403test # [ 0.109718] FPGA manager framework404test # [ 0.110708] vgaarb: loaded405test # [ 0.111115] clocksource: Switched to clocksource arch_sys_counter406second # [ 0.121763] VFS: Disk quotas dquot_6.6.0407second # [ 0.121804] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)408second # [ 0.122849] netfs: FS-Cache loaded409second # [ 0.122985] pnp: PnP ACPI: disabled410test # [ 0.121269] VFS: Disk quotas dquot_6.6.0411test # [ 0.121307] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)412test # [ 0.123638] netfs: FS-Cache loaded413test # [ 0.123761] pnp: PnP ACPI: disabled414second # [ 0.125343] NET: Registered PF_INET protocol family415second # [ 0.125531] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)416test # [ 0.126146] NET: Registered PF_INET protocol family417test # [ 0.126337] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)418test # [ 0.150752] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)419test # [ 0.150776] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)420second # [ 0.150088] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)421test # [ 0.150787] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422second # [ 0.150113] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)423test # [ 0.150823] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)424second # [ 0.150125] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)425test # [ 0.150890] TCP: Hash tables configured (established 8192 bind 8192)426second # [ 0.150162] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)427test # [ 0.150975] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)428second # [ 0.150230] TCP: Hash tables configured (established 8192 bind 8192)429test # [ 0.151003] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)430second # [ 0.150310] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)431test # [ 0.151062] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)432second # [ 0.150381] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)433second # [ 0.150434] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)434second # [ 0.150599] NET: Registered PF_UNIX/PF_LOCAL protocol family435second # [ 0.150675] NET: Registered PF_XDP protocol family436second # [ 0.150696] PCI: CLS 0 bytes, default 64437second # [ 0.150880] Trying to unpack rootfs image as initramfs...438test # [ 0.155809] NET: Registered PF_UNIX/PF_LOCAL protocol family439test # [ 0.155853] NET: Registered PF_XDP protocol family440test # [ 0.155873] PCI: CLS 0 bytes, default 64441second # [ 0.162441] kvm [1]: HYP mode not available442test # [ 0.156071] Trying to unpack rootfs image as initramfs...443test # [ 0.164671] kvm [1]: HYP mode not available444second # [ 0.227458] Initialise system trusted keyrings445second # [ 0.227989] workingset: timestamp_bits=42 max_order=18 bucket_order=0446second # [ 0.228942] squashfs: version 4.0 (2009/01/31) Phillip Lougher447second # [ 0.229440] 9p: Installing v9fs 9p2000 file system support448test # [ 0.229650] Initialise system trusted keyrings449test # [ 0.230155] workingset: timestamp_bits=42 max_order=18 bucket_order=0450test # [ 0.231093] squashfs: version 4.0 (2009/01/31) Phillip Lougher451test # [ 0.231648] 9p: Installing v9fs 9p2000 file system support452test # [ 0.246479] Key type asymmetric registered453test # [ 0.246498] Asymmetric key parser 'x509' registered454test # [ 0.246545] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)455second # [ 0.244291] Key type asymmetric registered456second # [ 0.244310] Asymmetric key parser 'x509' registered457second # [ 0.244356] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)458second # [ 0.253741] io scheduler mq-deadline registered459second # [ 0.253752] io scheduler kyber registered460test # [ 0.255174] io scheduler mq-deadline registered461test # [ 0.255184] io scheduler kyber registered462second # [ 0.260516] pl061_gpio 9030000.pl061: PL061 GPIO chip registered463second # [ 0.261316] ledtrig-cpu: registered to indicate activity on CPUs464second # [ 0.261622] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:465second # [ 0.261644] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000466second # [ 0.261659] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000467second # [ 0.261665] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000468second # [ 0.261695] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits469test # [ 0.263227] pl061_gpio 9030000.pl061: PL061 GPIO chip registered470second # [ 0.261728] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]471second # [ 0.261800] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00472second # [ 0.261818] pci_bus 0000:00: root bus resource [bus 00-ff]473second # [ 0.261822] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]474second # [ 0.261826] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]475second # [ 0.261830] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]476second # [ 0.261908] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint477test # [ 0.264017] ledtrig-cpu: registered to indicate activity on CPUs478second # [ 0.262349] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint479test # [ 0.264327] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:480second # [ 0.262479] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]481test # [ 0.264350] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000482second # [ 0.262490] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]483second # [ 0.262512] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484test # [ 0.264364] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000485second # [ 0.262524] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]486test # [ 0.264370] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000487second # [ 0.262850] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint488test # [ 0.264411] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits489second # [ 0.262995] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]490second # [ 0.263006] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]491test # [ 0.264438] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]492second # [ 0.263027] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]493test # [ 0.264528] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00494second # [ 0.263338] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint495test # [ 0.264546] pci_bus 0000:00: root bus resource [bus 00-ff]496second # [ 0.263464] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]497test # [ 0.264550] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]498second # [ 0.263475] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.264554] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]500second # [ 0.263496] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501test # [ 0.264558] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]502second # [ 0.263804] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint503test # [ 0.264643] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint504second # [ 0.263929] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]505second # [ 0.263940] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]506test # [ 0.265085] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint507test # [ 0.265220] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]508second # [ 0.263961] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509test # [ 0.265232] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]510second # [ 0.263972] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]511test # [ 0.265254] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512second # [ 0.264285] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint513test # [ 0.265265] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]514second # [ 0.264413] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]515test # [ 0.265592] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint516test # [ 0.265722] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]517test # [ 0.265734] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]518test # [ 0.265756] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519test # [ 0.266078] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint520test # [ 0.266210] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]521test # [ 0.266221] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]522test # [ 0.266242] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]523test # [ 0.266591] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint524test # [ 0.266723] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]525test # [ 0.266736] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]526test # [ 0.266757] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527test # [ 0.266768] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]528test # [ 0.267106] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint529second # [ 0.284486] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]530second # [ 0.284827] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint531second # [ 0.284961] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]532second # [ 0.284982] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533second # [ 0.285295] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint534second # [ 0.285421] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]535test # [ 0.284625] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]536second # [ 0.285603] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint537test # [ 0.284653] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.285734] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]539test # [ 0.285001] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint540second # [ 0.285755] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541test # [ 0.285134] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]542second # [ 0.286073] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint543test # [ 0.285156] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544second # [ 0.286203] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]545test # [ 0.285442] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint546second # [ 0.286224] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547test # [ 0.285569] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]548second # [ 0.286549] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint549second # [ 0.286678] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]550test # [ 0.285757] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint551second # [ 0.286700] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552test # [ 0.285889] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]553test # [ 0.285911] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554second # [ 0.287041] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint555second # [ 0.287274] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]556test # [ 0.286233] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint557second # [ 0.287287] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]558test # [ 0.286365] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]559second # [ 0.287308] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560test # [ 0.286386] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]561second # [ 0.287631] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint562test # [ 0.286719] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint563second # [ 0.287759] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]564test # [ 0.286852] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]565second # [ 0.287771] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]566test # [ 0.286873] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567second # [ 0.287791] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568second # [ 0.288208] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned569second # [ 0.288219] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned570second # [ 0.288224] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned571second # [ 0.288257] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned572second # [ 0.288290] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned573second # [ 0.288321] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned574second # [ 0.288353] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned575second # [ 0.288384] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned576test # [ 0.299263] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint577test # [ 0.299496] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]578test # [ 0.299510] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]579test # [ 0.299530] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]580test # [ 0.299859] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint581test # [ 0.299989] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]582test # [ 0.300002] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]583second # [ 0.308444] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned584test # [ 0.300022] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585second # [ 0.308502] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned586test # [ 0.300444] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned587test # [ 0.300454] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned588second # [ 0.308533] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned589test # [ 0.300459] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned590second # [ 0.308564] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned591test # [ 0.300494] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned592second # [ 0.308623] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned593test # [ 0.300527] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned594second # [ 0.308655] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned595second # [ 0.308835] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned596test # [ 0.300559] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned597second # [ 0.308851] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned598test # [ 0.300592] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned599second # [ 0.308865] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned600test # [ 0.300623] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned601second # [ 0.308879] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned602test # [ 0.300654] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned603second # [ 0.308893] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned604second # [ 0.308907] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned605test # [ 0.300685] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned606second # [ 0.308921] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned607test # [ 0.300716] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned608second # [ 0.308935] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned609test # [ 0.300747] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned610second # [ 0.308949] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned611test # [ 0.300835] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned612second # [ 0.308962] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned613test # [ 0.300884] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned614second # [ 0.308977] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned615test # [ 0.300898] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned616second # [ 0.308990] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned617second # [ 0.309004] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned618test # [ 0.300912] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned619second # [ 0.309018] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned620test # [ 0.300926] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned621second # [ 0.309031] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned622test # [ 0.300940] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned623second # [ 0.309045] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned624test # [ 0.300953] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned625second # [ 0.309059] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned626test # [ 0.300967] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned627second # [ 0.309077] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]628test # [ 0.300981] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned629second # [ 0.309088] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]630test # [ 0.300995] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned631second # [ 0.309092] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]632test # [ 0.301009] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned633second # [ 0.309622] pci 0000:00:07.0: enabling device (0000 -> 0002)634test # [ 0.301023] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned635test # [ 0.301036] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned636test # [ 0.301050] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned637test # [ 0.301063] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned638test # [ 0.301077] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned639test # [ 0.301091] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640test # [ 0.301104] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned641test # [ 0.301118] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned642test # [ 0.301136] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]643test # [ 0.301146] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]644test # [ 0.301150] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]645test # [ 0.301677] pci 0000:00:07.0: enabling device (0000 -> 0002)646second # [ 0.337236] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)647second # [ 0.339225] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)648test # [ 0.336011] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)649test # [ 0.337978] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)650second # [ 0.348868] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)651second # [ 0.351011] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)652test # [ 0.348836] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)653test # [ 0.350704] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)654second # [ 0.354126] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)655second # [ 0.356177] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)656second # [ 0.362916] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)657test # [ 0.361350] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)658test # [ 0.363809] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)659test # [ 0.365439] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)660test # [ 0.375274] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)661second # [ 0.376551] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)662test # [ 0.377106] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)663second # [ 0.378217] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)664test # [ 0.378760] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)665second # [ 0.379870] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)666second # [ 0.381945] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)667second # [ 0.393680] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled668test # [ 0.389089] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)669second # [ 0.395155] msm_serial: driver initialized670second # [ 0.395238] SuperH (H)SCI(F) driver initialized671second # [ 0.395273] STM32 USART driver initialized672test # [ 0.399449] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled673test # [ 0.401088] msm_serial: driver initialized674test # [ 0.401163] SuperH (H)SCI(F) driver initialized675test # [ 0.401200] STM32 USART driver initialized676second # [ 0.414721] loop: module loaded677second # [ 0.414844] virtio_blk virtio2: 1/0/0 default/read/poll queues678second # [ 0.415469] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)679test # [ 0.419072] loop: module loaded680test # [ 0.419492] virtio_blk virtio2: 1/0/0 default/read/poll queues681test # [ 0.420135] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)682second # [ 0.424788] megasas: 07.734.00.00-rc1683second # [ 0.425214] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]684second # [ 0.427419] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000685second # [ 0.427447] Intel/Sharp Extended Query Table at 0x0031686second # [ 0.431213] Using buffer write method687second # [ 0.431252] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]688test # [ 0.431478] megasas: 07.734.00.00-rc1689test # [ 0.431934] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]690second # [ 0.437038] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000691second # [ 0.437060] Intel/Sharp Extended Query Table at 0x0031692test # [ 0.434154] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000693second # [ 0.440070] Using buffer write method694test # [ 0.434181] Intel/Sharp Extended Query Table at 0x0031695second # [ 0.440091] Concatenating MTD devices:696second # [ 0.440094] (0): "0.flash"697second # [ 0.440097] (1): "0.flash"698second # [ 0.440099] into device "0.flash"699test # [ 0.437957] Using buffer write method700test # [ 0.438014] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]701test # [ 0.440828] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000702test # [ 0.440873] Intel/Sharp Extended Query Table at 0x0031703test # [ 0.442881] Using buffer write method704test # [ 0.442899] Concatenating MTD devices:705test # [ 0.442902] (0): "0.flash"706test # [ 0.442905] (1): "0.flash"707test # [ 0.442908] into device "0.flash"708second # [ 0.656074] Freeing initrd memory: 26964K709second # [ 0.662828] tun: Universal TUN/TAP device driver, 1.6710test # [ 0.660184] Freeing initrd memory: 26948K711second # [ 0.665705] thunder_xcv, ver 1.0712second # [ 0.665735] thunder_bgx, ver 1.0713second # [ 0.665747] nicpf, ver 1.0714second # [ 0.666060] e1000: Intel(R) PRO/1000 Network Driver715second # [ 0.666069] e1000: Copyright (c) 1999-2006 Intel Corporation.716second # [ 0.666084] e1000e: Intel(R) PRO/1000 Network Driver717second # [ 0.666090] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.718second # [ 0.666104] igb: Intel(R) Gigabit Ethernet Network Driver719test # [ 0.667034] tun: Universal TUN/TAP device driver, 1.6720second # [ 0.666108] igb: Copyright (c) 2007-2014 Intel Corporation.721second # [ 0.666121] igbvf: Intel(R) Gigabit Virtual Function Network Driver722second # [ 0.666125] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.723second # [ 0.666200] sky2: driver version 1.30724second # [ 0.667008] usbcore: registered new interface driver usb-storage725second # [ 0.667110] usbcore: registered new interface driver usbserial_generic726second # [ 0.667135] usbserial: USB Serial support registered for generic727second # [ 0.667416] hv_vmbus: registering driver hyperv_keyboard728second # [ 0.668617] ehci-pci 0000:00:07.0: EHCI Host Controller729second # [ 0.668654] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1730second # [ 0.668915] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000731test # [ 0.669721] thunder_xcv, ver 1.0732test # [ 0.669749] thunder_bgx, ver 1.0733test # [ 0.669760] nicpf, ver 1.0734test # [ 0.670074] e1000: Intel(R) PRO/1000 Network Driver735second # [ 0.676951] rtc-pl031 9010000.pl031: registered as rtc0736test # [ 0.670083] e1000: Copyright (c) 1999-2006 Intel Corporation.737test # [ 0.670097] e1000e: Intel(R) PRO/1000 Network Driver738second # [ 0.676992] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:20:56 UTC (1789986056)739test # [ 0.670103] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.740second # [ 0.677158] i2c_dev: i2c /dev entries driver741test # [ 0.670116] igb: Intel(R) Gigabit Ethernet Network Driver742test # [ 0.670120] igb: Copyright (c) 2007-2014 Intel Corporation.743test # [ 0.670134] igbvf: Intel(R) Gigabit Virtual Function Network Driver744test # [ 0.670137] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.745test # [ 0.670202] sky2: driver version 1.30746test # [ 0.670994] usbcore: registered new interface driver usb-storage747second # [ 0.679989] sdhci: Secure Digital Host Controller Interface driver748test # [ 0.671766] ehci-pci 0000:00:07.0: EHCI Host Controller749second # [ 0.680008] sdhci: Copyright(c) Pierre Ossman750second # [ 0.680139] Synopsys Designware Multimedia Card Interface Driver751test # [ 0.671803] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1752second # [ 0.680299] sdhci-pltfm: SDHCI platform and OF driver helper753test # [ 0.672002] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000754second # [ 0.680461] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00755second # [ 0.680805] hub 1-0:1.0: USB hub found756second # [ 0.680835] hub 1-0:1.0: 6 ports detected757test # [ 0.678416] usbcore: registered new interface driver usbserial_generic758test # [ 0.678459] usbserial: USB Serial support registered for generic759test # [ 0.678813] hv_vmbus: registering driver hyperv_keyboard760test # [ 0.680773] rtc-pl031 9010000.pl031: registered as rtc0761test # [ 0.680807] rtc-pl031 9010000.pl031: setting system clock to 2026-09-21T10:20:56 UTC (1789986056)762test # [ 0.680971] i2c_dev: i2c /dev entries driver763second # [ 0.684607] hid: raw HID events driver (C) Jiri Kosina764test # [ 0.683970] sdhci: Secure Digital Host Controller Interface driver765test # [ 0.683986] sdhci: Copyright(c) Pierre Ossman766second # [ 0.684745] usbcore: registered new interface driver usbhid767second # [ 0.684750] usbhid: USB HID core driver768test # [ 0.684121] Synopsys Designware Multimedia Card Interface Driver769test # [ 0.684286] sdhci-pltfm: SDHCI platform and OF driver helper770test # [ 0.685115] hid: raw HID events driver (C) Jiri Kosina771test # [ 0.685246] usbcore: registered new interface driver usbhid772test # [ 0.685250] usbhid: USB HID core driver773second # [ 0.718738] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available774second # [ 0.719724] drop_monitor: Initializing network drop monitor service775second # [ 0.719904] NET: Registered PF_INET6 protocol family776second # [ 0.722492] Segment Routing with IPv6777test # [ 0.716363] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00778second # [ 0.722521] In-situ OAM (IOAM) with IPv6779test # [ 0.716729] hub 1-0:1.0: USB hub found780second # [ 0.722572] NET: Registered PF_PACKET protocol family781test # [ 0.716761] hub 1-0:1.0: 6 ports detected782test # [ 0.717057] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available783test # [ 0.717869] drop_monitor: Initializing network drop monitor service784test # [ 0.717988] NET: Registered PF_INET6 protocol family785test # [ 0.721382] Segment Routing with IPv6786test # [ 0.721409] In-situ OAM (IOAM) with IPv6787second # [ 0.723673] 9pnet: Installing 9P2000 support788test # [ 0.721458] NET: Registered PF_PACKET protocol family789second # [ 0.723713] Key type dns_resolver registered790test # [ 0.722583] 9pnet: Installing 9P2000 support791test # [ 0.722624] Key type dns_resolver registered792second # [ 0.727289] registered taskstats version 1793second # [ 0.727462] Loading compiled-in X.509 certificates794test # [ 0.726100] registered taskstats version 1795test # [ 0.726247] Loading compiled-in X.509 certificates796second # [ 0.732990] Demotion targets for Node 0: null797second # [ 0.733061] Key type .fscrypt registered798second # [ 0.733067] Key type fscrypt-provisioning registered799second # [ 0.733172] ima: No TPM chip found, activating TPM-bypass!800second # [ 0.733184] ima: Allocated hash algorithm: sha1801second # [ 0.733235] ima: No architecture policies found802second # [ 0.735786] input: gpio-keys as /devices/platform/gpio-keys/input/input0803test # [ 0.731578] Demotion targets for Node 0: null804test # [ 0.731650] Key type .fscrypt registered805test # [ 0.731656] Key type fscrypt-provisioning registered806test # [ 0.731758] ima: No TPM chip found, activating TPM-bypass!807test # [ 0.731770] ima: Allocated hash algorithm: sha1808test # [ 0.731822] ima: No architecture policies found809test # [ 0.734426] input: gpio-keys as /devices/platform/gpio-keys/input/input0810second # [ 0.746127] clk: Disabling unused clocks811second # [ 0.746146] PM: genpd: Disabling unused power domains812second # [ 0.749177] Freeing unused kernel memory: 4736K813second # [ 0.749256] Run /init as init process814test # [ 0.745099] clk: Disabling unused clocks815test # [ 0.745115] PM: genpd: Disabling unused power domains816test # [ 0.748191] Freeing unused kernel memory: 4736K817test # [ 0.748278] Run /init as init process818second # [ 0.770700] systemd[1]: Successfully made /usr/ read-only.819test # [ 0.769369] systemd[1]: Successfully made /usr/ read-only.820second # [ 0.940475] usb 1-1: new high-speed USB device number 2 using ehci-pci821test # [ 0.967158] usb 1-1: new high-speed USB device number 2 using ehci-pci822second # [ 1.090785] 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/input1823second # [ 1.105260] 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)824second # [ 1.112844] systemd[1]: Detected virtualization qemu.825second # [ 1.114037] systemd[1]: Detected architecture arm64.826test # [ 1.103944] 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)827second # [ 1.115202] systemd[1]: Running in initrd.828test # [ 1.111456] systemd[1]: Detected virtualization qemu.829second # [ 1.117005] systemd[1]: Initializing machine ID from random generator.830test # [ 1.113100] systemd[1]: Detected architecture arm64.831second # [ 1.118885] systemd[1]: Hostname set to <second>.832test # [ 1.114587] systemd[1]: Running in initrd.833test # [ 1.116645] systemd[1]: Initializing machine ID from random generator.834test # [ 1.119051] systemd[1]: Hostname set to <test>.835test # [ 1.126252] 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/input1836second # [ 1.176612] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0837test # [ 1.203275] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838second # [ 1.296510] usb 1-2: new high-speed USB device number 3 using ehci-pci839test # [ 1.323155] usb 1-2: new high-speed USB device number 3 using ehci-pci840test # [ 1.378822] systemd[1]: bpf-restrict-fs: LSM BPF program attached841second # [ 1.389409] systemd[1]: bpf-restrict-fs: LSM BPF program attached842test # [ 1.427052] systemd[1]: Queued start job for default target Initrd Default Target.843test # [ 1.436904] systemd[1]: Created slice Slice /system/modprobe.844test # [ 1.437697] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.845test # [ 1.438774] systemd[1]: Expecting device /dev/disk/by-label/nixos...846test # [ 1.439767] systemd[1]: Reached target Path Units.847test # [ 1.440283] systemd[1]: Reached target Slice Units.848test # [ 1.440802] systemd[1]: Reached target Swaps.849test # [ 1.441273] systemd[1]: Reached target Timer Units.850test # [ 1.441956] systemd[1]: Listening on D-Bus System Message Bus Socket.851test # [ 1.443055] systemd[1]: Listening on Journal Socket (/dev/log).852test # [ 1.444274] systemd[1]: Listening on Journal Sockets.853test # [ 1.444953] systemd[1]: Listening on udev Control Socket.854test # [ 1.445675] systemd[1]: Listening on udev Kernel Socket.855test # [ 1.446376] systemd[1]: Reached target Socket Units.856second # [ 1.450341] 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/input2857test # [ 1.448290] systemd[1]: Starting Create List of Static Device Nodes...858second # [ 1.452132] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0859test # [ 1.449057] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs860test # [ 1.457622] systemd[1]: Mounting Kernel Configuration File System...861second # [ 1.475128] systemd[1]: Queued start job for default target Initrd Default Target.862test # [ 1.476971] 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/input2863test # [ 1.477069] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0864test # [ 1.479882] systemd[1]: Starting Journal Service...865second # [ 1.486347] systemd[1]: Created slice Slice /system/modprobe.866second # [ 1.487224] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.867second # [ 1.488115] systemd[1]: Expecting device /dev/disk/by-label/nixos...868second # [ 1.488894] systemd[1]: Reached target Path Units.869second # [ 1.489499] systemd[1]: Reached target Slice Units.870second # [ 1.490045] systemd[1]: Reached target Swaps.871second # [ 1.490536] systemd[1]: Reached target Timer Units.872test # [ 1.486416] systemd[1]: Starting Load Kernel Modules...873second # [ 1.491308] systemd[1]: Listening on D-Bus System Message Bus Socket.874test # [ 1.487216] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os875second # [ 1.492153] systemd[1]: Listening on Journal Socket (/dev/log).876second # [ 1.493012] systemd[1]: Listening on Journal Sockets.877second # [ 1.493695] systemd[1]: Listening on udev Control Socket.878second # [ 1.494424] systemd[1]: Listening on udev Kernel Socket.879second # [ 1.495155] systemd[1]: Reached target Socket Units.880second # [ 1.497003] systemd[1]: Starting Create List of Static Device Nodes...881second # [ 1.497775] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs882second # [ 1.501239] systemd[1]: Mounting Kernel Configuration File System...883second # [ 1.510875] systemd[1]: Starting Journal Service...884test # [ 1.504355] systemd[1]: Starting Coldplug All udev Devices...885test # [ 1.505277] systemd[1]: Finished Create List of Static Device Nodes.886test # [ 1.506017] systemd[1]: Mounted Kernel Configuration File System.887test # [ 1.517025] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.888test # [ 1.518237] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...889second # [ 1.521182] systemd[1]: Starting Load Kernel Modules...890second # [ 1.521267] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os891second # [ 1.544554] systemd[1]: Starting Coldplug All udev Devices...892test # [ 1.539159] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev893test # [ 1.544945] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0894test # [ 1.545163] [drm] features: -virgl +edid -resource_blob -host_visible895test # [ 1.545168] [drm] features: -context_init896test # [ 1.545638] [drm] number of scanouts: 1897test # [ 1.545653] [drm] number of cap sets: 0898second # [ 1.555787] systemd[1]: Finished Create List of Static Device Nodes.899second # [ 1.557341] systemd[1]: Mounted Kernel Configuration File System.900test # [ 1.555036] systemd-journald[72]: Collecting audit messages is disabled.901second # [ 1.560643] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...902test # [ 1.559476] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.903test # [ 1.560416] systemd[1]: Starting Create Static Device Nodes in /dev...904second # [ 1.569709] systemd-journald[72]: Collecting audit messages is disabled.905test # [ 1.575660] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic906test # [ 1.575675] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0907test # [ 1.587482] systemd[1]: Finished Create Static Device Nodes in /dev.908test # [ 1.587614] systemd[1]: Reached target Preparation for Local File Systems.909test # [ 1.587645] systemd[1]: Reached target Local File Systems.910second # [ 1.594393] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.911test # [ 1.590074] systemd[1]: Starting Rule-based Manager for Device Events and Files...912second # [ 1.595971] systemd[1]: Starting Create Static Device Nodes in /dev...913test # [ 1.598761] Console: switching to colour frame buffer device 160x50914test # [ 1.604085] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device915second # [ 1.618554] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.916second # [ 1.628814] systemd[1]: Finished Create Static Device Nodes in /dev.917second # [ 1.628958] systemd[1]: Reached target Preparation for Local File Systems.918second # [ 1.628984] systemd[1]: Reached target Local File Systems.919test # [ 1.627452] systemd[1]: Finished Load Kernel Modules.920second # [ 1.631421] systemd[1]: Starting Rule-based Manager for Device Events and Files...921test # [ 1.629390] systemd[1]: Starting Apply Kernel Variables...922second # [ 1.636088] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev923second # [ 1.641497] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0924second # [ 1.641721] [drm] features: -virgl +edid -resource_blob -host_visible925second # [ 1.641726] [drm] features: -context_init926second # [ 1.642191] [drm] number of scanouts: 1927second # [ 1.642207] [drm] number of cap sets: 0928test # [ 1.646976] systemd-modules-load[73]: Inserted module 'dm_mod'929test # [ 1.647858] systemd-modules-load[73]: Module 'virtio_balloon' is built in930second # [ 1.651336] systemd-modules-load[73]: Inserted module 'dm_mod'931test # [ 1.648710] systemd-modules-load[73]: Module 'virtio_console' is built in932second # [ 1.652095] systemd-modules-load[73]: Module 'virtio_balloon' is built in933test # [ 1.649390] systemd-modules-load[73]: Inserted module 'virtio_gpu'934test # [ 1.650063] systemd-modules-load[73]: Module 'virtio_rng' is built in935second # [ 1.661237] systemd[1]: Started Journal Service.936second # [ 1.654061] systemd-modules-load[73]: Module 'virtio_console' is built in937test # [ 1.658161] systemd[1]: Started Journal Service.938second # [ 1.656249] systemd[1]: Starting Create System Files and Directories...939second # [ 1.665588] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic940second # [ 1.665603] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0941test # [ 1.664280] systemd-udevd[78]: Using default interface naming scheme 'v261'.942test # [ 1.672457] systemd[1]: Starting Create System Files and Directories...943test # [ 1.674355] systemd[1]: Finished Apply Kernel Variables.944second # [ 1.689103] Console: switching to colour frame buffer device 160x50945second # [ 1.694407] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device946test # [ 1.694585] systemd[1]: Started Rule-based Manager for Device Events and Files.947test # [ 1.697000] systemd[1]: Finished Create System Files and Directories.948second # [ 1.702865] systemd[1]: Finished Create System Files and Directories.949second # [ 1.704379] systemd-modules-load[73]: Inserted module 'virtio_gpu'950second # [ 1.704999] systemd-modules-load[73]: Module 'virtio_rng' is built in951second # [ 1.708156] systemd[1]: Finished Load Kernel Modules.952second # [ 1.708673] systemd[1]: Starting Apply Kernel Variables...953second # [ 1.716208] systemd-udevd[79]: Using default interface naming scheme 'v261'.954second # [ 1.735939] systemd[1]: Started Rule-based Manager for Device Events and Files.955second # [ 1.740067] systemd[1]: Finished Apply Kernel Variables.956test # [ 1.737338] systemd[1]: Starting Virtual Console Setup...957second # [ 1.779474] systemd[1]: Starting Virtual Console Setup...958test # [ 1.780416] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.959test # [ 1.782559] systemd[1]: Finished Virtual Console Setup.960second # [ 1.808305] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.961second # [ 1.810357] systemd[1]: Finished Virtual Console Setup.962test # [ 2.008878] systemd[1]: Finished Coldplug All udev Devices.963test # [ 2.009443] systemd[1]: Reached target System Initialization.964test # [ 2.009946] systemd[1]: Reached target Basic System.965second # [ 2.028797] systemd[1]: Finished Coldplug All udev Devices.966second # [ 2.029387] systemd[1]: Reached target System Initialization.967second # [ 2.032079] systemd[1]: Reached target Basic System.968test # [ 2.106349] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.969second # [ 2.122459] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.970test # [ 2.124905] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.971second # [ 2.145392] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.972test # [ 2.197185] systemd[1]: Found device /dev/disk/by-label/nixos.973test # [ 2.199436] systemd[1]: Reached target Initrd Root Device.974test # [ 2.202221] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...975second # [ 2.206174] systemd[1]: Found device /dev/disk/by-label/nixos.976second # [ 2.210775] systemd[1]: Reached target Initrd Root Device.977second # [ 2.213370] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...978test # [ 2.233226] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks979test # [ 2.240317] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.980second # [ 2.250566] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks981second # [ 2.256964] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.982test # [ 2.499880] systemd[1]: Mounting /sysroot...983test # [ 2.531470] EXT4-fs (vda): mounted filesystem 8a2f08a8-9d27-4e1c-aeb4-f444db106339 r/w with ordered data mode. Quota mode: none.984test # [ 2.528642] systemd[1]: Mounted /sysroot.985test # [ 2.530105] systemd[1]: Reached target Initrd Root File System.986test # [ 2.534995] systemd[1]: Mounting /sysroot/nix/.ro-store...987second # [ 2.540091] systemd[1]: Mounting /sysroot...988test # [ 2.547135] systemd[1]: Mounting /sysroot/nix/.rw-store...989test # [ 2.549758] systemd[1]: Mounting /sysroot/run...990test # [ 2.562079] systemd[1]: Mounting /sysroot/tmp/shared...991second # [ 2.573418] EXT4-fs (vda): mounted filesystem 5945dc3c-f424-4c4b-a92a-a34ca44e4b3a r/w with ordered data mode. Quota mode: none.992second # [ 2.570455] systemd[1]: Mounted /sysroot.993second # [ 2.571898] systemd[1]: Reached target Initrd Root File System.994second # [ 2.574941] systemd[1]: Starting Mountpoints Configured in the Real Root...995second # [ 2.594575] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.996test # [ 2.602333] fuse: init (API version 7.45)997second # [ 2.599353] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...998test # [ 2.598438] systemd[1]: Mounting /sysroot/tmp/xchg...999second # [ 2.602502] systemd[1]: Reloading...1000test # [ 2.614038] virtiofs virtio6: discovered new tag: nix-store1001test # [ 2.614562] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1002test # [ 2.613716] systemd[1]: Starting Mountpoints Configured in the Real Root...1003test # [ 2.614382] systemd[1]: Mounted /sysroot/run.1004test # [ 2.628582] virtiofs virtio7: discovered new tag: shared1005test # [ 2.629110] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1006test # [ 2.633278] virtiofs virtio8: discovered new tag: xchg1007test # [ 2.633751] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1008test # [ 2.634646] systemd[1]: Mounted /sysroot/nix/.rw-store.1009test # [ 2.658318] systemd[1]: Starting rw-sysroot-nix-store.service...1010test # [ 2.658895] systemd[1]: Mounted /sysroot/tmp/shared.1011test # [ 2.662195] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1012test # [ 2.665148] systemd[1]: Mounted /sysroot/nix/.ro-store.1013test # [ 2.669032] systemd[1]: Mounted /sysroot/tmp/xchg.1014test # [ 2.680092] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1015test # [ 2.681872] systemd[1]: Reloading...1016second # [ 2.759424] systemd[1]: Reloading finished in 158 ms.1017second # [ 2.787998] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1018second # [ 2.791331] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1019second # [ 2.793486] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1020second # [ 2.795196] systemd[1]: Finished Mountpoints Configured in the Real Root.1021second # [ 2.796750] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1022test # [ 2.861233] systemd[1]: Reloading finished in 185 ms.1023test # [ 2.888536] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1024test # [ 2.891246] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1025test # [ 2.895457] systemd[1]: Finished rw-sysroot-nix-store.service.1026test # [ 2.896164] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1027test # [ 2.900766] systemd[1]: Starting rw-sysroot-nix-store.service...1028test # [ 2.904791] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1029test # [ 2.909372] systemd[1]: Finished Mountpoints Configured in the Real Root.1030test # [ 2.910010] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1031test # [ 2.921558] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1032test # [ 2.922502] systemd[1]: Finished rw-sysroot-nix-store.service.1033second # [ 2.934686] fuse: init (API version 7.45)1034second # [ 2.979463] virtiofs virtio6: discovered new tag: nix-store1035second # [ 2.979980] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1036second # [ 2.988483] (udev-worker)[92]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1037second # [ 2.991616] (udev-worker)[92]: 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 directory1038second # [ 3.006631] virtiofs virtio7: discovered new tag: shared1039second # [ 3.007122] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1040second # [ 3.016272] virtiofs virtio8: discovered new tag: xchg1041second # [ 3.018841] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1042second # [ 3.011751] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1043second # [ 3.012875] systemd[1]: Stopped Virtual Console Setup.1044second # [ 3.013467] systemd[1]: Stopping Virtual Console Setup...1045second # [ 3.018955] systemd[1]: Starting Virtual Console Setup...1046test # [ 3.020796] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1047test # [ 3.024121] (udev-worker)[93]: 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 directory1048second # [ 3.039979] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones.1049second # [ 3.041608] systemd[1]: Finished Virtual Console Setup.1050test # [ 3.044524] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1051test # [ 3.045991] systemd[1]: Stopped Virtual Console Setup.1052test # [ 3.047947] systemd[1]: Stopping Virtual Console Setup...1053test # [ 3.051719] systemd[1]: Starting Virtual Console Setup...1054test # [ 3.070335] systemd-vconsole-setup[153]: Configuration of first virtual console was skipped, ignoring remaining ones.1055test # [ 3.072522] systemd[1]: Finished Virtual Console Setup.1056test # [ 3.501224] systemd[1]: Mounting /sysroot/nix/store...1057second # [ 3.541375] systemd[1]: Mounting /sysroot/nix/.ro-store...1058second # [ 3.552336] systemd[1]: Mounting /sysroot/nix/.rw-store...1059test # [ 3.551689] systemd[1]: Mounted /sysroot/nix/store.1060test # [ 3.553928] systemd[1]: Reached target Initrd File Systems.1061second # [ 3.559068] systemd[1]: Mounting /sysroot/run...1062test # [ 3.557642] systemd[1]: Starting Find NixOS closure...1063test # [ 3.564918] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1064second # [ 3.573982] systemd[1]: Mounting /sysroot/tmp/shared...1065second # [ 3.596265] systemd[1]: Mounting /sysroot/tmp/xchg...1066test # [ 3.599371] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1067test # [ 3.600976] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1068second # [ 3.613131] systemd[1]: Mounted /sysroot/nix/.ro-store.1069test # [ 3.614755] systemd[1]: Finished Find NixOS closure.1070test # [ 3.617557] systemd[1]: Reached target Initrd Default Target.1071test # [ 3.619115] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1072second # [ 3.629565] systemd[1]: Mounted /sysroot/nix/.rw-store.1073second # [ 3.630736] systemd[1]: Mounted /sysroot/run.1074second # [ 3.640459] systemd[1]: Starting rw-sysroot-nix-store.service...1075second # [ 3.642151] systemd[1]: Mounted /sysroot/tmp/shared.1076second # [ 3.645478] systemd[1]: Mounted /sysroot/tmp/xchg.1077second # [ 3.654501] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1078test # [ 3.651631] systemd[1]: Stopped target Initrd Default Target.1079second # [ 3.655635] systemd[1]: Finished rw-sysroot-nix-store.service.1080test # [ 3.653241] systemd[1]: Stopped target Basic System.1081test # [ 3.654426] systemd[1]: Stopped target Initrd Root Device.1082test # [ 3.656210] systemd[1]: Stopped target Path Units.1083test # [ 3.660070] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1084test # [ 3.661164] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1085test # [ 3.664128] systemd[1]: Stopped target Slice Units.1086test # [ 3.664869] systemd[1]: Stopped target Socket Units.1087test # [ 3.665787] systemd[1]: Stopped target System Initialization.1088test # [ 3.668138] systemd[1]: Stopped target Swaps.1089test # [ 3.668790] systemd[1]: Stopped target Timer Units.1090test # [ 3.670261] systemd[1]: dbus.socket: Deactivated successfully.1091test # [ 3.672212] systemd[1]: Closed D-Bus System Message Bus Socket.1092test # [ 3.674552] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1093test # [ 3.676262] systemd[1]: Stopped Find NixOS closure.1094test # [ 3.677839] systemd[1]: Starting rw-sysroot-nix-store.service...1095test # [ 3.685125] systemd[1]: systemd-sysctl.service: Deactivated successfully.1096test # [ 3.688285] systemd[1]: Stopped Apply Kernel Variables.1097test # [ 3.689031] systemd[1]: systemd-modules-load.service: Deactivated successfully.1098test # [ 3.693025] systemd[1]: Stopped Load Kernel Modules.1099test # [ 3.695157] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1100test # [ 3.698629] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1101test # [ 3.701175] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1102test # [ 3.703551] systemd[1]: Stopped Create System Files and Directories.1103test # [ 3.704623] systemd[1]: Stopped target Local File Systems.1104test # [ 3.706410] systemd[1]: Stopped target Preparation for Local File Systems.1105test # [ 3.707962] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1106test # [ 3.711639] systemd[1]: Stopped Coldplug All udev Devices.1107test # [ 3.712498] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1108test # [ 3.713200] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1109test # [ 3.713861] systemd[1]: Stopped Virtual Console Setup.1110test # [ 3.714339] systemd[1]: initrd-cleanup.service: Deactivated successfully.1111test # [ 3.714942] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1112test # [ 3.715566] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1113test # [ 3.716612] systemd[1]: Finished rw-sysroot-nix-store.service.1114test # [ 3.717164] systemd[1]: systemd-udevd.service: Deactivated successfully.1115test # [ 3.717767] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1116test # [ 3.718404] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1117test # [ 3.719225] systemd[1]: Closed udev Control Socket.1118test # [ 3.719711] systemd[1]: Starting Cleanup udev Database...1119test # [ 3.720305] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1120test # [ 3.720980] systemd[1]: Stopped Create Static Device Nodes in /dev.1121test # [ 3.721527] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1122test # [ 3.722228] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1123test # [ 3.722843] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1124test # [ 3.723518] systemd[1]: Stopped Create List of Static Device Nodes.1125test # [ 3.739866] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1126test # [ 3.741186] systemd[1]: Finished Cleanup udev Database.1127test # [ 3.741667] systemd[1]: Reached target Switch Root.1128test # [ 3.744064] systemd[1]: Starting NixOS Activation...1129test # [ 3.815041] initrd-nixos-activation-start[176]: booting system configuration /nix/store/x7nsl2qv5glbry1qfmi2vql7jyx5zqy5-nixos-system-test-test1130test # [ 3.841372] initrd-nixos-activation-start[176]: running activation script...1131test # [ 4.054461] initrd-nixos-activation-start[199]: setting up /etc...1132test # [ 4.149894] initrd-nixos-activation-start[199]: setting up age secrets...1133test # [ 4.262400] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1134test # [ 4.264118] systemd[1]: Finished NixOS Activation.1135test # [ 4.264962] systemd[1]: Starting Switch Root...1136test # [ 4.283980] systemd[1]: Switching root.1137test # [ 4.451927] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1138second # [ 4.539770] systemd[1]: Mounting /sysroot/nix/store...1139second # [ 4.590381] systemd[1]: Mounted /sysroot/nix/store.1140second # [ 4.592965] systemd[1]: Reached target Initrd File Systems.1141second # [ 4.596726] systemd[1]: Starting Find NixOS closure...1142second # [ 4.608294] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1143second # [ 4.647915] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1144second # [ 4.651573] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1145second # [ 4.666785] systemd[1]: Finished Find NixOS closure.1146second # [ 4.668623] systemd[1]: Reached target Initrd Default Target.1147second # [ 4.670794] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1148second # [ 4.694472] systemd[1]: Stopped target Initrd Default Target.1149second # [ 4.695650] systemd[1]: Stopped target Basic System.1150second # [ 4.696849] systemd[1]: Stopped target Initrd Root Device.1151second # [ 4.697907] systemd[1]: Stopped target Path Units.1152second # [ 4.698957] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1153second # [ 4.702958] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1154second # [ 4.703907] systemd[1]: Stopped target Slice Units.1155second # [ 4.704623] systemd[1]: Stopped target Socket Units.1156second # [ 4.708115] systemd[1]: Stopped target System Initialization.1157second # [ 4.708845] systemd[1]: Stopped target Swaps.1158second # [ 4.709396] systemd[1]: Stopped target Timer Units.1159second # [ 4.709985] systemd[1]: dbus.socket: Deactivated successfully.1160second # [ 4.711450] systemd[1]: Closed D-Bus System Message Bus Socket.1161second # [ 4.713443] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1162second # [ 4.715581] systemd[1]: Stopped Find NixOS closure.1163second # [ 4.716933] systemd[1]: Starting rw-sysroot-nix-store.service...1164second # [ 4.720219] systemd[1]: systemd-sysctl.service: Deactivated successfully.1165second # [ 4.721868] systemd[1]: Stopped Apply Kernel Variables.1166second # [ 4.728821] systemd[1]: systemd-modules-load.service: Deactivated successfully.1167second # [ 4.731351] systemd[1]: Stopped Load Kernel Modules.1168second # [ 4.734361] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1169second # [ 4.736166] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1170second # [ 4.738049] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1171second # [ 4.738874] systemd[1]: Stopped Create System Files and Directories.1172second # [ 4.739469] systemd[1]: Stopped target Local File Systems.1173second # [ 4.739988] systemd[1]: Stopped target Preparation for Local File Systems.1174second # [ 4.746857] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1175second # [ 4.747578] systemd[1]: Stopped Coldplug All udev Devices.1176second # [ 4.748185] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1177second # [ 4.748876] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1178second # [ 4.749503] systemd[1]: Stopped Virtual Console Setup.1179second # [ 4.749962] systemd[1]: initrd-cleanup.service: Deactivated successfully.1180second # [ 4.750697] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1181second # [ 4.751260] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1182second # [ 4.751870] systemd[1]: Finished rw-sysroot-nix-store.service.1183second # [ 4.760187] systemd[1]: systemd-udevd.service: Deactivated successfully.1184second # [ 4.760783] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1185second # [ 4.761550] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1186second # [ 4.762185] systemd[1]: Closed udev Control Socket.1187second # [ 4.762637] systemd[1]: Starting Cleanup udev Database...1188second # [ 4.763130] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1189second # [ 4.768504] systemd[1]: Stopped Create Static Device Nodes in /dev.1190second # [ 4.769077] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1191second # [ 4.769785] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1192second # [ 4.770396] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1193second # [ 4.771000] systemd[1]: Stopped Create List of Static Device Nodes.1194second # [ 4.775025] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1195second # [ 4.775712] systemd[1]: Finished Cleanup udev Database.1196second # [ 4.779513] systemd[1]: Reached target Switch Root.1197second # [ 4.779960] systemd[1]: Starting NixOS Activation...1198second # [ 4.852505] initrd-nixos-activation-start[172]: booting system configuration /nix/store/hg1709ady9mg653qjmzpdz2jn5b23ni1-nixos-system-second-test1199second # [ 4.879214] initrd-nixos-activation-start[172]: running activation script...1200test # [ 5.006003] 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)1201test # [ 5.013116] systemd[1]: Detected virtualization qemu.1202test # [ 5.014887] systemd[1]: Detected architecture arm64.1203test # [ 5.017250] systemd[1]: Detected first boot.1204test # [ 5.021448] systemd[1]: Initializing machine ID from random generator.1205second # [ 5.091376] initrd-nixos-activation-start[195]: setting up /etc...1206second # [ 5.186466] initrd-nixos-activation-start[195]: setting up age secrets...1207second # [ 5.270935] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1208second # [ 5.272804] systemd[1]: Finished NixOS Activation.1209second # [ 5.273556] systemd[1]: Starting Switch Root...1210second # [ 5.292255] systemd[1]: Switching root.1211test # [ 5.303694] systemd[1]: bpf-restrict-fs: LSM BPF program attached1212test # [ 5.435580] systemd[1]: Applying preset policy.1213second # [ 5.468723] systemd-journald[72]: Received SIGTERM from PID 1 (systemd).1214test # [ 5.616545] systemd[1]: Populated /etc with preset unit settings.1215test # [ 5.850412] systemd[1]: initrd-switch-root.service: Deactivated successfully.1216test # [ 5.851331] systemd[1]: Stopped initrd-switch-root.service.1217test # [ 5.853292] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1218test # [ 5.855558] systemd[1]: Created slice Slice /system/getty.1219test # [ 5.856948] systemd[1]: Created slice User and Session Slice.1220test # [ 5.858001] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1221test # [ 5.859341] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1222test # [ 5.860483] systemd[1]: Expecting device /dev/hvc0...1223test # [ 5.861971] systemd[1]: Expecting device /dev/ttyAMA0...1224test # [ 5.863590] systemd[1]: Reached target Local Encrypted Volumes.1225test # [ 5.865087] systemd[1]: Stopped target initrd-fs.target.1226test # [ 5.866535] systemd[1]: Stopped target initrd-root-fs.target.1227test # [ 5.868296] systemd[1]: Stopped target initrd-switch-root.target.1228test # [ 5.869798] systemd[1]: Reached target Virtual Machines and Containers.1229test # [ 5.871426] systemd[1]: Reached target Path Units.1230test # [ 5.872827] systemd[1]: Reached target Remote File Systems.1231test # [ 5.874266] systemd[1]: Reached target Slice Units.1232test # [ 5.875787] systemd[1]: Reached target Swaps.1233test # [ 5.878104] systemd[1]: Listening on Query the User Interactively for a Password.1234test # [ 5.880806] systemd[1]: Listening on Process Core Dump Socket.1235test # [ 5.882956] systemd[1]: Listening on Credential Encryption/Decryption.1236test # [ 5.885451] systemd[1]: Listening on Factory Reset Management.1237test # [ 5.886499] systemd[1]: Listening on Hostname Service Socket.1238test # [ 5.889561] systemd[1]: Starting Journal Log Access Socket...1239test # [ 5.891334] systemd[1]: Listening on Journal Audit Socket.1240test # [ 5.894108] systemd[1]: Listening on Console Output Muting Service Socket.1241test # [ 5.895713] systemd[1]: Listening on Network Management Metrics Varlink Socket.1242test # [ 5.897532] systemd[1]: Listening on Network Management Varlink Socket.1243test # [ 5.899234] systemd[1]: Listening on Network Management Netlink Socket.1244test # [ 5.900911] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1245test # [ 5.902522] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1246test # [ 5.904395] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1247test # [ 5.909116] systemd[1]: Listening on Disk Repartitioning Service Socket.1248test # [ 5.910200] systemd[1]: Listening on Resolve Monitor Varlink Socket.1249test # [ 5.911288] systemd[1]: Listening on Resolve Service Varlink Socket.1250test # [ 5.912330] systemd[1]: Listening on udev Control Socket.1251test # [ 5.913316] systemd[1]: Listening on udev Varlink Socket.1252test # [ 5.916044] systemd[1]: Mounting Huge Pages File System...1253test # [ 5.918088] systemd[1]: Mounting POSIX Message Queue File System...1254test # [ 5.927303] systemd[1]: Mounting Kernel Debug File System...1255test # [ 5.935568] systemd[1]: Mounting Kernel Trace File System...1256test # [ 5.941803] systemd[1]: Starting Create List of Static Device Nodes...1257test # [ 5.945755] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1258test # [ 5.955673] systemd[1]: Mounting Kernel Configuration File System...1259test # [ 5.957153] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1260test # [ 5.963349] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1261test # [ 5.965220] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1262second # [ 5.979052] 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)1263second # [ 5.986350] systemd[1]: Detected virtualization qemu.1264second # [ 5.988143] systemd[1]: Detected architecture arm64.1265second # [ 5.990509] systemd[1]: Detected first boot.1266test # [ 5.985895] systemd[1]: Mounting FUSE Control File System...1267test # [ 5.986312] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671268second # [ 5.994201] systemd[1]: Initializing machine ID from random generator.1269test # [ 6.007851] systemd[1]: Starting Journal Service...1270test # [ 6.023295] systemd[1]: Starting Load Kernel Modules...1271test # [ 6.037134] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1272test # [ 6.045682] systemd[1]: Starting Remount Root and Kernel File Systems...1273test # [ 6.047273] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1274test # [ 6.059264] systemd[1]: Starting Coldplug All udev Devices...1275test # [ 6.064775] systemd[1]: Listening on Journal Log Access Socket.1276test # [ 6.066735] systemd[1]: Mounted Huge Pages File System.1277test # [ 6.070069] systemd[1]: Mounted POSIX Message Queue File System.1278test # [ 6.074141] systemd[1]: Mounted Kernel Debug File System.1279test # [ 6.085456] systemd[1]: Mounted Kernel Trace File System.1280test # [ 6.090194] systemd[1]: Finished Create List of Static Device Nodes.1281test # [ 6.097651] systemd[1]: Mounted Kernel Configuration File System.1282test # [ 6.104394] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1283test # [ 6.106582] systemd-journald[323]: Collecting audit messages is enabled.1284test # [ 6.115598] systemd[1]: Finished Load Kernel Modules.1285test # [ 6.118416] systemd[1]: Starting Firewall...1286test # [ 6.115007] systemd[1]: Queued start job for default target Multi-User System.1287test # [ 6.115860] systemd[1]: systemd-journald.service: Deactivated successfully.1288test # [ 6.119616] systemd-modules-load[324]: Module 'atkbd' is built in1289test # [ 6.124227] systemd-modules-load[324]: Module 'ctr' is built in1290test # [ 6.124895] systemd-modules-load[324]: Module 'loop' is built in1291test # [ 6.133412] systemd[1]: Starting Apply Kernel Variables...1292test # [ 6.133910] systemd[1]: Started Journal Service.1293test # [ 6.129927] systemd[1]: Mounted FUSE Control File System.1294test # [ 6.159213] EXT4-fs (vda): re-mounted 8a2f08a8-9d27-4e1c-aeb4-f444db106339.1295test # [ 6.168116] systemd[1]: Finished Remount Root and Kernel File Systems.1296test # [ 6.168749] systemd[1]: Listening on Disk Image Download Service Socket.1297test # [ 6.181486] systemd[1]: Starting Flush Journal to Persistent Storage...1298test # [ 6.182097] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1299test # [ 6.189230] systemd[1]: Starting Load/Save OS Random Seed...1300test # [ 6.189787] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1301test # [ 6.193492] systemd-oomd[325]: No swap; memory pressure usage will be degraded1302test # [ 6.208910] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1303test # [ 6.256355] systemd[1]: Finished Load/Save OS Random Seed.1304test # [ 6.256906] systemd[1]: Reached target First Boot Complete.1305test # [ 6.265193] systemd-journald[323]: Received client request to flush runtime journal.1306second # [ 6.277221] systemd[1]: bpf-restrict-fs: LSM BPF program attached1307test # [ 6.300651] systemd[1]: Finished Flush Journal to Persistent Storage.1308test # [ 6.327696] systemd[1]: Finished Apply Kernel Variables.1309test # [ 6.332839] systemd[1]: Starting Network Name Resolution...1310test # [ 6.361490] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1311test # [ 6.362766] systemd[1]: Starting Create Static Device Nodes in /dev...1312second # [ 6.407481] systemd[1]: Applying preset policy.1313test # [ 6.502059] systemd-resolved[352]: Positive Trust Anchors:1314test # [ 6.502620] systemd-resolved[352]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1315test # [ 6.503519] systemd-resolved[352]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161316test # [ 6.508331] systemd-resolved[352]: 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 test1317second # [ 6.571672] systemd[1]: Populated /etc with preset unit settings.1318test # [ 6.627933] systemd[1]: Finished Coldplug All udev Devices.1319test # [ 6.653353] systemd[1]: Finished Create Static Device Nodes in /dev.1320test # [ 6.653964] systemd[1]: Reached target Preparation for Local File Systems.1321test # [ 6.655461] systemd[1]: Starting Rule-based Manager for Device Events and Files...1322test # [ 6.664302] systemd-resolved[352]: Using system hostname 'test'.1323test # [ 6.667607] systemd[1]: Started Network Name Resolution.1324test # [ 6.707561] systemd-udevd[365]: Using default interface naming scheme 'v261'.1325second # [ 6.787402] systemd[1]: initrd-switch-root.service: Deactivated successfully.1326second # [ 6.788311] systemd[1]: Stopped initrd-switch-root.service.1327test # [ 6.778708] systemd[1]: Started Rule-based Manager for Device Events and Files.1328second # [ 6.790418] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1329second # [ 6.792205] systemd[1]: Created slice Slice /system/getty.1330second # [ 6.793976] systemd[1]: Created slice User and Session Slice.1331second # [ 6.795067] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1332second # [ 6.796331] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1333second # [ 6.798199] systemd[1]: Expecting device /dev/hvc0...1334second # [ 6.799673] systemd[1]: Expecting device /dev/ttyAMA0...1335second # [ 6.801245] systemd[1]: Reached target Local Encrypted Volumes.1336second # [ 6.802239] systemd[1]: Stopped target initrd-fs.target.1337second # [ 6.803250] systemd[1]: Stopped target initrd-root-fs.target.1338second # [ 6.804905] systemd[1]: Stopped target initrd-switch-root.target.1339second # [ 6.806458] systemd[1]: Reached target Virtual Machines and Containers.1340second # [ 6.808058] systemd[1]: Reached target Path Units.1341second # [ 6.809509] systemd[1]: Reached target Remote File Systems.1342second # [ 6.811006] systemd[1]: Reached target Slice Units.1343second # [ 6.811879] systemd[1]: Reached target Swaps.1344second # [ 6.814228] systemd[1]: Listening on Query the User Interactively for a Password.1345second # [ 6.816936] systemd[1]: Listening on Process Core Dump Socket.1346second # [ 6.818984] systemd[1]: Listening on Credential Encryption/Decryption.1347second # [ 6.821251] systemd[1]: Listening on Factory Reset Management.1348second # [ 6.822290] systemd[1]: Listening on Hostname Service Socket.1349second # [ 6.825312] systemd[1]: Starting Journal Log Access Socket...1350second # [ 6.827161] systemd[1]: Listening on Journal Audit Socket.1351second # [ 6.830359] systemd[1]: Listening on Console Output Muting Service Socket.1352second # [ 6.831637] systemd[1]: Listening on Network Management Metrics Varlink Socket.1353second # [ 6.833388] systemd[1]: Listening on Network Management Varlink Socket.1354second # [ 6.835667] systemd[1]: Listening on Network Management Netlink Socket.1355second # [ 6.837461] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1356second # [ 6.839134] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1357second # [ 6.840945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1358second # [ 6.845505] systemd[1]: Listening on Disk Repartitioning Service Socket.1359second # [ 6.846614] systemd[1]: Listening on Resolve Monitor Varlink Socket.1360second # [ 6.847717] systemd[1]: Listening on Resolve Service Varlink Socket.1361second # [ 6.848860] systemd[1]: Listening on udev Control Socket.1362second # [ 6.850395] systemd[1]: Listening on udev Varlink Socket.1363second # [ 6.853193] systemd[1]: Mounting Huge Pages File System...1364second # [ 6.855374] systemd[1]: Mounting POSIX Message Queue File System...1365test # [ 6.849263] systemd[1]: Mounting /run/wrappers...1366second # [ 6.864637] systemd[1]: Mounting Kernel Debug File System...1367second # [ 6.872765] systemd[1]: Mounting Kernel Trace File System...1368second # [ 6.880692] systemd[1]: Starting Create List of Static Device Nodes...1369second # [ 6.882240] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1370second # [ 6.891583] systemd[1]: Mounting Kernel Configuration File System...1371second # [ 6.897470] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1372second # [ 6.900698] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1373second # [ 6.905025] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1374second # [ 6.917888] systemd[1]: Mounting FUSE Control File System...1375test # [ 6.910093] systemd[1]: Mounted /run/wrappers.1376test # [ 6.910726] systemd[1]: Reached target Local File Systems.1377second # [ 6.922713] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671378test # [ 6.918669] systemd[1]: Listening on Boot Loader Control Service Socket.1379test # [ 6.923042] systemd[1]: Starting register-nix-paths.service...1380test # [ 6.925671] systemd[1]: Starting Create SUID/SGID Wrappers...1381test # [ 6.926246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1382test # [ 6.930134] systemd[1]: Starting Save Transient machine-id to Disk...1383second # [ 6.943713] systemd[1]: Starting Journal Service...1384test # [ 6.935844] systemd[1]: Starting Create System Files and Directories...1385test # [ 6.937974] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1386second # [ 6.955517] systemd[1]: Starting Load Kernel Modules...1387second # [ 6.976631] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1388test # [ 6.966568] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1389second # [ 6.982770] systemd[1]: Starting Remount Root and Kernel File Systems...1390second # [ 6.992872] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1391second # [ 7.001608] systemd[1]: Starting Coldplug All udev Devices...1392second # [ 7.013901] systemd[1]: Listening on Journal Log Access Socket.1393second # [ 7.018380] systemd-journald[298]: Collecting audit messages is enabled.1394second # [ 7.021942] systemd[1]: Mounted Huge Pages File System.1395second # [ 7.023441] systemd[1]: Mounted POSIX Message Queue File System.1396second # [ 7.022931] systemd[1]: Queued start job for default target Multi-User System.1397second # [ 7.027723] systemd[1]: systemd-journald.service: Deactivated successfully.1398second # [ 7.031630] systemd-modules-load[299]: Module 'atkbd' is built in1399second # [ 7.041230] systemd[1]: Started Journal Service.1400test # [ 7.032074] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1401second # [ 7.036356] systemd-modules-load[299]: Module 'ctr' is built in1402second # [ 7.037019] systemd-modules-load[299]: Module 'loop' is built in1403test # [ 7.039375] systemd[1]: Finished Save Transient machine-id to Disk.1404second # [ 7.044136] systemd[1]: Mounted Kernel Debug File System.1405second # [ 7.045929] systemd[1]: Mounted Kernel Trace File System.1406second # [ 7.046477] systemd[1]: Finished Create List of Static Device Nodes.1407second # [ 7.047045] systemd[1]: Finished Load Kernel Modules.1408second # [ 7.049448] systemd[1]: Mounted Kernel Configuration File System.1409second # [ 7.061290] systemd[1]: Starting Firewall...1410second # [ 7.063401] systemd[1]: Starting Apply Kernel Variables...1411second # [ 7.068844] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1412second # [ 7.096643] EXT4-fs (vda): re-mounted 5945dc3c-f424-4c4b-a92a-a34ca44e4b3a.1413test # [ 7.093305] systemd[1]: Finished Create System Files and Directories.1414test # [ 7.101871] systemd[1]: Starting Rebuild Journal Catalog...1415second # [ 7.106836] systemd-oomd[301]: No swap; memory pressure usage will be degraded1416second # [ 7.107705] systemd[1]: Finished Remount Root and Kernel File Systems.1417test # [ 7.107015] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1418second # [ 7.116299] systemd[1]: Listening on Disk Image Download Service Socket.1419second # [ 7.118748] systemd[1]: Starting Flush Journal to Persistent Storage...1420second # [ 7.119379] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1421second # [ 7.124635] systemd[1]: Starting Load/Save OS Random Seed...1422second # [ 7.125176] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1423second # [ 7.125921] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1424second # [ 7.170124] systemd[1]: Mounted FUSE Control File System.1425second # [ 7.200673] systemd-journald[298]: Received client request to flush runtime journal.1426test # [ 7.196979] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1427test # [ 7.211786] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1428second # [ 7.248814] systemd[1]: Finished Load/Save OS Random Seed.1429second # [ 7.249432] systemd[1]: Reached target First Boot Complete.1430second # [ 7.249964] systemd[1]: Finished Apply Kernel Variables.1431second # [ 7.250472] systemd[1]: Starting Network Name Resolution...1432test # [ 7.253505] systemd[1]: Finished Rebuild Journal Catalog.1433second # [ 7.261106] systemd[1]: Finished Flush Journal to Persistent Storage.1434test # [ 7.259618] systemd[1]: Starting Update is Completed...1435test # [ 7.310743] systemd[1]: Finished Update is Completed.1436test # [ 7.318569] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1437second # [ 7.373519] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1438second # [ 7.376505] systemd[1]: Starting Create Static Device Nodes in /dev...1439second # [ 7.393497] systemd-resolved[325]: Positive Trust Anchors:1440second # [ 7.394087] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1441second # [ 7.394989] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161442second # [ 7.395867] systemd-resolved[325]: 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 test1443second # [ 7.573734] systemd-resolved[325]: Using system hostname 'second'.1444second # [ 7.576587] systemd[1]: Started Network Name Resolution.1445test # [ 7.575715] (udev-worker)[398]: Network interface NamePolicy= disabled on kernel command line.1446test # [ 7.581585] (udev-worker)[399]: Network interface NamePolicy= disabled on kernel command line.1447second # [ 7.611735] systemd[1]: Finished Coldplug All udev Devices.1448second # [ 7.615606] systemd[1]: Finished Create Static Device Nodes in /dev.1449second # [ 7.616475] systemd[1]: Reached target Preparation for Local File Systems.1450second # [ 7.618554] systemd[1]: Starting Rule-based Manager for Device Events and Files...1451second # [ 7.671331] systemd-udevd[342]: Using default interface naming scheme 'v261'.1452second # [ 7.748156] systemd[1]: Started Rule-based Manager for Device Events and Files.1453test # [ 7.754946] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1454test # [ 7.757934] systemd[1]: Finished Create SUID/SGID Wrappers.1455second # [ 7.784457] systemd[1]: Mounting /run/wrappers...1456test # [ 7.784108] systemd[1]: Finished register-nix-paths.service.1457test # [ 7.784672] systemd[1]: Reached target System Initialization.1458test # [ 7.788079] systemd[1]: Started Discard unused filesystem blocks once a week.1459test # [ 7.788746] systemd[1]: Started Daily Cleanup of Temporary Directories.1460test # [ 7.789343] systemd[1]: Reached target Timer Units.1461test # [ 7.789797] systemd[1]: Listening on D-Bus System Message Bus Socket.1462test # [ 7.790365] systemd[1]: Listening on Nix Daemon Socket.1463test # [ 7.800171] systemd[1]: Listening on Authorization Manager Agent Helper.1464test # [ 7.800824] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1465test # [ 7.801559] systemd[1]: Reached target Socket Units.1466test # [ 7.804585] systemd[1]: Starting D-Bus System Message Bus...1467test # [ 7.853197] mousedev: PS/2 mouse device common for all mice1468second # [ 7.882374] systemd[1]: Mounted /run/wrappers.1469second # [ 7.883055] systemd[1]: Reached target Local File Systems.1470second # [ 7.887537] systemd[1]: Listening on Boot Loader Control Service Socket.1471second # [ 7.891777] systemd[1]: Starting register-nix-paths.service...1472second # [ 7.896087] systemd[1]: Starting Create SUID/SGID Wrappers...1473second # [ 7.896666] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1474second # [ 7.907757] systemd[1]: Starting Save Transient machine-id to Disk...1475second # [ 7.922800] systemd[1]: Starting Create System Files and Directories...1476second # [ 7.934113] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1477second # [ 7.935075] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1478test # [ 7.933354] dbus-broker-launch[519]: Looking up NSS user entry for 'systemd-timesync'...1479test # [ 7.939230] dbus-broker-launch[519]: NSS returned no entry for 'systemd-timesync'1480test # [ 7.939906] dbus-broker-launch[519]: Invalid user-name in /nix/store/vjjz5ppi4b35w0imjxw7ww7g00agg6rq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1481test # [ 7.977089] dbus-broker-launch[519]: 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'1482test # [ 7.981306] dbus-broker-launch[519]: 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'1483test # [ 7.985397] dbus-broker-launch[519]: 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'1484test # [ 7.990056] dbus-broker-launch[519]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1485test # [ 7.996824] systemd[1]: Started D-Bus System Message Bus.1486test # [ 7.997381] systemd[1]: Reached target Basic System.1487second # [ 8.002007] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1488test # [ 8.001774] systemd[1]: Starting Network Manager Script Dispatcher Service...1489test # [ 8.007143] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1490second # [ 8.011395] systemd[1]: Finished Save Transient machine-id to Disk.1491test # [ 8.012277] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1492test # [ 8.032066] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1493test # [ 8.058792] dbus-broker-launch[519]: Ready1494test # [ 8.063675] systemd[1]: Started backdoor.service.1495second # [ 8.076683] systemd[1]: Finished Create System Files and Directories.1496second # [ 8.084351] systemd[1]: Starting Rebuild Journal Catalog...1497second # [ 8.091898] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1498test # [ 8.090629] systemd[1]: Starting Import lastlog data into lastlog2 database...1499test # [ 8.104405] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1500test # [ 8.116224] systemd[1]: Starting Post-Boot Actions...1501test # [ 8.138338] systemd[1]: Started Reset console on configuration changes.1502test # [ 8.171142] bjcs8lkapwzyd3k2m2ffxyc0kzi5zcz7-wifi-secrets[537]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1503second # [ 8.181052] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1504test # [ 8.191841] bisan2gw8hg9biapdba8ky2a4y7pdg5v-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1505test # [ 8.213563] iqm3pxl9lwg0i0gp0zb96hq5rq9q1qck-wifi-secrets[540]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1506second # [ 8.228186] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1507test # [ 8.236608] systemd[1]: Finished Firewall.1508test # [ 8.237089] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1509test # [ 8.248278] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1510test # [ 8.248931] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1511test # [ 8.255859] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1512second # [ 8.261504] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1513test # connecting to host...1514second # [ 8.265581] systemd[1]: Finished Rebuild Journal Catalog.1515test # [ 8.262942] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1516test # [ 8.263759] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1517second # [ 8.272558] systemd[1]: Starting Update is Completed...1518test # [ 8.288473] systemd[1]: Reached target Preparation for Network.1519test # [ 8.289030] systemd[1]: Listening on Network Management Resolve Hook Socket.1520test # [ 8.298354] nsncd[547]: Sep 21 10:21:04.121 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1521test # [ 8.306290] systemd[1]: Starting Network Manager...1522test # [ 8.314356] systemd[1]: Starting Network Management...1523test: Guest shell says: b'Spawning backdoor root shell...\n'1524test # [ 8.326195] systemd[1]: Started Name Service Cache Daemon (nsncd).1525second # [ 8.344782] systemd[1]: Finished Update is Completed.1526test # [ 8.358899] systemd[1]: Finished Post-Boot Actions.1527test # [ 8.376662] systemd[1]: Started Network Manager Script Dispatcher Service.1528test # [ 8.377245] systemd[1]: Reached target Host and Network Name Lookups.1529test: connected to guest root shell1530test # [ 8.382683] systemd[1]: Reached target User and Group Name Lookups.1531test: (connecting took 8.63 seconds)1532test: (finished: waiting for the VM to finish booting, in 8.63 seconds)1533test # [ 8.391591] systemd[1]: Starting User Login Management...1534test # [ 8.422210] systemd[1]: Finished Import lastlog data into lastlog2 database.1535test # [ 8.540328] systemd-logind[597]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1536test # [ 8.543914] systemd-logind[597]: Watching system buttons on /dev/input/event0 (gpio-keys)1537test # [ 8.545942] systemd-logind[597]: New seat seat0.1538second # [ 8.554445] (udev-worker)[368]: Network interface NamePolicy= disabled on kernel command line.1539test # [ 8.560789] systemd[1]: Started User Login Management.1540second # [ 8.568243] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line.1541test # [ 8.565729] systemd[1]: Starting linger-users.service...1542test # [ 8.650757] cfg80211: Loading compiled-in X.509 certificates for regulatory database1543test # [ 8.682567] systemd[1]: linger-users.service: Deactivated successfully.1544test # [ 8.685685] systemd[1]: Finished linger-users.service.1545test # [ 8.701894] systemd[1]: Starting Hostname Service...1546test # [ 8.712753] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1547test # [ 8.713307] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1548second # [ 8.759170] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1549second # [ 8.761040] systemd[1]: Finished Create SUID/SGID Wrappers.1550test # [ 8.767007] systemd-networkd[586]: /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.1551test # [ 8.774242] systemd-networkd[586]: /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.1552second # [ 8.781756] systemd[1]: Finished register-nix-paths.service.1553second # [ 8.782328] systemd[1]: Reached target System Initialization.1554second # [ 8.782881] systemd[1]: Started Discard unused filesystem blocks once a week.1555second # [ 8.783518] systemd[1]: Started Daily Cleanup of Temporary Directories.1556test # [ 8.781580] systemd-networkd[586]: lo: Link UP1557test # [ 8.781993] systemd-networkd[586]: lo: Gained carrier1558second # [ 8.788446] systemd[1]: Reached target Timer Units.1559second # [ 8.788939] systemd[1]: Listening on D-Bus System Message Bus Socket.1560second # [ 8.789510] systemd[1]: Listening on Nix Daemon Socket.1561test # [ 8.786556] systemd[1]: Started Network Management.1562test # [ 8.792903] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1563second # [ 8.797015] systemd[1]: Listening on Authorization Manager Agent Helper.1564second # [ 8.797645] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1565second # [ 8.798403] systemd[1]: Reached target Socket Units.1566test # [ 8.796864] systemd-networkd[586]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1567test # [ 8.798141] systemd-networkd[586]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1568second # [ 8.801916] systemd[1]: Starting D-Bus System Message Bus...1569test # [ 8.809234] systemd-networkd[586]: eth1: Link UP1570test # [ 8.809684] systemd-networkd[586]: eth1: Gained carrier1571test # [ 8.810120] systemd-networkd[586]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1572test # [ 8.845553] systemd[1]: Started Hostname Service.1573test # [ 8.862408] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1574second # [ 8.896516] mousedev: PS/2 mouse device common for all mice1575second # [ 8.907318] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'...1576second # [ 8.912632] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync'1577second # [ 8.913308] dbus-broker-launch[501]: Invalid user-name in /nix/store/vjjz5ppi4b35w0imjxw7ww7g00agg6rq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1578test # [ 8.917772] systemd-networkd[586]: eth0: Link UP1579test # [ 8.918244] systemd-networkd[586]: eth0: Gained carrier1580test # [ 8.935014] systemd[1]: Started Network Manager.1581test # [ 8.936781] systemd[1]: Reached target Network.1582test # [ 8.943498] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1583test # [ 8.952096] systemd[1]: Starting Permit User Sessions...1584second # [ 8.973683] dbus-broker-launch[501]: 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'1585second # [ 8.977726] dbus-broker-launch[501]: 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'1586second # [ 8.981656] dbus-broker-launch[501]: 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'1587second # [ 8.986839] dbus-broker-launch[501]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1588second # [ 8.993430] systemd[1]: Started D-Bus System Message Bus.1589second # [ 8.993960] systemd[1]: Reached target Basic System.1590second # [ 9.000134] systemd[1]: Starting Network Manager Script Dispatcher Service...1591second # [ 9.005500] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1592second # [ 9.025447] systemd[1]: Started backdoor.service.1593second # [ 9.044472] systemd[1]: Starting Import lastlog data into lastlog2 database...1594second # [ 9.051389] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1595second # [ 9.059685] dbus-broker-launch[501]: Ready1596second # [ 9.066120] systemd[1]: Starting Post-Boot Actions...1597second # [ 9.074036] systemd[1]: Started Reset console on configuration changes.1598test # [ 9.081258] systemd[1]: Finished Permit User Sessions.1599second # [ 9.084874] systemd[1]: Finished Firewall.1600test # [ 9.091656] systemd[1]: Started Getty on tty1.1601test # [ 9.092184] systemd[1]: Reached target Login Prompts.1602test # [ 9.114030] NetworkManager[585]: <warn> [1789986064.9311] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1603second # [ 9.144091] bjcs8lkapwzyd3k2m2ffxyc0kzi5zcz7-wifi-secrets[520]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1604test # [ 9.147079] systemd-resolved[352]: eth0: Bus client set default route setting: yes1605test # [ 9.150755] systemd-resolved[352]: eth0: Bus client set DNS server list to: 10.0.2.31606second # connecting to host...1607second # [ 9.176098] systemd[1]: Reached target Preparation for Network.1608second # [ 9.176669] systemd[1]: Listening on Network Management Resolve Hook Socket.1609second # [ 9.192593] systemd[1]: Starting Network Manager...1610test # [ 9.210590] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1611test # [ 9.215621] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1612second # [ 9.219670] systemd[1]: Starting Network Management...1613second # [ 9.237994] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1614second # [ 9.245294] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1615second # [ 9.251221] systemd[1]: Started Name Service Cache Daemon (nsncd).1616second # [ 9.257366] nsncd[524]: Sep 21 10:21:05.082 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1617second # [ 9.263891] systemd[1]: Finished Post-Boot Actions.1618second # [ 9.285641] systemd[1]: Reached target Host and Network Name Lookups.1619second # [ 9.288091] systemd[1]: Reached target User and Group Name Lookups.1620second # [ 9.306006] systemd[1]: Starting User Login Management...1621second # [ 9.311270] systemd[1]: Finished Import lastlog data into lastlog2 database.1622second # [ 9.333034] systemd[1]: Started Network Manager Script Dispatcher Service.1623test # [ 9.372207] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31624test # [ 9.410417] systemd[1]: Reached target Multi-User System.1625test # [ 9.413736] systemd[1]: Startup finished in 747ms (kernel) + 3.786s (initrd) + 4.879s (userspace) = 9.412s.1626second # [ 9.431601] systemd-logind[558]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1627second # [ 9.433713] systemd-logind[558]: Watching system buttons on /dev/input/event0 (gpio-keys)1628second # [ 9.436368] systemd-logind[558]: New seat seat0.1629second # [ 9.440143] systemd[1]: Started User Login Management.1630second # [ 9.446083] systemd[1]: Starting linger-users.service...1631second # [ 9.556185] cfg80211: Loading compiled-in X.509 certificates for regulatory database1632second # [ 9.574025] systemd[1]: linger-users.service: Deactivated successfully.1633second # [ 9.574769] systemd[1]: Finished linger-users.service.1634second # [ 9.605961] systemd[1]: Starting Hostname Service...1635second # [ 9.630034] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1636second # [ 9.630586] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1637test: (finished: waiting for unit NetworkManager.service, in 9.89 seconds)1638test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1639second # [ 9.670397] systemd-networkd[540]: /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.1640second # [ 9.676753] systemd-networkd[540]: /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.1641second # [ 9.682085] systemd-networkd[540]: lo: Link UP1642second # [ 9.682537] systemd-networkd[540]: lo: Gained carrier1643test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds)1644test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1645second # [ 9.694762] systemd[1]: Started Network Management.1646second # [ 9.695241] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1647second # [ 9.708950] systemd-networkd[540]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1648second # [ 9.709750] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1649second # [ 9.710389] systemd-networkd[540]: eth1: Link UP1650second # [ 9.710799] systemd-networkd[540]: eth1: Gained carrier1651second # [ 9.711239] systemd-networkd[540]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1652test # [ 9.722330] systemd[1]: Starting Virtual Console Setup...1653test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.05 seconds)1654test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1655test # [ 9.740752] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1656test # [ 9.744179] systemd[1]: Stopped Virtual Console Setup.1657second # [ 9.748888] systemd[1]: Started Hostname Service.1658test # [ 9.754525] systemd[1]: Starting Virtual Console Setup...1659second # [ 9.767997] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1660test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1661(finished: run the VM test script, in 10.53 seconds)1662second # [ 9.812998] systemd-networkd[540]: eth0: Link UP1663second # [ 9.814279] systemd-networkd[540]: eth0: Gained carrier1664test script finished in 10.58s1665cleanup1666kill QemuMachine (pid 47)1667second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1668second # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1669second # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1670second # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1671test # [ 9.857865] systemd-networkd[586]: eth1: Gained IPv6LL1672test # [ 9.861183] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1673test # [ 9.878311] systemd-logind[597]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1674test # [ 9.930457] systemd-vconsole-setup[681]: Configuration of first virtual console was skipped, ignoring remaining ones.1675test # [ 9.932911] systemd[1]: Finished Virtual Console Setup.1676kill QemuMachine (pid 48)1677test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1678test # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1679test # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1680test # [2026-09-21T10:21:06Z INFO virtiofsd] Client disconnected, shutting down1681(finished: cleanup, in 0.29 seconds)