vm-test-run-wifi
checks.aarch64-linux.wifi
· build #805
· raw
1build01: building 1nmpj8fdcxac60rggajmhx1nkm5v8vjm-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 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_ssh11start all VMs12second: starting vm13test: starting vm14test: QEMU running (pid 48)15test # Disk image does not exist, creating the virtualisation disk image...16test # Formatting '/build/vm-state-test/tmp.jOF4eLRqjc', fmt=raw size=107374182417test # mke2fs 1.47.4 (6-Mar-2025)18test # Discarding device blocks: 0/262144 done19test # Creating filesystem with 262144 4k blocks and 65536 inodes20test # Filesystem UUID: 2af24f21-5934-4bcf-8bcc-e81bcb2eefab21test # Superblock backups stored on blocks:22test # 32768, 98304, 163840, 22937623test # 24test # Allocating group tables: 0/8 done25test # Writing inode tables: 0/8 done26test # Creating journal (8192 blocks): done27test # Writing superblocks and filesystem accounting information: 0/8 done28test # 29test # Virtualisation disk image created.30test # Starting virtiofs daemons...31test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)32test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether33test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...34test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether36test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...37test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38test # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether39test # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...40test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests41test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests42test # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests43second # Disk image does not exist, creating the virtualisation disk image...44second: QEMU running (pid 47)45second # Formatting '/build/vm-state-second/tmp.NRgY7pNB6a', fmt=raw size=107374182446second # mke2fs 1.47.4 (6-Mar-2025)47second # Discarding device blocks: 0/262144 done48second # Creating filesystem with 262144 4k blocks and 65536 inodes49second # Filesystem UUID: 12635fd7-15f7-42ad-91aa-f3e70fc0184f50second # Superblock backups stored on blocks:51second # 32768, 98304, 163840, 22937652second # 53second # Allocating group tables: 0/8 done54second # Writing inode tables: 0/8 done55second # Creating journal (8192 blocks): done56second # Writing superblocks and filesystem accounting information: 0/8 done57second # 58second # Virtualisation disk image created.59second # Starting virtiofs daemons...60second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether62second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...63second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-09-29T10:26:52Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-09-29T10:26:52Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests70second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests71second # [2026-09-29T10:26:52Z INFO virtiofsd] Client connected, servicing requests72(finished: start all VMs, in 0.48 seconds)73test: waiting for unit NetworkManager.service74test: waiting for the VM to finish booting75test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202677test # [ 0.000000] KASLR enabled78test # [ 0.000000] random: crng init done79test # [ 0.000000] Machine model: linux,dummy-virt80test # [ 0.000000] efi: UEFI not found.81test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT82test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]83test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]84second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]85test # [ 0.000000] Zone ranges:86test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]87test # [ 0.000000] DMA32 empty88second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202689test # [ 0.000000] Normal empty90second # [ 0.000000] KASLR enabled91test # [ 0.000000] Device empty92second # [ 0.000000] random: crng init done93test # [ 0.000000] Movable zone start for each node94second # [ 0.000000] Machine model: linux,dummy-virt95test # [ 0.000000] Early memory node ranges96second # [ 0.000000] efi: UEFI not found.97test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]98second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT99test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]100second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]101test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000102second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]103test # [ 0.000000] psci: probing for conduit method from DT.104second # [ 0.000000] Zone ranges:105test # [ 0.000000] psci: PSCIv1.3 detected in firmware.106second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]107test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs108second # [ 0.000000] DMA32 empty109second # [ 0.000000] Normal empty110test # [ 0.000000] psci: Trusted OS migration not required111second # [ 0.000000] Device empty112test # [ 0.000000] psci: SMC Calling Convention v1.1113second # [ 0.000000] Movable zone start for each node114second # [ 0.000000] Early memory node ranges115test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)116second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]117test # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296118test # [ 0.000000] Detected PIPT I-cache on CPU0119second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]120test # [ 0.000000] CPU features: detected: GICv3 CPU interface121second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000122test # [ 0.000000] CPU features: detected: Spectre-v4123second # [ 0.000000] psci: probing for conduit method from DT.124test # [ 0.000000] CPU features: detected: Spectre-BHB125second # [ 0.000000] psci: PSCIv1.3 detected in firmware.126test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR127second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs128second # [ 0.000000] psci: Trusted OS migration not required129test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)130second # [ 0.000000] psci: SMC Calling Convention v1.1131test # [ 0.000000] CPU features: detected: ARM erratum 1418040132test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion133second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)134test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing135second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296136test # [ 0.000000] alternatives: applying boot alternatives137second # [ 0.000000] Detected PIPT I-cache on CPU0138second # [ 0.000000] CPU features: detected: GICv3 CPU interface139second # [ 0.000000] CPU features: detected: Spectre-v4140second # [ 0.000000] CPU features: detected: Spectre-BHB141second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)143second # [ 0.000000] CPU features: detected: ARM erratum 1418040144test # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/2qarjrgmq4v844l3yxqpspmfvvgifl3v-nixos-system-test-test/init regInfo=/nix/.ro-store/x7rz7p3xa6ipmryscq5jgklv99swn46q-closure-info/registration console=ttyAMA0,115200n8 console=tty0145second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion146second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing147test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/x7rz7p3xa6ipmryscq5jgklv99swn46q-closure-info/registration", will be passed to user space.148second # [ 0.000000] alternatives: applying boot alternatives149test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes150test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)151test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)152test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB153test # [ 0.000000] software IO TLB: area num 1.154second # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/zcaykmk7yj15a72a7sy16f6hdjrmqrds-nixos-system-second-test/init regInfo=/nix/.ro-store/yjyhgsj0zjx42h3pqryn94mywiw2rjgd-closure-info/registration console=ttyAMA0,115200n8 console=tty0155test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)156test # [ 0.000000] Fallback order for Node 0: 0157second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yjyhgsj0zjx42h3pqryn94mywiw2rjgd-closure-info/registration", will be passed to user space.158test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144159test # [ 0.000000] Policy zone: DMA160second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes161test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off162second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)163test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1164test # [ 0.000000] allocated 2097152 bytes of page_ext165second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)166test # [ 0.000000] ftrace: allocating 74966 entries in 294 pages167second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168test # [ 0.000000] ftrace: allocated 294 pages with 4 groups169second # [ 0.000000] software IO TLB: area num 1.170test # [ 0.000000] rcu: Hierarchical RCU implementation.171second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)172test # [ 0.000000] rcu: RCU event tracing is enabled.173second # [ 0.000000] Fallback order for Node 0: 0174test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.175second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144176test # [ 0.000000] Trampoline variant of Tasks RCU enabled.177second # [ 0.000000] Policy zone: DMA178test # [ 0.000000] Rude variant of Tasks RCU enabled.179second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off180test # [ 0.000000] Tracing variant of Tasks RCU enabled.181second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1182test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.183second # [ 0.000000] allocated 2097152 bytes of page_ext184test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1185second # [ 0.000000] ftrace: allocating 74966 entries in 294 pages186second # [ 0.000000] ftrace: allocated 294 pages with 4 groups187test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.188second # [ 0.000000] rcu: Hierarchical RCU implementation.189second # [ 0.000000] rcu: RCU event tracing is enabled.190test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.191second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.192test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.193second # [ 0.000000] Trampoline variant of Tasks RCU enabled.194test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0195second # [ 0.000000] Rude variant of Tasks RCU enabled.196test # [ 0.000000] GICv3: 256 SPIs implemented197second # [ 0.000000] Tracing variant of Tasks RCU enabled.198test # [ 0.000000] GICv3: 0 Extended SPIs implemented199test # [ 0.000000] Root IRQ handler: gic_handle_irq200second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.201test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI202second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1203test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0204second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.205test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000206test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]207second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)209second # [ 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] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0211test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)212second # [ 0.000000] GICv3: 256 SPIs implemented213test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000214second # [ 0.000000] GICv3: 0 Extended SPIs implemented215test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000216second # [ 0.000000] Root IRQ handler: gic_handle_irq217second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI218test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.219second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0220test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns221second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000222second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]223test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040224test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).225second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)226test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns227second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)228second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000229test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns230test # [ 0.000046] arm-pv: using stolen time PV231second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000232test # [ 0.000373] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)233second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.234test # [ 0.000631] Console: colour dummy device 80x25235test # [ 0.000648] printk: legacy console [tty0] enabled236second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns237second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040238test # [ 0.000854] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)239test # [ 0.000859] pid_max: default: 32768 minimum: 301240second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).241test # [ 0.000940] LSM: initializing lsm=capability,landlock,yama,bpf,ima242test # [ 0.001073] landlock: Up and running.243second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns244test # [ 0.001075] Yama: becoming mindful.245second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns246test # [ 0.001473] LSM support for eBPF active247second # [ 0.000058] arm-pv: using stolen time PV248test # [ 0.001638] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)249second # [ 0.000506] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)250test # [ 0.001657] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)251second # [ 0.000786] Console: colour dummy device 80x25252test # [ 0.004459] rcu: Hierarchical SRCU implementation.253second # [ 0.000802] printk: legacy console [tty0] enabled254test # [ 0.004467] rcu: Max phase no-delay instances is 1000.255test # [ 0.005411] fsl-mc MSI: its@8080000 domain created256second # [ 0.001022] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)257test # [ 0.005624] EFI services will not be available.258second # [ 0.001027] pid_max: default: 32768 minimum: 301259test # [ 0.005713] smp: Bringing up secondary CPUs ...260test # [ 0.005745] smp: Brought up 1 node, 1 CPU261second # [ 0.001104] LSM: initializing lsm=capability,landlock,yama,bpf,ima262test # [ 0.005748] SMP: Total of 1 processors activated.263second # [ 0.001232] landlock: Up and running.264second # [ 0.001235] Yama: becoming mindful.265test # [ 0.005750] CPU: All CPU(s) started at EL1266second # [ 0.001649] LSM support for eBPF active267test # [ 0.005762] CPU features: detected: 32-bit EL0 Support268second # [ 0.001813] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)269test # [ 0.005765] CPU features: detected: Data cache clean to the PoU not required for I/D coherence270test # [ 0.005768] CPU features: detected: Common not Private translations271second # [ 0.001834] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)272test # [ 0.005770] CPU features: detected: CRC32 instructions273second # [ 0.004653] rcu: Hierarchical SRCU implementation.274second # [ 0.004660] rcu: Max phase no-delay instances is 1000.275test # [ 0.005772] CPU features: detected: Data cache clean to Point of Persistence276second # [ 0.005600] fsl-mc MSI: its@8080000 domain created277test # [ 0.005774] CPU features: detected: Enhanced Virtualization Traps278second # [ 0.005835] EFI services will not be available.279test # [ 0.005784] CPU features: detected: RCpc load-acquire (LDAPR)280second # [ 0.005920] smp: Bringing up secondary CPUs ...281second # [ 0.005946] smp: Brought up 1 node, 1 CPU282test # [ 0.005787] CPU features: detected: LSE atomic instructions283second # [ 0.005949] SMP: Total of 1 processors activated.284test # [ 0.005789] CPU features: detected: Privileged Access Never285second # [ 0.005951] CPU: All CPU(s) started at EL1286test # [ 0.005790] CPU features: detected: PMUv3287second # [ 0.005963] CPU features: detected: 32-bit EL0 Support288test # [ 0.005792] CPU features: detected: RAS Extension Support289test # [ 0.005796] CPU features: detected: Speculative Store Bypassing Safe (SSBS)290second # [ 0.005965] CPU features: detected: Data cache clean to the PoU not required for I/D coherence291test # [ 0.005818] alternatives: applying system-wide alternatives292second # [ 0.005969] CPU features: detected: Common not Private translations293test # [ 0.007298] CPU features: detected: Hardware dirty bit management on CPU0294second # [ 0.005971] CPU features: detected: CRC32 instructions295second # [ 0.005973] CPU features: detected: Data cache clean to Point of Persistence296test # [ 0.007467] Memory: 891292K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 115948K reserved, 32768K cma-reserved)297second # [ 0.005975] CPU features: detected: Enhanced Virtualization Traps298test # [ 0.007926] devtmpfs: initialized299second # [ 0.005985] CPU features: detected: RCpc load-acquire (LDAPR)300test # [ 0.009478] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)301second # [ 0.005987] CPU features: detected: LSE atomic instructions302second # [ 0.005989] CPU features: detected: Privileged Access Never303test # [ 0.009500] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).304second # [ 0.005991] CPU features: detected: PMUv3305test # [ 0.009629] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL306second # [ 0.005993] CPU features: detected: RAS Extension Support307test # [ 0.009635] 0 pages in range for non-PLT usage308second # [ 0.005996] CPU features: detected: Speculative Store Bypassing Safe (SSBS)309test # [ 0.009635] 508224 pages in range for PLT usage310test # [ 0.009723] pinctrl core: initialized pinctrl subsystem311second # [ 0.006017] alternatives: applying system-wide alternatives312test # [ 0.010464] DMI not present or invalid.313second # [ 0.007435] CPU features: detected: Hardware dirty bit management on CPU0314test # [ 0.013706] NET: Registered PF_NETLINK/PF_ROUTE protocol family315test # [ 0.016159] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations316second # [ 0.007601] Memory: 891288K/1048576K available (24448K kernel code, 7094K rwdata, 26596K rodata, 4736K init, 1107K bss, 115972K reserved, 32768K cma-reserved)317test # [ 0.016291] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations318second # [ 0.008036] devtmpfs: initialized319second # [ 0.009468] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)320test # [ 0.016443] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations321test # [ 0.016461] audit: initializing netlink subsys (disabled)322second # [ 0.009490] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).323test # [ 0.017082] thermal_sys: Registered thermal governor 'fair_share'324second # [ 0.009636] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL325test # [ 0.017084] thermal_sys: Registered thermal governor 'bang_bang'326second # [ 0.009641] 0 pages in range for non-PLT usage327test # [ 0.017087] thermal_sys: Registered thermal governor 'step_wise'328second # [ 0.009642] 508224 pages in range for PLT usage329test # [ 0.017089] thermal_sys: Registered thermal governor 'user_space'330second # [ 0.009755] pinctrl core: initialized pinctrl subsystem331second # [ 0.010552] DMI not present or invalid.332test # [ 0.017091] thermal_sys: Registered thermal governor 'power_allocator'333second # [ 0.013787] NET: Registered PF_NETLINK/PF_ROUTE protocol family334test # [ 0.017126] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1335second # [ 0.016179] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations336test # [ 0.017143] cpuidle: using governor ladder337test # [ 0.017148] cpuidle: using governor menu338second # [ 0.016310] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations339test # [ 0.017279] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.340second # [ 0.016464] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations341test # [ 0.017302] ASID allocator initialised with 32768 entries342test # [ 0.017951] Serial: AMBA PL011 UART driver343second # [ 0.016481] audit: initializing netlink subsys (disabled)344second # [ 0.017020] thermal_sys: Registered thermal governor 'fair_share'345test # [ 0.021350] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1346second # [ 0.017022] thermal_sys: Registered thermal governor 'bang_bang'347test # [ 0.021511] printk: console [ttyAMA0] enabled348second # [ 0.017025] thermal_sys: Registered thermal governor 'step_wise'349second # [ 0.017027] thermal_sys: Registered thermal governor 'user_space'350second # [ 0.017029] thermal_sys: Registered thermal governor 'power_allocator'351second # [ 0.017072] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352second # [ 0.017086] cpuidle: using governor ladder353second # [ 0.017091] cpuidle: using governor menu354second # [ 0.017212] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.355second # [ 0.017234] ASID allocator initialised with 32768 entries356second # [ 0.017887] Serial: AMBA PL011 UART driver357second # [ 0.021581] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1358second # [ 0.021756] printk: console [ttyAMA0] enabled359test # [ 0.094590] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages360test # [ 0.094604] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page361test # [ 0.094607] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages362test # [ 0.094611] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page363test # [ 0.094613] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364test # [ 0.094615] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page365test # [ 0.094617] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages366test # [ 0.094619] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page367test # [ 0.095656] fbcon: Taking over console368test # [ 0.095672] ACPI: Interpreter disabled.369test # [ 0.098261] iommu: Default domain type: Translated370test # [ 0.098266] iommu: DMA domain TLB invalidation policy: strict mode371test # [ 0.098574] SCSI subsystem initialized372second # [ 0.096876] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages373second # [ 0.096889] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page374second # [ 0.096892] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages375second # [ 0.096896] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page376test # [ 0.104589] usbcore: registered new interface driver usbfs377second # [ 0.096898] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages378test # [ 0.104620] usbcore: registered new interface driver hub379second # [ 0.096900] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page380test # [ 0.104631] usbcore: registered new device driver usb381second # [ 0.096902] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages382test # [ 0.104858] pps_core: LinuxPPS API ver. 1 registered383second # [ 0.096904] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page384test # [ 0.104861] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>385second # [ 0.097866] fbcon: Taking over console386test # [ 0.104865] PTP clock support registered387second # [ 0.097882] ACPI: Interpreter disabled.388test # [ 0.104892] EDAC MC: Ver: 3.0.0389second # [ 0.098549] iommu: Default domain type: Translated390test # [ 0.105050] scmi_core: SCMI protocol bus registered391test # [ 0.105313] FPGA manager framework392second # [ 0.098553] iommu: DMA domain TLB invalidation policy: strict mode393second # [ 0.098818] SCSI subsystem initialized394second # [ 0.100758] usbcore: registered new interface driver usbfs395test # [ 0.110172] vgaarb: loaded396second # [ 0.100788] usbcore: registered new interface driver hub397test # [ 0.110409] clocksource: Switched to clocksource arch_sys_counter398second # [ 0.100798] usbcore: registered new device driver usb399second # [ 0.101018] pps_core: LinuxPPS API ver. 1 registered400second # [ 0.101021] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401second # [ 0.101032] PTP clock support registered402second # [ 0.101064] EDAC MC: Ver: 3.0.0403second # [ 0.110155] scmi_core: SCMI protocol bus registered404second # [ 0.110519] FPGA manager framework405second # [ 0.114211] vgaarb: loaded406second # [ 0.114508] clocksource: Switched to clocksource arch_sys_counter407test # [ 0.122880] VFS: Disk quotas dquot_6.6.0408test # [ 0.122909] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)409test # [ 0.124325] netfs: FS-Cache loaded410test # [ 0.124456] pnp: PnP ACPI: disabled411test # [ 0.126333] NET: Registered PF_INET protocol family412second # [ 0.124773] VFS: Disk quotas dquot_6.6.0413second # [ 0.124798] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)414test # [ 0.128248] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)415second # [ 0.126282] netfs: FS-Cache loaded416second # [ 0.126391] pnp: PnP ACPI: disabled417second # [ 0.129653] NET: Registered PF_INET protocol family418second # [ 0.129828] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419test # [ 0.152091] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420test # [ 0.152117] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421test # [ 0.152129] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422test # [ 0.152167] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)423test # [ 0.152234] TCP: Hash tables configured (established 8192 bind 8192)424test # [ 0.152331] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)425test # [ 0.152389] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)426test # [ 0.152436] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)427test # [ 0.152560] NET: Registered PF_UNIX/PF_LOCAL protocol family428test # [ 0.152602] NET: Registered PF_XDP protocol family429test # [ 0.152626] PCI: CLS 0 bytes, default 64430test # [ 0.152958] Trying to unpack rootfs image as initramfs...431second # [ 0.153760] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)432test # [ 0.160536] kvm [1]: HYP mode not available433second # [ 0.153783] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)434second # [ 0.153795] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)435second # [ 0.153833] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)436second # [ 0.153899] TCP: Hash tables configured (established 8192 bind 8192)437second # [ 0.153998] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)438second # [ 0.154055] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)439second # [ 0.154078] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)440second # [ 0.154210] NET: Registered PF_UNIX/PF_LOCAL protocol family441second # [ 0.154243] NET: Registered PF_XDP protocol family442second # [ 0.154263] PCI: CLS 0 bytes, default 64443second # [ 0.154547] Trying to unpack rootfs image as initramfs...444second # [ 0.164730] kvm [1]: HYP mode not available445test # [ 0.225382] Initialise system trusted keyrings446test # [ 0.226457] workingset: timestamp_bits=42 max_order=18 bucket_order=0447second # [ 0.231130] Initialise system trusted keyrings448second # [ 0.231255] workingset: timestamp_bits=42 max_order=18 bucket_order=0449test # [ 0.227758] squashfs: version 4.0 (2009/01/31) Phillip Lougher450second # [ 0.231575] squashfs: version 4.0 (2009/01/31) Phillip Lougher451test # [ 0.227801] 9p: Installing v9fs 9p2000 file system support452second # [ 0.231611] 9p: Installing v9fs 9p2000 file system support453second # [ 0.250458] Key type asymmetric registered454second # [ 0.250475] Asymmetric key parser 'x509' registered455test # [ 0.255582] Key type asymmetric registered456test # [ 0.255599] Asymmetric key parser 'x509' registered457test # [ 0.255656] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)458test # [ 0.255752] io scheduler mq-deadline registered459test # [ 0.255756] io scheduler kyber registered460test # [ 0.258025] pl061_gpio 9030000.pl061: PL061 GPIO chip registered461test # [ 0.258353] ledtrig-cpu: registered to indicate activity on CPUs462test # [ 0.261532] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:463test # [ 0.261550] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000464test # [ 0.261578] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000465test # [ 0.261583] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000466test # [ 0.261616] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits467test # [ 0.261646] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]468second # [ 0.258570] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)469second # [ 0.258689] io scheduler mq-deadline registered470test # [ 0.261735] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00471second # [ 0.258692] io scheduler kyber registered472test # [ 0.261756] pci_bus 0000:00: root bus resource [bus 00-ff]473test # [ 0.261759] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]474test # [ 0.261762] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]475test # [ 0.261764] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]476test # [ 0.261832] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint477test # [ 0.262278] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint478second # [ 0.263888] pl061_gpio 9030000.pl061: PL061 GPIO chip registered479second # [ 0.264229] ledtrig-cpu: registered to indicate activity on CPUs480second # [ 0.264528] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:481second # [ 0.264545] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000482second # [ 0.264562] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000483second # [ 0.264567] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000484second # [ 0.264596] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits485second # [ 0.264616] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]486second # [ 0.264685] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00487second # [ 0.264705] pci_bus 0000:00: root bus resource [bus 00-ff]488second # [ 0.264709] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]489second # [ 0.264711] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]490second # [ 0.264714] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]491second # [ 0.264783] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint492second # [ 0.265235] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint493second # [ 0.265367] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]494second # [ 0.265377] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.265396] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]496second # [ 0.265406] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]497second # [ 0.265738] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint498second # [ 0.265875] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]499second # [ 0.265885] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]500second # [ 0.265904] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501test # [ 0.278453] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]502second # [ 0.266227] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint503test # [ 0.278493] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]504second # [ 0.266357] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]505test # [ 0.278521] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506second # [ 0.266366] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]507test # [ 0.278530] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]508second # [ 0.266385] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]509test # [ 0.278887] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint510test # [ 0.279018] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]511test # [ 0.279027] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]512test # [ 0.279046] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513test # [ 0.279380] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint514test # [ 0.279507] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]515test # [ 0.279516] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]516test # [ 0.279535] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]517test # [ 0.279856] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint518test # [ 0.279983] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]519test # [ 0.279992] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]520test # [ 0.280011] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521test # [ 0.280020] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]522test # [ 0.280342] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint523test # [ 0.280470] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]524test # [ 0.280488] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525test # [ 0.280814] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint526test # [ 0.280942] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]527test # [ 0.280960] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528second # [ 0.286798] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint529test # [ 0.281274] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint530second # [ 0.286932] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]531test # [ 0.281397] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]532second # [ 0.286941] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]533test # [ 0.281576] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint534second # [ 0.286961] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535test # [ 0.281703] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]536second # [ 0.286970] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]537test # [ 0.281721] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.287364] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint539test # [ 0.282043] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint540second # [ 0.287496] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]541test # [ 0.282169] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]542second # [ 0.287515] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543test # [ 0.282187] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544second # [ 0.287847] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint545second # [ 0.287977] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]546second # [ 0.287995] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547second # [ 0.288277] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint548second # [ 0.288402] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]549second # [ 0.288577] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint550second # [ 0.288706] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]551second # [ 0.288725] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552test # [ 0.298534] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint553second # [ 0.289055] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint554test # [ 0.298666] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]555second # [ 0.289185] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]556test # [ 0.298684] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557second # [ 0.289203] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558test # [ 0.299022] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint559second # [ 0.289519] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint560test # [ 0.299245] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]561second # [ 0.289647] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]562test # [ 0.299255] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]563second # [ 0.289665] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564test # [ 0.299273] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565second # [ 0.289988] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint566test # [ 0.299601] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint567second # [ 0.290205] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]568test # [ 0.299727] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]569second # [ 0.290215] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]570test # [ 0.299736] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]571second # [ 0.290234] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]572test # [ 0.299754] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]573test # [ 0.300199] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned574test # [ 0.300206] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned575test # [ 0.300209] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned576test # [ 0.300244] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned577test # [ 0.300275] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned578test # [ 0.300307] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned579test # [ 0.300340] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned580test # [ 0.300370] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned581test # [ 0.300401] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned582test # [ 0.300432] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned583test # [ 0.300462] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned584test # [ 0.300492] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned585test # [ 0.300578] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned586test # [ 0.300609] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned587test # [ 0.300621] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned588test # [ 0.300633] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned589test # [ 0.300645] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned590test # [ 0.300658] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned591second # [ 0.310627] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint592test # [ 0.300670] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned593second # [ 0.310762] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]594test # [ 0.300682] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned595second # [ 0.310772] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]596test # [ 0.300695] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned597second # [ 0.310791] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]598test # [ 0.300708] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned599second # [ 0.311290] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned600test # [ 0.300720] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned601second # [ 0.311297] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned602test # [ 0.300733] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned603second # [ 0.311300] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned604test # [ 0.300745] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned605second # [ 0.311335] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned606test # [ 0.300757] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned607test # [ 0.300770] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned608second # [ 0.311366] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned609test # [ 0.300782] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned610second # [ 0.311397] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned611test # [ 0.300794] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned612second # [ 0.311429] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned613test # [ 0.300806] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned614test # [ 0.300818] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned615second # [ 0.311459] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned616test # [ 0.300844] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]617second # [ 0.311490] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned618test # [ 0.300852] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]619second # [ 0.311520] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned620test # [ 0.300854] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]621test # [ 0.301408] pci 0000:00:07.0: enabling device (0000 -> 0002)622second # [ 0.311550] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned623second # [ 0.311580] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned624second # [ 0.311641] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned625second # [ 0.311670] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned626second # [ 0.311682] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned627second # [ 0.311694] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned628second # [ 0.311707] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned629second # [ 0.311720] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned630second # [ 0.311732] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned631second # [ 0.311744] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned632second # [ 0.311757] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned633second # [ 0.311866] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned634second # [ 0.311880] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned635second # [ 0.311892] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned636second # [ 0.311904] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned637second # [ 0.311916] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned638second # [ 0.311928] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned639second # [ 0.311941] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned640second # [ 0.311953] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned641second # [ 0.311965] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned642second # [ 0.311977] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned643second # [ 0.312002] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]644second # [ 0.312010] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]645second # [ 0.312013] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]646second # [ 0.312572] pci 0000:00:07.0: enabling device (0000 -> 0002)647test # [ 0.340026] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)648test # [ 0.341968] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)649second # [ 0.340223] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)650second # [ 0.342149] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)651test # [ 0.352666] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)652test # [ 0.354345] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)653second # [ 0.359595] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)654test # [ 0.364096] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)655second # [ 0.361229] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)656second # [ 0.364093] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)657second # [ 0.366053] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)658test # [ 0.367088] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)659test # [ 0.368562] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)660test # [ 0.370146] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)661second # [ 0.368655] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)662test # [ 0.379650] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)663test # [ 0.381246] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)664second # [ 0.378963] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)665second # [ 0.380608] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)666second # [ 0.382166] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)667test # [ 0.392025] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)668second # [ 0.391694] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)669test # [ 0.396010] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled670test # [ 0.396962] msm_serial: driver initialized671test # [ 0.397029] SuperH (H)SCI(F) driver initialized672test # [ 0.397066] STM32 USART driver initialized673second # [ 0.395670] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled674second # [ 0.399809] msm_serial: driver initialized675second # [ 0.399888] SuperH (H)SCI(F) driver initialized676second # [ 0.399928] STM32 USART driver initialized677test # [ 0.420740] loop: module loaded678test # [ 0.420879] virtio_blk virtio2: 1/0/0 default/read/poll queues679test # [ 0.421518] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)680second # [ 0.424446] loop: module loaded681second # [ 0.424576] virtio_blk virtio2: 1/0/0 default/read/poll queues682second # [ 0.425172] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)683test # [ 0.426837] megasas: 07.734.00.00-rc1684test # [ 0.427293] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685second # [ 0.428245] megasas: 07.734.00.00-rc1686second # [ 0.428721] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]687test # [ 0.439359] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688test # [ 0.439375] Intel/Sharp Extended Query Table at 0x0031689test # [ 0.440999] Using buffer write method690test # [ 0.441047] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]691second # [ 0.439466] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000692second # [ 0.439479] Intel/Sharp Extended Query Table at 0x0031693second # [ 0.441088] Using buffer write method694test # [ 0.446359] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000695test # [ 0.446372] Intel/Sharp Extended Query Table at 0x0031696second # [ 0.441113] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]697second # [ 0.451008] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000698test # [ 0.456761] Using buffer write method699test # [ 0.456776] Concatenating MTD devices:700second # [ 0.451023] Intel/Sharp Extended Query Table at 0x0031701test # [ 0.456778] (0): "0.flash"702second # [ 0.452467] Using buffer write method703test # [ 0.456780] (1): "0.flash"704second # [ 0.452480] Concatenating MTD devices:705test # [ 0.456782] into device "0.flash"706second # [ 0.452481] (0): "0.flash"707second # [ 0.452483] (1): "0.flash"708second # [ 0.452485] into device "0.flash"709second # [ 0.666147] Freeing initrd memory: 26916K710test # [ 0.666809] Freeing initrd memory: 26892K711test # [ 0.674106] tun: Universal TUN/TAP device driver, 1.6712second # [ 0.673619] tun: Universal TUN/TAP device driver, 1.6713test # [ 0.676872] thunder_xcv, ver 1.0714test # [ 0.676888] thunder_bgx, ver 1.0715test # [ 0.676897] nicpf, ver 1.0716test # [ 0.677223] e1000: Intel(R) PRO/1000 Network Driver717test # [ 0.677227] e1000: Copyright (c) 1999-2006 Intel Corporation.718test # [ 0.677240] e1000e: Intel(R) PRO/1000 Network Driver719test # [ 0.677245] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.720test # [ 0.677258] igb: Intel(R) Gigabit Ethernet Network Driver721test # [ 0.677260] igb: Copyright (c) 2007-2014 Intel Corporation.722test # [ 0.677273] igbvf: Intel(R) Gigabit Virtual Function Network Driver723test # [ 0.677275] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.724test # [ 0.677338] sky2: driver version 1.30725second # [ 0.676306] thunder_xcv, ver 1.0726second # [ 0.676323] thunder_bgx, ver 1.0727test # [ 0.678164] usbcore: registered new interface driver usb-storage728second # [ 0.676332] nicpf, ver 1.0729test # [ 0.678236] usbcore: registered new interface driver usbserial_generic730second # [ 0.676675] e1000: Intel(R) PRO/1000 Network Driver731test # [ 0.678242] usbserial: USB Serial support registered for generic732second # [ 0.676680] e1000: Copyright (c) 1999-2006 Intel Corporation.733second # [ 0.676692] e1000e: Intel(R) PRO/1000 Network Driver734second # [ 0.676696] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.735second # [ 0.676709] igb: Intel(R) Gigabit Ethernet Network Driver736second # [ 0.676711] igb: Copyright (c) 2007-2014 Intel Corporation.737second # [ 0.676723] igbvf: Intel(R) Gigabit Virtual Function Network Driver738test # [ 0.685084] ehci-pci 0000:00:07.0: EHCI Host Controller739second # [ 0.676726] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.740test # [ 0.685121] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1741second # [ 0.676795] sky2: driver version 1.30742test # [ 0.685302] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000743second # [ 0.677629] usbcore: registered new interface driver usb-storage744test # [ 0.685529] hv_vmbus: registering driver hyperv_keyboard745second # [ 0.677719] usbcore: registered new interface driver usbserial_generic746test # [ 0.686099] rtc-pl031 9010000.pl031: registered as rtc0747second # [ 0.677726] usbserial: USB Serial support registered for generic748test # [ 0.686128] rtc-pl031 9010000.pl031: setting system clock to 2026-09-29T10:26:53 UTC (1790677613)749second # [ 0.678035] hv_vmbus: registering driver hyperv_keyboard750test # [ 0.686317] i2c_dev: i2c /dev entries driver751second # [ 0.679049] ehci-pci 0000:00:07.0: EHCI Host Controller752second # [ 0.679088] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1753second # [ 0.679426] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000754second # [ 0.687627] rtc-pl031 9010000.pl031: registered as rtc0755test # [ 0.691241] sdhci: Secure Digital Host Controller Interface driver756second # [ 0.687660] rtc-pl031 9010000.pl031: setting system clock to 2026-09-29T10:26:53 UTC (1790677613)757test # [ 0.691246] sdhci: Copyright(c) Pierre Ossman758second # [ 0.687905] i2c_dev: i2c /dev entries driver759test # [ 0.691396] Synopsys Designware Multimedia Card Interface Driver760second # [ 0.689551] sdhci: Secure Digital Host Controller Interface driver761second # [ 0.689555] sdhci: Copyright(c) Pierre Ossman762test # [ 0.691574] sdhci-pltfm: SDHCI platform and OF driver helper763test # [ 0.692444] hid: raw HID events driver (C) Jiri Kosina764second # [ 0.689709] Synopsys Designware Multimedia Card Interface Driver765test # [ 0.692575] usbcore: registered new interface driver usbhid766second # [ 0.689893] sdhci-pltfm: SDHCI platform and OF driver helper767test # [ 0.692577] usbhid: USB HID core driver768test # [ 0.693256] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available769second # [ 0.693755] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00770test # [ 0.694068] drop_monitor: Initializing network drop monitor service771second # [ 0.694159] hub 1-0:1.0: USB hub found772test # [ 0.694188] NET: Registered PF_INET6 protocol family773second # [ 0.694178] hub 1-0:1.0: 6 ports detected774test # [ 0.694475] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00775second # [ 0.695876] hid: raw HID events driver (C) Jiri Kosina776test # [ 0.699685] hub 1-0:1.0: USB hub found777second # [ 0.696014] usbcore: registered new interface driver usbhid778test # [ 0.699705] hub 1-0:1.0: 6 ports detected779second # [ 0.696017] usbhid: USB HID core driver780test # [ 0.700463] Segment Routing with IPv6781test # [ 0.700474] In-situ OAM (IOAM) with IPv6782second # [ 0.696743] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available783test # [ 0.700535] NET: Registered PF_PACKET protocol family784second # [ 0.697559] drop_monitor: Initializing network drop monitor service785test # [ 0.700604] 9pnet: Installing 9P2000 support786second # [ 0.697713] NET: Registered PF_INET6 protocol family787test # [ 0.700641] Key type dns_resolver registered788second # [ 0.698330] Segment Routing with IPv6789second # [ 0.698341] In-situ OAM (IOAM) with IPv6790second # [ 0.698401] NET: Registered PF_PACKET protocol family791second # [ 0.698461] 9pnet: Installing 9P2000 support792second # [ 0.698498] Key type dns_resolver registered793test # [ 0.705895] registered taskstats version 1794test # [ 0.706042] Loading compiled-in X.509 certificates795second # [ 0.705825] registered taskstats version 1796second # [ 0.705962] Loading compiled-in X.509 certificates797test # [ 0.711605] Demotion targets for Node 0: null798test # [ 0.711687] Key type .fscrypt registered799test # [ 0.711690] Key type fscrypt-provisioning registered800test # [ 0.711801] ima: No TPM chip found, activating TPM-bypass!801test # [ 0.711810] ima: Allocated hash algorithm: sha1802test # [ 0.711873] ima: No architecture policies found803test # [ 0.712385] input: gpio-keys as /devices/platform/gpio-keys/input/input0804second # [ 0.711371] Demotion targets for Node 0: null805second # [ 0.711450] Key type .fscrypt registered806second # [ 0.711453] Key type fscrypt-provisioning registered807second # [ 0.711558] ima: No TPM chip found, activating TPM-bypass!808second # [ 0.711568] ima: Allocated hash algorithm: sha1809second # [ 0.711624] ima: No architecture policies found810second # [ 0.712135] input: gpio-keys as /devices/platform/gpio-keys/input/input0811test # [ 0.724716] clk: Disabling unused clocks812test # [ 0.724727] PM: genpd: Disabling unused power domains813second # [ 0.724961] clk: Disabling unused clocks814second # [ 0.724972] PM: genpd: Disabling unused power domains815test # [ 0.727747] Freeing unused kernel memory: 4736K816test # [ 0.727828] Run /init as init process817second # [ 0.728061] Freeing unused kernel memory: 4736K818second # [ 0.728148] Run /init as init process819test # [ 0.748809] systemd[1]: Successfully made /usr/ read-only.820second # [ 0.748909] systemd[1]: Successfully made /usr/ read-only.821second # [ 0.938580] usb 1-1: new high-speed USB device number 2 using ehci-pci822test # [ 0.946494] usb 1-1: new high-speed USB device number 2 using ehci-pci823test # [ 1.082917] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)824test # [ 1.082947] systemd[1]: Detected virtualization qemu.825test # [ 1.082987] systemd[1]: Detected architecture arm64.826test # [ 1.082996] systemd[1]: Running in initrd.827second # [ 1.083043] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)828test # [ 1.083768] systemd[1]: Initializing machine ID from random generator.829second # [ 1.083072] systemd[1]: Detected virtualization qemu.830test # [ 1.084075] systemd[1]: Hostname set to <test>.831second # [ 1.083266] systemd[1]: Detected architecture arm64.832second # [ 1.083279] systemd[1]: Running in initrd.833second # [ 1.084997] systemd[1]: Initializing machine ID from random generator.834second # [ 1.085294] systemd[1]: Hostname set to <second>.835test # [ 1.105899] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1836second # [ 1.105294] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1837test # [ 1.194620] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838second # [ 1.194680] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0839test # [ 1.314459] usb 1-2: new high-speed USB device number 3 using ehci-pci840second # [ 1.314566] usb 1-2: new high-speed USB device number 3 using ehci-pci841test # [ 1.371341] systemd[1]: bpf-restrict-fs: LSM BPF program attached842second # [ 1.371358] systemd[1]: bpf-restrict-fs: LSM BPF program attached843test # [ 1.445116] systemd[1]: Queued start job for default target Initrd Default Target.844second # [ 1.443169] systemd[1]: Queued start job for default target Initrd Default Target.845test # [ 1.452346] systemd[1]: Created slice Slice /system/modprobe.846test # [ 1.452540] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.847test # [ 1.452579] systemd[1]: Expecting device /dev/disk/by-label/nixos...848test # [ 1.452610] systemd[1]: Reached target Path Units.849test # [ 1.452629] systemd[1]: Reached target Slice Units.850test # [ 1.452644] systemd[1]: Reached target Swaps.851test # [ 1.452660] systemd[1]: Reached target Timer Units.852second # [ 1.452744] systemd[1]: Created slice Slice /system/modprobe.853test # [ 1.452818] systemd[1]: Listening on D-Bus System Message Bus Socket.854second # [ 1.452976] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.855test # [ 1.452970] systemd[1]: Listening on Journal Socket (/dev/log).856test # [ 1.453122] systemd[1]: Listening on Journal Sockets.857second # [ 1.453024] systemd[1]: Expecting device /dev/disk/by-label/nixos...858test # [ 1.453219] systemd[1]: Listening on udev Control Socket.859test # [ 1.453279] systemd[1]: Listening on udev Kernel Socket.860second # [ 1.453059] systemd[1]: Reached target Path Units.861test # [ 1.453299] systemd[1]: Reached target Socket Units.862second # [ 1.453084] systemd[1]: Reached target Slice Units.863test # [ 1.462815] systemd[1]: Starting Create List of Static Device Nodes...864second # [ 1.453107] systemd[1]: Reached target Swaps.865second # [ 1.453128] systemd[1]: Reached target Timer Units.866test # [ 1.462893] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs867second # [ 1.453319] systemd[1]: Listening on D-Bus System Message Bus Socket.868second # [ 1.453503] systemd[1]: Listening on Journal Socket (/dev/log).869second # [ 1.453639] systemd[1]: Listening on Journal Sockets.870second # [ 1.453799] systemd[1]: Listening on udev Control Socket.871second # [ 1.453882] systemd[1]: Listening on udev Kernel Socket.872second # [ 1.453908] systemd[1]: Reached target Socket Units.873second # [ 1.469859] systemd[1]: Starting Create List of Static Device Nodes...874test # [ 1.471675] systemd[1]: Mounting Kernel Configuration File System...875test # [ 1.472492] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2876second # [ 1.469950] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs877test # [ 1.472619] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0878second # [ 1.477054] systemd[1]: Mounting Kernel Configuration File System...879test # [ 1.484649] systemd[1]: Starting Journal Service...880second # [ 1.482067] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2881second # [ 1.482245] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0882test # [ 1.495393] systemd[1]: Starting Load Kernel Modules...883test # [ 1.495476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os884second # [ 1.500032] systemd[1]: Starting Journal Service...885second # [ 1.511635] systemd[1]: Starting Load Kernel Modules...886second # [ 1.511726] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os887second # [ 1.522174] systemd[1]: Starting Coldplug All udev Devices...888test # [ 1.530733] systemd[1]: Starting Coldplug All udev Devices...889test # [ 1.537390] systemd-journald[71]: Collecting audit messages is disabled.890test # [ 1.539188] systemd[1]: Finished Create List of Static Device Nodes.891second # [ 1.534708] systemd[1]: Finished Create List of Static Device Nodes.892second # [ 1.535743] systemd[1]: Mounted Kernel Configuration File System.893second # [ 1.537248] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...894test # [ 1.547340] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...895test # [ 1.578671] systemd[1]: Mounted Kernel Configuration File System.896second # [ 1.578997] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.897second # [ 1.580312] systemd[1]: Starting Create Static Device Nodes in /dev...898test # [ 1.591359] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.899test # [ 1.591821] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.900test # [ 1.592656] systemd[1]: Starting Create Static Device Nodes in /dev...901second # [ 1.592894] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.902second # [ 1.596074] systemd-journald[71]: Collecting audit messages is disabled.903test # [ 1.606508] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev904test # [ 1.617123] systemd[1]: Started Journal Service.905second # [ 1.614553] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev906test # [ 1.616316] systemd-modules-load[72]: Inserted module 'dm_mod'907test # [ 1.624181] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0908test # [ 1.624387] [drm] features: -virgl +edid -resource_blob -host_visible909test # [ 1.624389] [drm] features: -context_init910test # [ 1.624847] [drm] number of scanouts: 1911test # [ 1.624856] [drm] number of cap sets: 0912test # [ 1.621749] systemd-modules-load[72]: Module 'virtio_balloon' is built in913second # [ 1.626912] systemd[1]: Finished Create Static Device Nodes in /dev.914second # [ 1.627033] systemd[1]: Reached target Preparation for Local File Systems.915test # [ 1.624206] systemd-modules-load[72]: Module 'virtio_console' is built in916second # [ 1.627053] systemd[1]: Reached target Local File Systems.917second # [ 1.628183] systemd[1]: Starting Rule-based Manager for Device Events and Files...918test # [ 1.635436] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic919test # [ 1.635447] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0920test # [ 1.629418] systemd[1]: Finished Create Static Device Nodes in /dev.921second # [ 1.636266] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0922second # [ 1.636472] [drm] features: -virgl +edid -resource_blob -host_visible923test # [ 1.632262] systemd[1]: Reached target Preparation for Local File Systems.924second # [ 1.636474] [drm] features: -context_init925second # [ 1.636956] [drm] number of scanouts: 1926second # [ 1.636966] [drm] number of cap sets: 0927test # [ 1.636225] systemd[1]: Reached target Local File Systems.928test # [ 1.636862] systemd[1]: Starting Create System Files and Directories...929second # [ 1.643358] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic930second # [ 1.643366] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0931test # [ 1.642703] systemd[1]: Starting Rule-based Manager for Device Events and Files...932test # [ 1.654143] Console: switching to colour frame buffer device 160x50933second # [ 1.663298] Console: switching to colour frame buffer device 160x50934test # [ 1.678211] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device935test # [ 1.672936] systemd-modules-load[72]: Inserted module 'virtio_gpu'936second # [ 1.673628] systemd-modules-load[72]: Inserted module 'dm_mod'937test # [ 1.673601] systemd-modules-load[72]: Module 'virtio_rng' is built in938second # [ 1.674423] systemd-modules-load[72]: Module 'virtio_balloon' is built in939second # [ 1.675206] systemd-modules-load[72]: Module 'virtio_console' is built in940second # [ 1.682675] systemd[1]: Started Journal Service.941test # [ 1.680105] systemd[1]: Finished Load Kernel Modules.942second # [ 1.683995] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device943test # [ 1.680642] systemd[1]: Starting Apply Kernel Variables...944second # [ 1.689507] systemd[1]: Starting Create System Files and Directories...945second # [ 1.692132] systemd-modules-load[72]: Inserted module 'virtio_gpu'946second # [ 1.692790] systemd-modules-load[72]: Module 'virtio_rng' is built in947test # [ 1.693104] systemd[1]: Finished Create System Files and Directories.948second # [ 1.696882] systemd[1]: Finished Load Kernel Modules.949test # [ 1.704765] systemd[1]: Finished Apply Kernel Variables.950second # [ 1.712257] systemd-udevd[77]: Using default interface naming scheme 'v261'.951second # [ 1.713304] systemd[1]: Starting Apply Kernel Variables...952test # [ 1.713045] systemd-udevd[80]: Using default interface naming scheme 'v261'.953second # [ 1.731556] systemd[1]: Finished Apply Kernel Variables.954second # [ 1.736159] systemd[1]: Finished Create System Files and Directories.955test # [ 1.737295] systemd[1]: Started Rule-based Manager for Device Events and Files.956second # [ 1.744264] systemd[1]: Started Rule-based Manager for Device Events and Files.957test # [ 1.784488] systemd[1]: Starting Virtual Console Setup...958second # [ 1.792080] systemd[1]: Starting Virtual Console Setup...959test # [ 1.832347] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.960test # [ 1.834539] systemd[1]: Finished Virtual Console Setup.961second # [ 1.837328] systemd-vconsole-setup[100]: Configuration of first virtual console was skipped, ignoring remaining ones.962second # [ 1.838404] systemd[1]: Finished Virtual Console Setup.963test # [ 2.038419] systemd[1]: Finished Coldplug All udev Devices.964test # [ 2.039033] systemd[1]: Reached target System Initialization.965test # [ 2.039565] systemd[1]: Reached target Basic System.966second # [ 2.057008] systemd[1]: Finished Coldplug All udev Devices.967second # [ 2.057623] systemd[1]: Reached target System Initialization.968second # [ 2.058161] systemd[1]: Reached target Basic System.969test # [ 2.151389] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.970second # [ 2.154012] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.971test # [ 2.172558] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.972second # [ 2.173903] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.973test # [ 2.219428] systemd[1]: Found device /dev/disk/by-label/nixos.974test # [ 2.222977] systemd[1]: Reached target Initrd Root Device.975test # [ 2.226162] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...976second # [ 2.236617] systemd[1]: Found device /dev/disk/by-label/nixos.977second # [ 2.245030] systemd[1]: Reached target Initrd Root Device.978second # [ 2.248651] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...979test # [ 2.269078] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks980second # [ 2.285132] systemd-fsck[109]: nixos: clean, 12/65536 files, 13019/262144 blocks981test # [ 2.290200] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.982second # [ 2.292055] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.983second # [ 2.518643] systemd[1]: Mounting /sysroot...984test # [ 2.522956] systemd[1]: Mounting /sysroot...985second # [ 2.546944] systemd[1]: Mounted /sysroot.986second # [ 2.551479] EXT4-fs (vda): mounted filesystem 12635fd7-15f7-42ad-91aa-f3e70fc0184f r/w with ordered data mode. Quota mode: none.987second # [ 2.549536] systemd[1]: Reached target Initrd Root File System.988test # [ 2.560040] EXT4-fs (vda): mounted filesystem 2af24f21-5934-4bcf-8bcc-e81bcb2eefab r/w with ordered data mode. Quota mode: none.989test # [ 2.554065] systemd[1]: Mounted /sysroot.990second # [ 2.552641] systemd[1]: Starting Mountpoints Configured in the Real Root...991test # [ 2.555476] systemd[1]: Reached target Initrd Root File System.992test # [ 2.559204] systemd[1]: Starting Mountpoints Configured in the Real Root...993second # [ 2.575020] systemd-sysroot-fstab-check[117]: /sysroot should be mounted in the initrd, will request daemon-reload.994test # [ 2.577964] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.995second # [ 2.580950] systemd[1]: Reload requested from client PID 117 ('systemd-sysroot') (unit initrd-parse-etc.service)...996test # [ 2.581144] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...997test # [ 2.583762] systemd[1]: Reloading...998second # [ 2.584421] systemd[1]: Reloading...999test # [ 2.744988] systemd[1]: Reloading finished in 162 ms.1000second # [ 2.745688] systemd[1]: Reloading finished in 161 ms.1001test # [ 2.764092] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1002test # [ 2.766487] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1003test # [ 2.768894] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004test # [ 2.770341] systemd[1]: Finished Mountpoints Configured in the Real Root.1005second # [ 2.771500] systemd-sysroot-fstab-check[117]: Requesting initrd-fs.target/start/replace...1006test # [ 2.771586] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1007second # [ 2.773880] systemd-sysroot-fstab-check[117]: Requesting swap.target/start/replace...1008second # [ 2.777061] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1009second # [ 2.779819] systemd[1]: Finished Mountpoints Configured in the Real Root.1010second # [ 2.781278] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1011second # [ 2.982201] fuse: init (API version 7.45)1012test # [ 3.005726] fuse: init (API version 7.45)1013second # [ 3.009561] virtiofs virtio6: discovered new tag: nix-store1014second # [ 3.010090] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1015test # [ 3.015836] virtiofs virtio6: discovered new tag: nix-store1016test # [ 3.016351] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1017second # [ 3.016057] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1018second # [ 3.020277] (udev-worker)[94]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1019test # [ 3.020279] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/gnadg5slifq2r638xvz4pv6vrsdl9xkj-systemd-261.2/lib/udev/mtd_probe": No such file or directory1020test # [ 3.024220] (udev-worker)[91]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1021second # [ 3.034823] virtiofs virtio7: discovered new tag: shared1022second # [ 3.035366] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1023test # [ 3.041593] virtiofs virtio7: discovered new tag: shared1024test # [ 3.042110] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1025second # [ 3.039364] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.[ 3.045234] virtiofs virtio8: discovered new tag: xchg1026second # [ 3.045751] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1027second # 1028test # [ 3.042973] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1029test # [ 3.052121] virtiofs virtio8: discovered new tag: xchg1030test # [ 3.052652] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1031second # [ 3.045522] systemd[1]: Stopped Virtual Console Setup.1032second # [ 3.046287] systemd[1]: Stopping Virtual Console Setup...1033test # [ 3.045981] systemd[1]: Stopped Virtual Console Setup.1034test # [ 3.048324] systemd[1]: Stopping Virtual Console Setup...1035second # [ 3.048927] systemd[1]: Starting Virtual Console Setup...1036test # [ 3.052329] systemd[1]: Starting Virtual Console Setup...1037second # [ 3.071731] systemd-vconsole-setup[136]: Configuration of first virtual console was skipped, ignoring remaining ones.1038second # [ 3.074228] systemd[1]: Finished Virtual Console Setup.1039test # [ 3.075522] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1040test # [ 3.078073] systemd[1]: Finished Virtual Console Setup.1041second # [ 3.520290] systemd[1]: Mounting /sysroot/nix/.ro-store...1042test # [ 3.526259] systemd[1]: Mounting /sysroot/nix/.ro-store...1043second # [ 3.532302] systemd[1]: Mounting /sysroot/nix/.rw-store...1044second # [ 3.535687] systemd[1]: Mounting /sysroot/run...1045test # [ 3.537799] systemd[1]: Mounting /sysroot/nix/.rw-store...1046test # [ 3.540128] systemd[1]: Mounting /sysroot/run...1047second # [ 3.547961] systemd[1]: Mounting /sysroot/tmp/shared...1048test # [ 3.552298] systemd[1]: Mounting /sysroot/tmp/shared...1049test # [ 3.571102] systemd[1]: Mounting /sysroot/tmp/xchg...1050second # [ 3.572340] systemd[1]: Mounting /sysroot/tmp/xchg...1051test # [ 3.593170] systemd[1]: Mounted /sysroot/nix/.rw-store.1052second # [ 3.597525] systemd[1]: Mounted /sysroot/nix/.rw-store.1053second # [ 3.604385] systemd[1]: Mounted /sysroot/nix/.ro-store.1054test # [ 3.606978] systemd[1]: Starting rw-sysroot-nix-store.service...1055second # [ 3.610255] systemd[1]: Mounted /sysroot/run.1056test # [ 3.610299] systemd[1]: Mounted /sysroot/nix/.ro-store.1057test # [ 3.612344] systemd[1]: Mounted /sysroot/run.1058test # [ 3.612925] systemd[1]: Mounted /sysroot/tmp/shared.1059second # [ 3.615106] systemd[1]: Mounted /sysroot/tmp/shared.1060test # [ 3.618721] systemd[1]: Mounted /sysroot/tmp/xchg.1061second # [ 3.620238] systemd[1]: Starting rw-sysroot-nix-store.service...1062second # [ 3.622409] systemd[1]: Mounted /sysroot/tmp/xchg.1063test # [ 3.626537] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1064test # [ 3.627706] systemd[1]: Finished rw-sysroot-nix-store.service.1065test # [ 3.629694] systemd[1]: Mounting /sysroot/nix/store...1066second # [ 3.638427] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1067second # [ 3.639514] systemd[1]: Finished rw-sysroot-nix-store.service.1068test # [ 3.656852] systemd[1]: Mounted /sysroot/nix/store.1069test # [ 3.658159] systemd[1]: Reached target Initrd File Systems.1070test # [ 3.659484] systemd[1]: Starting Find NixOS closure...1071test # [ 3.664209] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1072test # [ 3.679257] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1073test # [ 3.688958] systemd[1]: Finished Find NixOS closure.1074test # [ 3.689897] systemd[1]: Reached target Initrd Default Target.1075test # [ 3.691696] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1076test # [ 3.705781] systemd[1]: Stopped target Initrd Default Target.1077test # [ 3.706682] systemd[1]: Stopped target Basic System.1078test # [ 3.707363] systemd[1]: Stopped target Initrd Root Device.1079test # [ 3.708215] systemd[1]: Stopped target Path Units.1080test # [ 3.710621] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1081test # [ 3.711454] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1082test # [ 3.712233] systemd[1]: Stopped target Slice Units.1083test # [ 3.713075] systemd[1]: Stopped target Socket Units.1084test # [ 3.714193] systemd[1]: Stopped target System Initialization.1085test # [ 3.715436] systemd[1]: Stopped target Swaps.1086test # [ 3.717629] systemd[1]: Stopped target Timer Units.1087test # [ 3.720639] systemd[1]: dbus.socket: Deactivated successfully.1088test # [ 3.722387] systemd[1]: Closed D-Bus System Message Bus Socket.1089test # [ 3.722952] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1090test # [ 3.729046] systemd[1]: Stopped Find NixOS closure.1091test # [ 3.729521] systemd[1]: Starting rw-sysroot-nix-store.service...1092test # [ 3.730009] systemd[1]: systemd-sysctl.service: Deactivated successfully.1093test # [ 3.730542] systemd[1]: Stopped Apply Kernel Variables.1094test # [ 3.731014] systemd[1]: systemd-modules-load.service: Deactivated successfully.1095test # [ 3.731590] systemd[1]: Stopped Load Kernel Modules.1096test # [ 3.733632] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1097test # [ 3.734360] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1098test # [ 3.735019] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1099test # [ 3.735626] systemd[1]: Stopped Create System Files and Directories.1100test # [ 3.736270] systemd[1]: Stopped target Local File Systems.1101test # [ 3.736749] systemd[1]: Stopped target Preparation for Local File Systems.1102test # [ 3.737314] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1103test # [ 3.738137] systemd[1]: Stopped Coldplug All udev Devices.1104test # [ 3.738624] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1105test # [ 3.739282] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1106test # [ 3.739876] systemd[1]: Stopped Virtual Console Setup.1107test # [ 3.747580] systemd[1]: initrd-cleanup.service: Deactivated successfully.1108test # [ 3.748307] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1109test # [ 3.749183] systemd[1]: systemd-udevd.service: Deactivated successfully.1110test # [ 3.752213] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1111test # [ 3.752894] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1112test # [ 3.753528] systemd[1]: Closed udev Control Socket.1113test # [ 3.755030] systemd[1]: Starting Cleanup udev Database...1114test # [ 3.755589] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1115test # [ 3.757863] systemd[1]: Stopped Create Static Device Nodes in /dev.1116test # [ 3.758495] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1117test # [ 3.759361] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1118test # [ 3.760119] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1119test # [ 3.760755] systemd[1]: Stopped Create List of Static Device Nodes.1120test # [ 3.761331] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1121test # [ 3.761961] systemd[1]: Finished rw-sysroot-nix-store.service.1122test # [ 3.779739] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1123test # [ 3.782025] systemd[1]: Finished Cleanup udev Database.1124test # [ 3.782522] systemd[1]: Reached target Switch Root.1125test # [ 3.783185] systemd[1]: Starting NixOS Activation...1126test # [ 3.855418] initrd-nixos-activation-start[172]: booting system configuration /nix/store/2qarjrgmq4v844l3yxqpspmfvvgifl3v-nixos-system-test-test1127test # [ 3.882859] initrd-nixos-activation-start[172]: running activation script...1128test # [ 4.123202] initrd-nixos-activation-start[195]: setting up /etc...1129test # [ 4.222459] initrd-nixos-activation-start[195]: setting up age secrets...1130test # [ 4.343429] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1131test # [ 4.344307] systemd[1]: Finished NixOS Activation.1132test # [ 4.348107] systemd[1]: Starting Switch Root...1133test # [ 4.364672] systemd[1]: Switching root.1134second # [ 4.519135] systemd[1]: Mounting /sysroot/nix/store...1135test # [ 4.542680] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1136second # [ 4.566047] systemd[1]: Mounted /sysroot/nix/store.1137second # [ 4.568651] systemd[1]: Reached target Initrd File Systems.1138second # [ 4.572177] systemd[1]: Starting Find NixOS closure...1139second # [ 4.580268] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1140second # [ 4.625180] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1141second # [ 4.630781] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1142second # [ 4.642581] systemd[1]: Finished Find NixOS closure.1143second # [ 4.645812] systemd[1]: Reached target Initrd Default Target.1144second # [ 4.647523] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1145second # [ 4.681224] systemd[1]: Stopped target Initrd Default Target.1146second # [ 4.682616] systemd[1]: Stopped target Basic System.1147second # [ 4.684354] systemd[1]: Stopped target Initrd Root Device.1148second # [ 4.688218] systemd[1]: Stopped target Path Units.1149second # [ 4.689085] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1150second # [ 4.692121] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1151second # [ 4.693270] systemd[1]: Stopped target Slice Units.1152second # [ 4.693953] systemd[1]: Stopped target Socket Units.1153second # [ 4.695570] systemd[1]: Stopped target System Initialization.1154second # [ 4.699103] systemd[1]: Stopped target Swaps.1155second # [ 4.699715] systemd[1]: Stopped target Timer Units.1156second # [ 4.700646] systemd[1]: dbus.socket: Deactivated successfully.1157second # [ 4.702685] systemd[1]: Closed D-Bus System Message Bus Socket.1158second # [ 4.704337] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1159second # [ 4.714189] systemd[1]: Stopped Find NixOS closure.1160second # [ 4.714845] systemd[1]: Starting rw-sysroot-nix-store.service...1161second # [ 4.715615] systemd[1]: systemd-sysctl.service: Deactivated successfully.1162second # [ 4.720261] systemd[1]: Stopped Apply Kernel Variables.1163second # [ 4.721020] systemd[1]: systemd-modules-load.service: Deactivated successfully.1164second # [ 4.721797] systemd[1]: Stopped Load Kernel Modules.1165second # [ 4.722355] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1166second # [ 4.723188] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1167second # [ 4.730169] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1168second # [ 4.730970] systemd[1]: Stopped Create System Files and Directories.1169second # [ 4.731670] systemd[1]: Stopped target Local File Systems.1170second # [ 4.735751] systemd[1]: Stopped target Preparation for Local File Systems.1171second # [ 4.740417] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1172second # [ 4.741097] systemd[1]: Stopped Coldplug All udev Devices.1173second # [ 4.741594] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1174second # [ 4.742238] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1175second # [ 4.742902] systemd[1]: Stopped Virtual Console Setup.1176second # [ 4.743373] systemd[1]: initrd-cleanup.service: Deactivated successfully.1177second # [ 4.743963] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1178second # [ 4.745327] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1179second # [ 4.745997] systemd[1]: Finished rw-sysroot-nix-store.service.1180second # [ 4.746535] systemd[1]: systemd-udevd.service: Deactivated successfully.1181second # [ 4.747134] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1182second # [ 4.747778] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1183second # [ 4.748595] systemd[1]: Closed udev Control Socket.1184second # [ 4.749073] systemd[1]: Starting Cleanup udev Database...1185second # [ 4.749570] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1186second # [ 4.750254] systemd[1]: Stopped Create Static Device Nodes in /dev.1187second # [ 4.750812] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1188second # [ 4.751520] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1189second # [ 4.756155] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1190second # [ 4.760114] systemd[1]: Stopped Create List of Static Device Nodes.1191second # [ 4.773755] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1192second # [ 4.775315] systemd[1]: Finished Cleanup udev Database.1193second # [ 4.775889] systemd[1]: Reached target Switch Root.1194second # [ 4.779800] systemd[1]: Starting NixOS Activation...1195second # [ 4.855789] initrd-nixos-activation-start[171]: booting system configuration /nix/store/zcaykmk7yj15a72a7sy16f6hdjrmqrds-nixos-system-second-test1196second # [ 4.883336] initrd-nixos-activation-start[171]: running activation script...1197test # [ 5.079625] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1198test # [ 5.079721] systemd[1]: Detected virtualization qemu.1199test # [ 5.079779] systemd[1]: Detected architecture arm64.1200test # [ 5.079893] systemd[1]: Detected first boot.1201test # [ 5.091837] systemd[1]: Initializing machine ID from random generator.1202second # [ 5.105594] initrd-nixos-activation-start[194]: setting up /etc...1203second # [ 5.206866] initrd-nixos-activation-start[194]: setting up age secrets...1204second # [ 5.296694] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1205second # [ 5.298463] systemd[1]: Finished NixOS Activation.1206second # [ 5.299224] systemd[1]: Starting Switch Root...1207second # [ 5.312900] systemd[1]: Switching root.1208test # [ 5.380004] systemd[1]: bpf-restrict-fs: LSM BPF program attached1209second # [ 5.486830] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1210test # [ 5.513775] systemd[1]: Applying preset policy.1211test # [ 5.685715] systemd[1]: Populated /etc with preset unit settings.1212test # [ 5.913908] systemd[1]: initrd-switch-root.service: Deactivated successfully.1213test # [ 5.914195] systemd[1]: Stopped initrd-switch-root.service.1214test # [ 5.916720] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1215test # [ 5.917654] systemd[1]: Created slice Slice /system/getty.1216test # [ 5.918228] systemd[1]: Created slice User and Session Slice.1217test # [ 5.921048] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1218test # [ 5.921625] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1219test # [ 5.922188] systemd[1]: Expecting device /dev/hvc0...1220test # [ 5.924965] systemd[1]: Expecting device /dev/ttyAMA0...1221test # [ 5.925461] systemd[1]: Reached target Local Encrypted Volumes.1222test # [ 5.926045] systemd[1]: Stopped target initrd-fs.target.1223test # [ 5.928629] systemd[1]: Stopped target initrd-root-fs.target.1224test # [ 5.929096] systemd[1]: Stopped target initrd-switch-root.target.1225test # [ 5.929659] systemd[1]: Reached target Virtual Machines and Containers.1226test # [ 5.930222] systemd[1]: Reached target Path Units.1227test # [ 5.933121] systemd[1]: Reached target Remote File Systems.1228test # [ 5.933588] systemd[1]: Reached target Slice Units.1229test # [ 5.934134] systemd[1]: Reached target Swaps.1230test # [ 5.937963] systemd[1]: Listening on Query the User Interactively for a Password.1231test # [ 5.941235] systemd[1]: Listening on Process Core Dump Socket.1232test # [ 5.943453] systemd[1]: Listening on Credential Encryption/Decryption.1233test # [ 5.945214] systemd[1]: Listening on Factory Reset Management.1234test # [ 5.945760] systemd[1]: Listening on Hostname Service Socket.1235test # [ 5.950343] systemd[1]: Starting Journal Log Access Socket...1236test # [ 5.952774] systemd[1]: Listening on Journal Audit Socket.1237test # [ 5.955438] systemd[1]: Listening on Console Output Muting Service Socket.1238test # [ 5.956118] systemd[1]: Listening on Network Management Metrics Varlink Socket.1239test # [ 5.956820] systemd[1]: Listening on Network Management Varlink Socket.1240test # [ 5.957501] systemd[1]: Listening on Network Management Netlink Socket.1241test # [ 5.958256] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1242test # [ 5.962273] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1243test # [ 5.964319] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1244test # [ 5.969224] systemd[1]: Listening on Disk Repartitioning Service Socket.1245test # [ 5.969763] systemd[1]: Listening on Resolve Monitor Varlink Socket.1246test # [ 5.970307] systemd[1]: Listening on Resolve Service Varlink Socket.1247test # [ 5.972411] systemd[1]: Listening on udev Control Socket.1248test # [ 5.973001] systemd[1]: Listening on udev Varlink Socket.1249test # [ 5.976087] systemd[1]: Mounting Huge Pages File System...1250test # [ 5.977840] systemd[1]: Mounting POSIX Message Queue File System...1251test # [ 5.989905] systemd[1]: Mounting Kernel Debug File System...1252test # [ 5.997339] systemd[1]: Mounting Kernel Trace File System...1253test # [ 6.002517] systemd[1]: Starting Create List of Static Device Nodes...1254test # [ 6.007232] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1255test # [ 6.016759] systemd[1]: Mounting Kernel Configuration File System...1256test # [ 6.018991] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1257test # [ 6.025052] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1258test # [ 6.025681] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1259second # [ 6.015870] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1260second # [ 6.015964] systemd[1]: Detected virtualization qemu.1261second # [ 6.016023] systemd[1]: Detected architecture arm64.1262second # [ 6.016143] systemd[1]: Detected first boot.1263second # [ 6.029300] systemd[1]: Initializing machine ID from random generator.1264test # [ 6.038382] systemd[1]: Mounting FUSE Control File System...1265test # [ 6.041265] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671266test # [ 6.066655] systemd[1]: Starting Journal Service...1267test # [ 6.080430] systemd[1]: Starting Load Kernel Modules...1268test # [ 6.100336] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1269test # [ 6.114403] systemd[1]: Starting Remount Root and Kernel File Systems...1270test # [ 6.116330] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1271test # [ 6.131856] systemd[1]: Starting Coldplug All udev Devices...1272test # [ 6.133921] systemd[1]: Listening on Journal Log Access Socket.1273test # [ 6.134298] systemd[1]: Mounted Huge Pages File System.1274test # [ 6.143020] systemd[1]: Mounted POSIX Message Queue File System.1275test # [ 6.143512] systemd[1]: Mounted Kernel Debug File System.1276test # [ 6.143877] systemd[1]: Mounted Kernel Trace File System.1277test # [ 6.148473] systemd[1]: Finished Create List of Static Device Nodes.1278test # [ 6.151509] systemd[1]: Finished Load Kernel Modules.1279test # [ 6.164690] systemd[1]: Starting Firewall...1280test # [ 6.180162] systemd[1]: Starting Apply Kernel Variables...1281test # [ 6.196994] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1282test # [ 6.202571] systemd-journald[320]: Collecting audit messages is enabled.1283test # [ 6.206478] EXT4-fs (vda): re-mounted 2af24f21-5934-4bcf-8bcc-e81bcb2eefab.1284test # [ 6.222742] systemd[1]: Queued start job for default target Multi-User System.1285test # [ 6.224101] systemd[1]: systemd-journald.service: Deactivated successfully.1286test # [ 6.224907] systemd-modules-load[321]: Module 'atkbd' is built in1287test # [ 6.225580] systemd-modules-load[321]: Module 'ctr' is built in1288test # [ 6.226231] systemd-modules-load[321]: Module 'loop' is built in1289test # [ 6.240985] systemd[1]: Started Journal Service.1290test # [ 6.241879] systemd[1]: Mounted Kernel Configuration File System.1291test # [ 6.242512] systemd[1]: Finished Remount Root and Kernel File Systems.1292test # [ 6.255371] systemd-oomd[322]: No swap; memory pressure usage will be degraded1293test # [ 6.269467] systemd[1]: Listening on Disk Image Download Service Socket.1294test # [ 6.273535] systemd[1]: Starting Flush Journal to Persistent Storage...1295test # [ 6.274214] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1296test # [ 6.284167] systemd[1]: Starting Load/Save OS Random Seed...1297test # [ 6.284767] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1298test # [ 6.285549] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1299test # [ 6.307049] systemd[1]: Mounted FUSE Control File System.1300second # [ 6.315719] systemd[1]: bpf-restrict-fs: LSM BPF program attached1301test # [ 6.355352] systemd-journald[320]: Received client request to flush runtime journal.1302test # [ 6.412432] systemd[1]: Finished Apply Kernel Variables.1303test # [ 6.413121] systemd[1]: Starting Network Name Resolution...1304test # [ 6.420243] systemd[1]: Finished Load/Save OS Random Seed.1305test # [ 6.420938] systemd[1]: Reached target First Boot Complete.1306test # [ 6.421457] systemd[1]: Finished Flush Journal to Persistent Storage.1307second # [ 6.451965] systemd[1]: Applying preset policy.1308test # [ 6.490237] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1309test # [ 6.496591] systemd[1]: Starting Create Static Device Nodes in /dev...1310test # [ 6.525042] systemd-resolved[345]: Positive Trust Anchors:1311test # [ 6.525640] systemd-resolved[345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1312test # [ 6.526552] systemd-resolved[345]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161313test # [ 6.527423] systemd-resolved[345]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1314second # [ 6.636446] systemd[1]: Populated /etc with preset unit settings.1315test # [ 6.716772] systemd-resolved[345]: Using system hostname 'test'.1316test # [ 6.720211] systemd[1]: Started Network Name Resolution.1317test # [ 6.755940] systemd[1]: Finished Coldplug All udev Devices.1318test # [ 6.758196] systemd[1]: Finished Create Static Device Nodes in /dev.1319test # [ 6.758828] systemd[1]: Reached target Preparation for Local File Systems.1320test # [ 6.760279] systemd[1]: Starting Rule-based Manager for Device Events and Files...1321test # [ 6.811495] systemd-udevd[363]: Using default interface naming scheme 'v261'.1322second # [ 6.861820] systemd[1]: initrd-switch-root.service: Deactivated successfully.1323second # [ 6.862125] systemd[1]: Stopped initrd-switch-root.service.1324second # [ 6.864637] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1325second # [ 6.865703] systemd[1]: Created slice Slice /system/getty.1326second # [ 6.868472] systemd[1]: Created slice User and Session Slice.1327second # [ 6.869067] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1328second # [ 6.869662] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1329second # [ 6.870242] systemd[1]: Expecting device /dev/hvc0...1330second # [ 6.873656] systemd[1]: Expecting device /dev/ttyAMA0...1331second # [ 6.874145] systemd[1]: Reached target Local Encrypted Volumes.1332second # [ 6.876209] systemd[1]: Stopped target initrd-fs.target.1333second # [ 6.876713] systemd[1]: Stopped target initrd-root-fs.target.1334second # [ 6.877338] systemd[1]: Stopped target initrd-switch-root.target.1335second # [ 6.877988] systemd[1]: Reached target Virtual Machines and Containers.1336second # [ 6.881154] systemd[1]: Reached target Path Units.1337second # [ 6.881642] systemd[1]: Reached target Remote File Systems.1338second # [ 6.882202] systemd[1]: Reached target Slice Units.1339second # [ 6.884582] systemd[1]: Reached target Swaps.1340test # [ 6.883439] systemd[1]: Started Rule-based Manager for Device Events and Files.1341second # [ 6.887128] systemd[1]: Listening on Query the User Interactively for a Password.1342second # [ 6.889307] systemd[1]: Listening on Process Core Dump Socket.1343second # [ 6.892068] systemd[1]: Listening on Credential Encryption/Decryption.1344second # [ 6.893733] systemd[1]: Listening on Factory Reset Management.1345second # [ 6.894263] systemd[1]: Listening on Hostname Service Socket.1346second # [ 6.898303] systemd[1]: Starting Journal Log Access Socket...1347second # [ 6.900781] systemd[1]: Listening on Journal Audit Socket.1348second # [ 6.903600] systemd[1]: Listening on Console Output Muting Service Socket.1349second # [ 6.904266] systemd[1]: Listening on Network Management Metrics Varlink Socket.1350second # [ 6.904970] systemd[1]: Listening on Network Management Varlink Socket.1351second # [ 6.905609] systemd[1]: Listening on Network Management Netlink Socket.1352second # [ 6.906310] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1353second # [ 6.910450] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1354second # [ 6.912736] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1355test # [ 6.908709] systemd[1]: Mounting /run/wrappers...1356second # [ 6.917556] systemd[1]: Listening on Disk Repartitioning Service Socket.1357second # [ 6.918095] systemd[1]: Listening on Resolve Monitor Varlink Socket.1358second # [ 6.920255] systemd[1]: Listening on Resolve Service Varlink Socket.1359second # [ 6.920767] systemd[1]: Listening on udev Control Socket.1360second # [ 6.921337] systemd[1]: Listening on udev Varlink Socket.1361second # [ 6.924819] systemd[1]: Mounting Huge Pages File System...1362second # [ 6.927273] systemd[1]: Mounting POSIX Message Queue File System...1363second # [ 6.934916] systemd[1]: Mounting Kernel Debug File System...1364second # [ 6.945020] systemd[1]: Mounting Kernel Trace File System...1365second # [ 6.956057] systemd[1]: Starting Create List of Static Device Nodes...1366second # [ 6.958363] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1367second # [ 6.972154] systemd[1]: Mounting Kernel Configuration File System...1368second # [ 6.975604] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1369second # [ 6.975974] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1370second # [ 6.976240] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1371second # [ 7.001690] systemd[1]: Mounting FUSE Control File System...1372second # [ 7.002084] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671373test # [ 7.016199] systemd[1]: Mounted /run/wrappers.1374test # [ 7.017616] systemd[1]: Reached target Local File Systems.1375second # [ 7.022823] systemd[1]: Starting Journal Service...1376test # [ 7.022026] systemd[1]: Listening on Boot Loader Control Service Socket.1377test # [ 7.024188] systemd[1]: Starting register-nix-paths.service...1378test # [ 7.028410] systemd[1]: Starting Create SUID/SGID Wrappers...1379test # [ 7.029024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1380second # [ 7.034609] systemd[1]: Starting Load Kernel Modules...1381test # [ 7.032818] systemd[1]: Starting Save Transient machine-id to Disk...1382second # [ 7.041301] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1383test # [ 7.041539] systemd[1]: Starting Create System Files and Directories...1384test # [ 7.047331] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1385second # [ 7.059973] systemd[1]: Starting Remount Root and Kernel File Systems...1386second # [ 7.060357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1387second # [ 7.072413] systemd[1]: Starting Coldplug All udev Devices...1388second # [ 7.074383] systemd[1]: Listening on Journal Log Access Socket.1389second # [ 7.087428] systemd[1]: Mounted Huge Pages File System.1390second # [ 7.087932] systemd[1]: Mounted POSIX Message Queue File System.1391second # [ 7.088296] systemd[1]: Mounted Kernel Debug File System.1392second # [ 7.088635] systemd[1]: Mounted Kernel Trace File System.1393test # [ 7.089268] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1394second # [ 7.098202] systemd[1]: Finished Create List of Static Device Nodes.1395second # [ 7.102914] systemd[1]: Mounted Kernel Configuration File System.1396second # [ 7.103438] systemd[1]: Mounted FUSE Control File System.1397second # [ 7.116636] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1398second # [ 7.142965] systemd-journald[298]: Collecting audit messages is enabled.1399second # [ 7.151853] systemd[1]: Finished Load Kernel Modules.1400second # [ 7.149721] systemd[1]: Queued start job for default target Multi-User System.1401second # [ 7.154476] systemd[1]: systemd-journald.service: Deactivated successfully.1402second # [ 7.155321] systemd-modules-load[299]: Module 'atkbd' is built in1403second # [ 7.158675] systemd[1]: Starting Firewall...1404second # [ 7.163896] systemd[1]: Starting Apply Kernel Variables...1405second # [ 7.164371] systemd[1]: Started Journal Service.1406second # [ 7.155993] systemd-modules-load[299]: Module 'ctr' is built in1407second # [ 7.163866] systemd-modules-load[299]: Module 'loop' is built in1408test # [ 7.164160] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1409test # [ 7.171453] systemd[1]: Finished Save Transient machine-id to Disk.1410second # [ 7.177430] systemd-oomd[300]: No swap; memory pressure usage will be degraded1411second # [ 7.191476] EXT4-fs (vda): re-mounted 12635fd7-15f7-42ad-91aa-f3e70fc0184f.1412second # [ 7.198232] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1413second # [ 7.209964] systemd[1]: Finished Remount Root and Kernel File Systems.1414second # [ 7.212701] systemd[1]: Listening on Disk Image Download Service Socket.1415second # [ 7.219278] systemd[1]: Starting Flush Journal to Persistent Storage...1416second # [ 7.219924] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1417second # [ 7.228779] systemd[1]: Starting Load/Save OS Random Seed...1418test # [ 7.228311] systemd[1]: Finished Create System Files and Directories.1419second # [ 7.229317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1420test # [ 7.234384] systemd[1]: Starting Rebuild Journal Catalog...1421test # [ 7.243422] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1422second # [ 7.299087] systemd-journald[298]: Received client request to flush runtime journal.1423test # [ 7.309992] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1424second # [ 7.349091] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1425second # [ 7.349924] systemd[1]: Starting Create Static Device Nodes in /dev...1426second # [ 7.350659] systemd[1]: Finished Load/Save OS Random Seed.1427second # [ 7.351176] systemd[1]: Reached target First Boot Complete.1428second # [ 7.360703] systemd[1]: Finished Apply Kernel Variables.1429second # [ 7.363032] systemd[1]: Starting Network Name Resolution...1430second # [ 7.363606] systemd[1]: Finished Flush Journal to Persistent Storage.1431test # [ 7.391670] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1432test # [ 7.425524] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1433test # [ 7.429826] systemd[1]: Finished Rebuild Journal Catalog.1434test # [ 7.434821] systemd[1]: Starting Update is Completed...1435test # [ 7.508417] systemd[1]: Finished Update is Completed.1436second # [ 7.540849] systemd-resolved[328]: Positive Trust Anchors:1437second # [ 7.541451] systemd-resolved[328]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1438second # [ 7.542326] systemd-resolved[328]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161439second # [ 7.543246] systemd-resolved[328]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1440second # [ 7.613939] systemd[1]: Finished Create Static Device Nodes in /dev.1441second # [ 7.614608] systemd[1]: Reached target Preparation for Local File Systems.1442second # [ 7.618070] systemd[1]: Starting Rule-based Manager for Device Events and Files...1443second # [ 7.687792] systemd[1]: Finished Coldplug All udev Devices.1444second # [ 7.708066] systemd-resolved[328]: Using system hostname 'second'.1445second # [ 7.711038] systemd[1]: Started Network Name Resolution.1446test # [ 7.712668] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line.1447test # [ 7.717145] (udev-worker)[395]: Network interface NamePolicy= disabled on kernel command line.1448second # [ 7.731443] systemd-udevd[335]: Using default interface naming scheme 'v261'.1449second # [ 7.804502] systemd[1]: Started Rule-based Manager for Device Events and Files.1450second # [ 7.860583] systemd[1]: Mounting /run/wrappers...1451test # [ 7.913850] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1452test # [ 7.914599] systemd[1]: Finished Create SUID/SGID Wrappers.1453second # [ 7.931237] systemd[1]: Mounted /run/wrappers.1454second # [ 7.932250] systemd[1]: Reached target Local File Systems.1455second # [ 7.936260] systemd[1]: Listening on Boot Loader Control Service Socket.1456test # [ 7.940130] systemd[1]: Finished register-nix-paths.service.1457second # [ 7.941012] systemd[1]: Starting register-nix-paths.service...1458test # [ 7.940752] systemd[1]: Reached target System Initialization.1459test # [ 7.941302] systemd[1]: Started Discard unused filesystem blocks once a week.1460test # [ 7.941942] systemd[1]: Started Daily Cleanup of Temporary Directories.1461test # [ 7.942560] systemd[1]: Reached target Timer Units.1462second # [ 7.945619] systemd[1]: Starting Create SUID/SGID Wrappers...1463second # [ 7.946400] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1464test # [ 7.947856] systemd[1]: Listening on D-Bus System Message Bus Socket.1465second # [ 7.950374] systemd[1]: Starting Save Transient machine-id to Disk...1466test # [ 7.951281] systemd[1]: Listening on Nix Daemon Socket.1467test # [ 7.955804] systemd[1]: Listening on Authorization Manager Agent Helper.1468test # [ 7.957649] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1469test # [ 7.958441] systemd[1]: Reached target Socket Units.1470second # [ 7.960088] systemd[1]: Starting Create System Files and Directories...1471second # [ 7.960737] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1472test # [ 7.961403] systemd[1]: Starting D-Bus System Message Bus...1473test # [ 7.993888] mousedev: PS/2 mouse device common for all mice1474second # [ 8.001790] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1475second # [ 8.091636] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1476test # [ 8.097044] dbus-broker-launch[517]: Looking up NSS user entry for 'systemd-timesync'...1477second # [ 8.102370] systemd[1]: Finished Save Transient machine-id to Disk.1478test # [ 8.103385] dbus-broker-launch[517]: NSS returned no entry for 'systemd-timesync'1479test # [ 8.106175] dbus-broker-launch[517]: Invalid user-name in /nix/store/nqlq2n3zxbzbrsy4c4zkc32fs9qsli5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1480second # [ 8.140760] systemd[1]: Finished Create System Files and Directories.1481test # [ 8.139858] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1482test # [ 8.145003] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1483second # [ 8.150870] systemd[1]: Starting Rebuild Journal Catalog...1484test # [ 8.149551] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nb29m4n6sgq50sylz05ljdr1lnihyh37-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1485second # [ 8.159020] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1486test # [ 8.157879] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1487test # [ 8.159624] systemd[1]: Started D-Bus System Message Bus.1488test # [ 8.166749] systemd[1]: Reached target Basic System.1489test # [ 8.167258] systemd[1]: Starting Network Manager Script Dispatcher Service...1490test # [ 8.167883] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1491test # [ 8.176169] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1492test # [ 8.207611] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1493second # [ 8.222894] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1494test # [ 8.224445] dbus-broker-launch[517]: Ready1495test # [ 8.229867] systemd[1]: Started backdoor.service.1496test # [ 8.241662] systemd[1]: Starting Import lastlog data into lastlog2 database...1497test # [ 8.273610] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1498second # [ 8.293900] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1499test # [ 8.302066] systemd[1]: Starting Post-Boot Actions...1500second # [ 8.330907] systemd[1]: Finished Rebuild Journal Catalog.1501test # [ 8.333465] 1hd0ky5xzfyzxxy55l8ycm4nknsipgaa-wifi-secrets[535]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1502second # [ 8.334933] systemd[1]: Starting Update is Completed...1503second # [ 8.335508] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1504test # [ 8.347292] pg2l5f6p367x80lzljwyzc6ry5wwfygh-wifi-secrets[537]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one1505test # [ 8.357961] systemd[1]: Started Reset console on configuration changes.1506test # [ 8.364688] systemd[1]: Finished Firewall.1507test # [ 8.365192] systemd[1]: Reached target Preparation for Network.1508test # [ 8.365848] systemd[1]: Listening on Network Management Resolve Hook Socket.1509test # [ 8.411074] ffziadw2s3h3i1y9l1d32yxiwmhp3y27-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open1510second # [ 8.415379] systemd[1]: Finished Update is Completed.1511test # [ 8.420489] systemd[1]: Starting Network Manager...1512test # [ 8.451621] systemd[1]: Starting Network Management...1513test # [ 8.454588] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1514test # [ 8.460666] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1515test # [ 8.461347] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1516test # [ 8.462135] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1517test # [ 8.462761] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1518test # [ 8.463542] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1519test # connecting to host...1520test # [ 8.503031] systemd[1]: Started Name Service Cache Daemon (nsncd).1521test # [ 8.503912] nsncd[544]: Sep 29 10:27:01.326 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1522test: Guest shell says: b'Spawning backdoor root shell...\n'1523test # [ 8.537665] systemd[1]: Finished Post-Boot Actions.1524test: connected to guest root shell1525test: (connecting took 8.83 seconds)1526test: (finished: waiting for the VM to finish booting, in 8.83 seconds)1527test # [ 8.567345] systemd[1]: Started Network Manager Script Dispatcher Service.1528test # [ 8.569226] systemd[1]: Reached target Host and Network Name Lookups.1529test # [ 8.576390] systemd[1]: Reached target User and Group Name Lookups.1530test # [ 8.590719] systemd[1]: Starting User Login Management...1531test # [ 8.599768] systemd[1]: Finished Import lastlog data into lastlog2 database.1532second # [ 8.602826] (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line.1533second # [ 8.608365] (udev-worker)[361]: Network interface NamePolicy= disabled on kernel command line.1534test # [ 8.753751] systemd-logind[595]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1535test # [ 8.762739] systemd-logind[595]: Watching system buttons on /dev/input/event0 (gpio-keys)1536test # [ 8.763499] systemd-logind[595]: New seat seat0.1537test # [ 8.769168] systemd[1]: Started User Login Management.1538test # [ 8.772410] systemd[1]: Starting linger-users.service...1539second # [ 8.791968] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1540second # [ 8.795695] systemd[1]: Finished Create SUID/SGID Wrappers.1541second # [ 8.841815] systemd[1]: Finished register-nix-paths.service.1542second # [ 8.842853] systemd[1]: Reached target System Initialization.1543second # [ 8.845582] systemd[1]: Started Discard unused filesystem blocks once a week.1544second # [ 8.846257] systemd[1]: Started Daily Cleanup of Temporary Directories.1545second # [ 8.846857] systemd[1]: Reached target Timer Units.1546second # [ 8.847338] systemd[1]: Listening on D-Bus System Message Bus Socket.1547second # [ 8.847931] systemd[1]: Listening on Nix Daemon Socket.1548test # [ 8.865970] cfg80211: Loading compiled-in X.509 certificates for regulatory database1549second # [ 8.858505] systemd[1]: Listening on Authorization Manager Agent Helper.1550second # [ 8.860249] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1551second # [ 8.861067] systemd[1]: Reached target Socket Units.1552second # [ 8.864087] systemd[1]: Starting D-Bus System Message Bus...1553test # [ 8.864970] systemd[1]: Starting Hostname Service...1554test # [ 8.898210] systemd[1]: linger-users.service: Deactivated successfully.1555second # [ 8.905037] mousedev: PS/2 mouse device common for all mice1556test # [ 8.901068] systemd[1]: Finished linger-users.service.1557test # [ 8.933129] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1558test # [ 8.933635] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1559test # [ 8.990669] systemd[1]: Started Hostname Service.1560second # [ 8.992754] dbus-broker-launch[491]: Looking up NSS user entry for 'systemd-timesync'...1561second # [ 8.999047] dbus-broker-launch[491]: NSS returned no entry for 'systemd-timesync'1562test # [ 8.998468] systemd-networkd[566]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1563second # [ 9.001730] dbus-broker-launch[491]: Invalid user-name in /nix/store/nqlq2n3zxbzbrsy4c4zkc32fs9qsli5c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1564test # [ 9.007274] systemd-networkd[566]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1565test # [ 9.016159] systemd-networkd[566]: lo: Link UP1566test # [ 9.016618] systemd-networkd[566]: lo: Gained carrier1567test # [ 9.017084] systemd[1]: Started Network Management.1568test # [ 9.025165] systemd-networkd[566]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1569test # [ 9.032713] systemd-networkd[566]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1570test # [ 9.033480] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1571second # [ 9.033044] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1572test # [ 9.034134] systemd-networkd[566]: eth1: Link UP1573test # [ 9.034545] systemd-networkd[566]: eth1: Gained carrier1574test # [ 9.035010] systemd-networkd[566]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1575second # [ 9.037655] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/bh7karc6ky6iyrzicn2adjl19aczwgcf-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1576second # [ 9.042458] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/nb29m4n6sgq50sylz05ljdr1lnihyh37-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1577second # [ 9.046027] dbus-broker-launch[491]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/dr9dflpjm2jq4gyhvqw4l0p4v85f4ksw-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1578second # [ 9.055370] systemd[1]: Started D-Bus System Message Bus.1579second # [ 9.055939] systemd[1]: Reached target Basic System.1580second # [ 9.059417] systemd[1]: Starting Network Manager Script Dispatcher Service...1581second # [ 9.061733] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1582second # [ 9.065274] systemd[1]: Started backdoor.service.1583second # [ 9.083066] systemd[1]: Starting Import lastlog data into lastlog2 database...1584second # [ 9.096301] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1585test # [ 9.101839] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1586test # [ 9.105805] systemd-networkd[566]: eth0: Link UP1587test # [ 9.106286] systemd-networkd[566]: eth0: Gained carrier1588second # [ 9.119316] dbus-broker-launch[491]: Ready1589test # [ 9.124714] systemd[1]: Started Network Manager.1590second # [ 9.126868] systemd[1]: Starting Post-Boot Actions...1591test # [ 9.127677] systemd[1]: Reached target Network.1592test # [ 9.132223] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1593second # [ 9.146539] systemd[1]: Started Reset console on configuration changes.1594test # [ 9.147205] systemd[1]: Starting Permit User Sessions...1595second # [ 9.237636] 1hd0ky5xzfyzxxy55l8ycm4nknsipgaa-wifi-secrets[509]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1596test # [ 9.258664] systemd[1]: Finished Permit User Sessions.1597second # connecting to host...1598second # [ 9.276610] systemd[1]: Finished Post-Boot Actions.1599test # [ 9.276750] systemd[1]: Started Getty on tty1.1600test # [ 9.277228] systemd[1]: Reached target Login Prompts.1601second # [ 9.287120] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1602second # [ 9.292295] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1603second # [ 9.309348] systemd[1]: Started Name Service Cache Daemon (nsncd).1604second # [ 9.309961] systemd[1]: Reached target Host and Network Name Lookups.1605second # [ 9.310513] systemd[1]: Reached target User and Group Name Lookups.1606second # [ 9.317547] nsncd[513]: Sep 29 10:27:02.131 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1607second # [ 9.328764] systemd[1]: Starting User Login Management...1608test # [ 9.340409] systemd-resolved[345]: eth0: Bus client set default route setting: yes1609test # [ 9.344497] systemd-resolved[345]: eth0: Bus client set DNS server list to: 10.0.2.31610second # [ 9.376948] systemd[1]: Started Network Manager Script Dispatcher Service.1611second # [ 9.381428] systemd[1]: Finished Firewall.1612second # [ 9.384155] systemd[1]: Reached target Preparation for Network.1613second # [ 9.384785] systemd[1]: Listening on Network Management Resolve Hook Socket.1614test # [ 9.386708] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1615second # [ 9.390681] systemd[1]: Starting Network Manager...1616test # [ 9.391868] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1617second # [ 9.393700] systemd[1]: Starting Network Management...1618second # [ 9.429752] systemd[1]: Finished Import lastlog data into lastlog2 database.1619second # [ 9.483949] systemd-logind[549]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1620second # [ 9.488159] systemd-logind[549]: Watching system buttons on /dev/input/event0 (gpio-keys)1621second # [ 9.492622] systemd-logind[549]: New seat seat0.1622second # [ 9.493081] systemd[1]: Started User Login Management.1623second # [ 9.502114] systemd[1]: Starting linger-users.service...1624test # [ 9.539562] systemd[1]: Reached target Multi-User System.1625test # [ 9.542072] systemd[1]: Startup finished in 722ms (kernel) + 3.881s (initrd) + 4.937s (userspace) = 9.541s.1626second # [ 9.562653] systemd[1]: linger-users.service: Deactivated successfully.1627second # [ 9.567946] systemd[1]: Finished linger-users.service.1628test # [ 9.604538] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31629second # [ 9.646219] cfg80211: Loading compiled-in X.509 certificates for regulatory database1630second # [ 9.722068] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1631second # [ 9.723387] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1632second # [ 9.732266] systemd[1]: Starting Hostname Service...1633second # [ 9.762807] systemd-networkd[564]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1634second # [ 9.769034] systemd-networkd[564]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1635second # [ 9.778632] systemd-networkd[564]: lo: Link UP1636second # [ 9.779121] systemd-networkd[564]: lo: Gained carrier1637second # [ 9.784088] systemd[1]: Started Network Management.1638second # [ 9.791777] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1639second # [ 9.802488] systemd-networkd[564]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1640second # [ 9.803256] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1641second # [ 9.803898] systemd-networkd[564]: eth1: Link UP1642second # [ 9.810520] systemd-networkd[564]: eth1: Gained carrier1643second # [ 9.811019] systemd-networkd[564]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1644test: (finished: waiting for unit NetworkManager.service, in 10.12 seconds)1645test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1646test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.02 seconds)1647test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open1648second # [ 9.873562] systemd[1]: Started Hostname Service.1649second # [ 9.890485] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1650test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.05 seconds)1651test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1652second # [ 9.941687] systemd-networkd[564]: eth0: Link UP1653second # [ 9.942197] systemd-networkd[564]: eth0: Gained carrier1654test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1655(finished: run the VM test script, in 10.70 seconds)1656second # [ 9.961146] systemd[1]: Started Network Manager.1657second # [ 9.963314] systemd[1]: Reached target Network.1658second # [ 9.970062] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1659second # [ 9.975777] systemd[1]: Starting Permit User Sessions...1660test script finished in 10.76s1661cleanup1662kill QemuMachine (pid 47)1663second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1664test # [ 10.027630] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1665second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down1666second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down1667second # [2026-09-29T10:27:02Z INFO virtiofsd] Client disconnected, shutting down1668test # [ 10.034285] systemd[1]: Starting Virtual Console Setup...1669test # [ 10.060068] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1670test # [ 10.063438] systemd[1]: Stopped Virtual Console Setup.1671test # [ 10.068661] systemd[1]: Starting Virtual Console Setup...1672test # [ 10.085382] systemd-logind[595]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1673kill QemuMachine (pid 48)1674test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/3fl7bdkdxk2k4nssy1d1161isbzn6bsr-python3-3.14.7/bin/python3.14)1675test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down1676test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down1677test # [2026-09-29T10:27:03Z INFO virtiofsd] Client disconnected, shutting down1678(finished: cleanup, in 0.26 seconds)