vm-test-run-wifi
checks.aarch64-linux.wifi
· build #789
· raw
1attached to the build already running on this worker2build01: building 0w1c1kyx2vx7231d2nl5j2l33p9z87mi-vm-test-run-wifi.drv3Machine state will be reset. To keep it, pass --keep-machine-state4start all VLans5(finished: start all VLans, in 0.00 seconds)6Test will time out and terminate in 3600 seconds7run the VM test script8additionally exposed symbols:9 second, test,10 vlan1,11 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_ssh12start all VMs13second: starting vm14test: starting vm15test # Disk image does not exist, creating the virtualisation disk image...16test # Formatting '/build/vm-state-test/tmp.oLN09wPp9x', 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: QEMU running (pid 15)21test # Filesystem UUID: bba0b3bf-2dfc-43d7-809a-06a144864fc122test # Superblock backups stored on blocks:23test # 32768, 98304, 163840, 22937624test # 25test # Allocating group tables: 0/8 done26test # Writing inode tables: 0/8 done27test # Creating journal (8192 blocks): done28test # Writing superblocks and filesystem accounting information: 0/8 done29test # 30test # Virtualisation disk image created.31second # Disk image does not exist, creating the virtualisation disk image...32second # Formatting '/build/vm-state-second/tmp.qlciM2S4SW', fmt=raw size=107374182433second # mke2fs 1.47.4 (6-Mar-2025)34second # Discarding device blocks: 0/262144 done35second # Creating filesystem with 262144 4k blocks and 65536 inodes36second # Filesystem UUID: b50d5f76-e1ab-4550-8c09-0f75b7130bb037second # Superblock backups stored on blocks:38second # 32768, 98304, 163840, 22937639second # 40second # Allocating group tables: 0/8 done41second # Writing inode tables: 0/8 done42second # Creating journal (8192 blocks): done43second # Writing superblocks and filesystem accounting information: 0/8 done44second # 45second # Virtualisation disk image created.46second: QEMU running (pid 14)47(finished: start all VMs, in 0.47 seconds)48test: waiting for unit NetworkManager.service49test: waiting for the VM to finish booting50test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]51second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]52test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202653test # [ 0.000000] KASLR enabled54test # [ 0.000000] random: crng init done55test # [ 0.000000] Machine model: linux,dummy-virt56test # [ 0.000000] efi: UEFI not found.57test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT58test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]59test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]60second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202661test # [ 0.000000] Zone ranges:62second # [ 0.000000] KASLR enabled63second # [ 0.000000] random: crng init done64test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]65test # [ 0.000000] DMA32 empty66second # [ 0.000000] Machine model: linux,dummy-virt67test # [ 0.000000] Normal empty68second # [ 0.000000] efi: UEFI not found.69test # [ 0.000000] Device empty70test # [ 0.000000] Movable zone start for each node71second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT72test # [ 0.000000] Early memory node ranges73second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]74test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]75second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]76second # [ 0.000000] Zone ranges:77test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]78second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]79test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000080second # [ 0.000000] DMA32 empty81test # [ 0.000000] psci: probing for conduit method from DT.82second # [ 0.000000] Normal empty83second # [ 0.000000] Device empty84test # [ 0.000000] psci: PSCIv1.3 detected in firmware.85second # [ 0.000000] Movable zone start for each node86test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs87second # [ 0.000000] Early memory node ranges88test # [ 0.000000] psci: Trusted OS migration not required89second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]90test # [ 0.000000] psci: SMC Calling Convention v1.191second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]92test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)93second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000094second # [ 0.000000] psci: probing for conduit method from DT.95test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129696test # [ 0.000000] Detected PIPT I-cache on CPU097second # [ 0.000000] psci: PSCIv1.3 detected in firmware.98second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs99test # [ 0.000000] CPU features: detected: GICv3 CPU interface100test # [ 0.000000] CPU features: detected: Spectre-v4101second # [ 0.000000] psci: Trusted OS migration not required102second # [ 0.000000] psci: SMC Calling Convention v1.1103test # [ 0.000000] CPU features: detected: Spectre-BHB104test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR105second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)106test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)107second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296108second # [ 0.000000] Detected PIPT I-cache on CPU0109test # [ 0.000000] CPU features: detected: ARM erratum 1418040110second # [ 0.000000] CPU features: detected: GICv3 CPU interface111test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion112second # [ 0.000000] CPU features: detected: Spectre-v4113test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing114second # [ 0.000000] CPU features: detected: Spectre-BHB115test # [ 0.000000] alternatives: applying boot alternatives116second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR117second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)118second # [ 0.000000] CPU features: detected: ARM erratum 1418040119second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion120second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing121test # [ 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/4xlf8h0kwvz1rdm88ndanzdwijzj0mfy-nixos-system-test-test/init regInfo=/nix/store/98ybqjvfxslby0fp2r30rvyn8lbi6yya-closure-info/registration console=ttyAMA0,115200n8 console=tty0122second # [ 0.000000] alternatives: applying boot alternatives123test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/98ybqjvfxslby0fp2r30rvyn8lbi6yya-closure-info/registration", will be passed to user space.124test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes125test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)126second # [ 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/f8z2991bmvsmmq1q37nk6ir6pmwgm714-nixos-system-second-test/init regInfo=/nix/store/3rl5dpjfmn57hm5kzfkg5jb9ch52r5bg-closure-info/registration console=ttyAMA0,115200n8 console=tty0127test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)128test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB129test # [ 0.000000] software IO TLB: area num 1.130second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/3rl5dpjfmn57hm5kzfkg5jb9ch52r5bg-closure-info/registration", will be passed to user space.131second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes132test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)133second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)134test # [ 0.000000] Fallback order for Node 0: 0135second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144137second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB138test # [ 0.000000] Policy zone: DMA139second # [ 0.000000] software IO TLB: area num 1.140test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off141second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)142test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1143second # [ 0.000000] Fallback order for Node 0: 0144test # [ 0.000000] allocated 2097152 bytes of page_ext145second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144146test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages147test # [ 0.000000] ftrace: allocated 294 pages with 4 groups148second # [ 0.000000] Policy zone: DMA149test # [ 0.000000] rcu: Hierarchical RCU implementation.150test # [ 0.000000] rcu: RCU event tracing is enabled.151second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off152test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.153second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154second # [ 0.000000] allocated 2097152 bytes of page_ext155test # [ 0.000000] Trampoline variant of Tasks RCU enabled.156test # [ 0.000000] Rude variant of Tasks RCU enabled.157second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages158test # [ 0.000000] Tracing variant of Tasks RCU enabled.159second # [ 0.000000] ftrace: allocated 294 pages with 4 groups160second # [ 0.000000] rcu: Hierarchical RCU implementation.161test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.162second # [ 0.000000] rcu: RCU event tracing is enabled.163test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1164test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.165second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.166second # [ 0.000000] Trampoline variant of Tasks RCU enabled.167second # [ 0.000000] Rude variant of Tasks RCU enabled.168second # [ 0.000000] Tracing variant of Tasks RCU enabled.169test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.171test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1173test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0174test # [ 0.000000] GICv3: 256 SPIs implemented175second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.176test # [ 0.000000] GICv3: 0 Extended SPIs implemented177test # [ 0.000000] Root IRQ handler: gic_handle_irq178second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.179test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI180test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0181second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.182test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000183second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0184test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]185second # [ 0.000000] GICv3: 256 SPIs implemented186second # [ 0.000000] GICv3: 0 Extended SPIs implemented187second # [ 0.000000] Root IRQ handler: gic_handle_irq188test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)189second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI190second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0191test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)192second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000193test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000194second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]195test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000196second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)197test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.198second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)199test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns200second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000201test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040202second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000203test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).204second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.205test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns206second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns207test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns208second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040209test # [ 0.000045] arm-pv: using stolen time PV210second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).211test # [ 0.000581] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)212test # [ 0.001113] Console: colour dummy device 80x25213second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns214test # [ 0.001127] printk: legacy console [tty0] enabled215second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns216second # [ 0.000052] arm-pv: using stolen time PV217test # [ 0.001353] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)218test # [ 0.001358] pid_max: default: 32768 minimum: 301219second # [ 0.000557] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)220test # [ 0.001432] LSM: initializing lsm=capability,landlock,yama,bpf,ima221second # [ 0.000922] Console: colour dummy device 80x25222test # [ 0.001640] landlock: Up and running.223test # [ 0.001643] Yama: becoming mindful.224second # [ 0.000938] printk: legacy console [tty0] enabled225test # [ 0.002158] LSM support for eBPF active226second # [ 0.001150] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)227test # [ 0.002381] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)228second # [ 0.001155] pid_max: default: 32768 minimum: 301229test # [ 0.002401] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)230second # [ 0.001224] LSM: initializing lsm=capability,landlock,yama,bpf,ima231test # [ 0.005446] rcu: Hierarchical SRCU implementation.232second # [ 0.001422] landlock: Up and running.233second # [ 0.001425] Yama: becoming mindful.234test # [ 0.005455] rcu: Max phase no-delay instances is 1000.235second # [ 0.002040] LSM support for eBPF active236test # [ 0.006447] fsl-mc MSI: its@8080000 domain created237test # [ 0.006634] EFI services will not be available.238second # [ 0.002231] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)239test # [ 0.006770] smp: Bringing up secondary CPUs ...240second # [ 0.002254] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)241test # [ 0.006803] smp: Brought up 1 node, 1 CPU242second # [ 0.005107] rcu: Hierarchical SRCU implementation.243test # [ 0.006806] SMP: Total of 1 processors activated.244test # [ 0.006808] CPU: All CPU(s) started at EL1245second # [ 0.005115] rcu: Max phase no-delay instances is 1000.246second # [ 0.006136] fsl-mc MSI: its@8080000 domain created247test # [ 0.006822] CPU features: detected: 32-bit EL0 Support248second # [ 0.006300] EFI services will not be available.249second # [ 0.006406] smp: Bringing up secondary CPUs ...250test # [ 0.006825] CPU features: detected: Data cache clean to the PoU not required for I/D coherence251second # [ 0.006456] smp: Brought up 1 node, 1 CPU252test # [ 0.006828] CPU features: detected: Common not Private translations253second # [ 0.006459] SMP: Total of 1 processors activated.254test # [ 0.006830] CPU features: detected: CRC32 instructions255second # [ 0.006461] CPU: All CPU(s) started at EL1256test # [ 0.006832] CPU features: detected: Data cache clean to Point of Persistence257second # [ 0.006476] CPU features: detected: 32-bit EL0 Support258test # [ 0.006835] CPU features: detected: Enhanced Virtualization Traps259second # [ 0.006479] CPU features: detected: Data cache clean to the PoU not required for I/D coherence260test # [ 0.006847] CPU features: detected: RCpc load-acquire (LDAPR)261second # [ 0.006482] CPU features: detected: Common not Private translations262test # [ 0.006850] CPU features: detected: LSE atomic instructions263second # [ 0.006484] CPU features: detected: CRC32 instructions264test # [ 0.006852] CPU features: detected: Privileged Access Never265second # [ 0.006486] CPU features: detected: Data cache clean to Point of Persistence266test # [ 0.006854] CPU features: detected: PMUv3267second # [ 0.006488] CPU features: detected: Enhanced Virtualization Traps268test # [ 0.006856] CPU features: detected: RAS Extension Support269second # [ 0.006499] CPU features: detected: RCpc load-acquire (LDAPR)270test # [ 0.006860] CPU features: detected: Speculative Store Bypassing Safe (SSBS)271second # [ 0.006501] CPU features: detected: LSE atomic instructions272test # [ 0.006885] alternatives: applying system-wide alternatives273second # [ 0.006503] CPU features: detected: Privileged Access Never274test # [ 0.008346] CPU features: detected: Hardware dirty bit management on CPU0275second # [ 0.006505] CPU features: detected: PMUv3276second # [ 0.006507] CPU features: detected: RAS Extension Support277second # [ 0.006511] CPU features: detected: Speculative Store Bypassing Safe (SSBS)278test # [ 0.008558] Memory: 893140K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)279second # [ 0.006535] alternatives: applying system-wide alternatives280test # [ 0.009164] devtmpfs: initialized281second # [ 0.008058] CPU features: detected: Hardware dirty bit management on CPU0282test # [ 0.010752] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)283test # [ 0.010776] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).284second # [ 0.008349] Memory: 893184K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)285test # [ 0.010905] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL286second # [ 0.008851] devtmpfs: initialized287test # [ 0.010909] 0 pages in range for non-PLT usage288second # [ 0.010485] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)289test # [ 0.010910] 508336 pages in range for PLT usage290test # [ 0.011057] pinctrl core: initialized pinctrl subsystem291second # [ 0.010509] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).292test # [ 0.011892] DMI not present or invalid.293second # [ 0.010638] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL294test # [ 0.015580] NET: Registered PF_NETLINK/PF_ROUTE protocol family295second # [ 0.010642] 0 pages in range for non-PLT usage296second # [ 0.010643] 508336 pages in range for PLT usage297test # [ 0.018217] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations298second # [ 0.010771] pinctrl core: initialized pinctrl subsystem299test # [ 0.018385] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations300second # [ 0.011604] DMI not present or invalid.301second # [ 0.015290] NET: Registered PF_NETLINK/PF_ROUTE protocol family302test # [ 0.018558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations303test # [ 0.018589] audit: initializing netlink subsys (disabled)304second # [ 0.017828] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations305test # [ 0.019231] thermal_sys: Registered thermal governor 'fair_share'306second # [ 0.018007] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations307test # [ 0.019234] thermal_sys: Registered thermal governor 'bang_bang'308second # [ 0.018182] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations309test # [ 0.019237] thermal_sys: Registered thermal governor 'step_wise'310second # [ 0.018216] audit: initializing netlink subsys (disabled)311test # [ 0.019239] thermal_sys: Registered thermal governor 'user_space'312second # [ 0.018879] thermal_sys: Registered thermal governor 'fair_share'313test # [ 0.019241] thermal_sys: Registered thermal governor 'power_allocator'314second # [ 0.018881] thermal_sys: Registered thermal governor 'bang_bang'315test # [ 0.019287] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1316second # [ 0.018884] thermal_sys: Registered thermal governor 'step_wise'317test # [ 0.019302] cpuidle: using governor ladder318test # [ 0.019307] cpuidle: using governor menu319second # [ 0.018886] thermal_sys: Registered thermal governor 'user_space'320test # [ 0.019442] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.321second # [ 0.018888] thermal_sys: Registered thermal governor 'power_allocator'322test # [ 0.019467] ASID allocator initialised with 32768 entries323second # [ 0.018925] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1324test # [ 0.020169] Serial: AMBA PL011 UART driver325second # [ 0.018939] cpuidle: using governor ladder326second # [ 0.018944] cpuidle: using governor menu327test # [ 0.023602] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1328test # [ 0.023781] printk: console [ttyAMA0] enabled329second # [ 0.019072] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.330second # [ 0.019095] ASID allocator initialised with 32768 entries331second # [ 0.019785] Serial: AMBA PL011 UART driver332second # [ 0.023193] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333second # [ 0.023404] printk: console [ttyAMA0] enabled334test # [ 0.102834] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages335test # [ 0.102861] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page336test # [ 0.102866] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages337test # [ 0.102872] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page338second # [ 0.101826] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages339test # [ 0.102875] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages340second # [ 0.101854] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page341test # [ 0.102878] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page342second # [ 0.101858] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages343test # [ 0.102882] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages344second # [ 0.101864] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page345test # [ 0.102885] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page346second # [ 0.101867] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages347second # [ 0.101870] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page348second # [ 0.101873] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages349second # [ 0.101876] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page350test # [ 0.108029] fbcon: Taking over console351test # [ 0.108051] ACPI: Interpreter disabled.352test # [ 0.109498] iommu: Default domain type: Translated353test # [ 0.109511] iommu: DMA domain TLB invalidation policy: strict mode354second # [ 0.107095] fbcon: Taking over console355test # [ 0.110759] SCSI subsystem initialized356second # [ 0.107118] ACPI: Interpreter disabled.357second # [ 0.108432] iommu: Default domain type: Translated358second # [ 0.108445] iommu: DMA domain TLB invalidation policy: strict mode359test # [ 0.111447] usbcore: registered new interface driver usbfs360test # [ 0.111489] usbcore: registered new interface driver hub361second # [ 0.109764] SCSI subsystem initialized362test # [ 0.111499] usbcore: registered new device driver usb363test # [ 0.111704] pps_core: LinuxPPS API ver. 1 registered364second # [ 0.110430] usbcore: registered new interface driver usbfs365test # [ 0.111709] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>366second # [ 0.110470] usbcore: registered new interface driver hub367test # [ 0.111716] PTP clock support registered368test # [ 0.111746] EDAC MC: Ver: 3.0.0369second # [ 0.110480] usbcore: registered new device driver usb370second # [ 0.110685] pps_core: LinuxPPS API ver. 1 registered371test # [ 0.115033] scmi_core: SCMI protocol bus registered372second # [ 0.110691] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>373second # [ 0.110698] PTP clock support registered374second # [ 0.110732] EDAC MC: Ver: 3.0.0375test # [ 0.115824] FPGA manager framework376second # [ 0.114016] scmi_core: SCMI protocol bus registered377second # [ 0.114773] FPGA manager framework378test # [ 0.122880] vgaarb: loaded379test # [ 0.123429] clocksource: Switched to clocksource arch_sys_counter380second # [ 0.121864] vgaarb: loaded381second # [ 0.122411] clocksource: Switched to clocksource arch_sys_counter382test # [ 0.128861] VFS: Disk quotas dquot_6.6.0383test # [ 0.128907] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)384second # [ 0.127481] VFS: Disk quotas dquot_6.6.0385second # [ 0.127520] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)386test # [ 0.131472] netfs: FS-Cache loaded387test # [ 0.131602] pnp: PnP ACPI: disabled388second # [ 0.130053] netfs: FS-Cache loaded389second # [ 0.130186] pnp: PnP ACPI: disabled390test # [ 0.134152] NET: Registered PF_INET protocol family391test # [ 0.134355] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)392second # [ 0.132720] NET: Registered PF_INET protocol family393second # [ 0.132928] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.158983] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.159019] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.159034] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.159075] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398test # [ 0.159143] TCP: Hash tables configured (established 8192 bind 8192)399test # [ 0.159241] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.159309] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401test # [ 0.159365] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402second # [ 0.157053] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)403second # [ 0.157085] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)404second # [ 0.157100] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)405second # [ 0.157138] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)406second # [ 0.157206] TCP: Hash tables configured (established 8192 bind 8192)407second # [ 0.157309] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)408second # [ 0.157339] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)409second # [ 0.157401] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)410second # [ 0.157538] NET: Registered PF_UNIX/PF_LOCAL protocol family411second # [ 0.157590] NET: Registered PF_XDP protocol family412second # [ 0.157613] PCI: CLS 0 bytes, default 64413second # [ 0.157825] Trying to unpack rootfs image as initramfs...414test # [ 0.164328] NET: Registered PF_UNIX/PF_LOCAL protocol family415test # [ 0.164375] NET: Registered PF_XDP protocol family416test # [ 0.164400] PCI: CLS 0 bytes, default 64417test # [ 0.164612] Trying to unpack rootfs image as initramfs...418second # [ 0.168683] kvm [1]: HYP mode not available419test # [ 0.173072] kvm [1]: HYP mode not available420second # [ 0.243362] Initialise system trusted keyrings421second # [ 0.243882] workingset: timestamp_bits=42 max_order=18 bucket_order=0422second # [ 0.244839] squashfs: version 4.0 (2009/01/31) Phillip Lougher423second # [ 0.245371] 9p: Installing v9fs 9p2000 file system support424test # [ 0.249353] Initialise system trusted keyrings425test # [ 0.249902] workingset: timestamp_bits=42 max_order=18 bucket_order=0426test # [ 0.250858] squashfs: version 4.0 (2009/01/31) Phillip Lougher427test # [ 0.251421] 9p: Installing v9fs 9p2000 file system support428second # [ 0.264779] Key type asymmetric registered429second # [ 0.264808] Asymmetric key parser 'x509' registered430second # [ 0.264878] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431test # [ 0.270789] Key type asymmetric registered432test # [ 0.270822] Asymmetric key parser 'x509' registered433test # [ 0.270895] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)434second # [ 0.270482] io scheduler mq-deadline registered435second # [ 0.270502] io scheduler kyber registered436test # [ 0.275510] io scheduler mq-deadline registered437test # [ 0.275528] io scheduler kyber registered438second # [ 0.273420] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439second # [ 0.274247] ledtrig-cpu: registered to indicate activity on CPUs440second # [ 0.275159] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:441second # [ 0.275191] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000442second # [ 0.275214] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000443second # [ 0.275220] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000444second # [ 0.275310] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits445second # [ 0.275376] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]446second # [ 0.275485] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00447second # [ 0.275505] pci_bus 0000:00: root bus resource [bus 00-ff]448second # [ 0.275510] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]449second # [ 0.275514] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]450second # [ 0.275518] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]451second # [ 0.275749] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint452second # [ 0.276206] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint453second # [ 0.276323] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]454second # [ 0.276334] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]455second # [ 0.276354] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]456second # [ 0.276366] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]457second # [ 0.276652] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint458test # [ 0.283579] pl061_gpio 9030000.pl061: PL061 GPIO chip registered459second # [ 0.276768] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]460test # [ 0.283951] ledtrig-cpu: registered to indicate activity on CPUs461second # [ 0.276779] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]462test # [ 0.284247] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:463second # [ 0.276799] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]464test # [ 0.284268] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000465second # [ 0.277072] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint466test # [ 0.284282] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000467second # [ 0.277186] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]468second # [ 0.277197] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]469test # [ 0.284288] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000470second # [ 0.277216] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]471test # [ 0.284327] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits472second # [ 0.277497] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint473test # [ 0.284366] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]474second # [ 0.277610] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]475second # [ 0.277621] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]476test # [ 0.284445] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00477second # [ 0.277640] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]478test # [ 0.284463] pci_bus 0000:00: root bus resource [bus 00-ff]479test # [ 0.284468] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]480second # [ 0.277920] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint481test # [ 0.284472] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]482second # [ 0.278045] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]483second # [ 0.278055] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]484test # [ 0.284476] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]485second # [ 0.278075] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]486test # [ 0.284570] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint487second # [ 0.278365] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint488test # [ 0.285031] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint489test # [ 0.285152] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]490test # [ 0.285164] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]491test # [ 0.285185] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492test # [ 0.285196] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]493test # [ 0.285483] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint494test # [ 0.285600] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]495test # [ 0.285611] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]496test # [ 0.285632] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497test # [ 0.285916] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint498test # [ 0.286036] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]499test # [ 0.286047] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]500test # [ 0.286068] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]501test # [ 0.286350] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint502test # [ 0.286469] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]503test # [ 0.286481] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]504test # [ 0.286502] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505test # [ 0.286800] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint506second # [ 0.302549] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]507test # [ 0.286916] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]508second # [ 0.302577] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]509test # [ 0.286928] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]510test # [ 0.286948] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511second # [ 0.302598] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512test # [ 0.287232] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint513second # [ 0.302953] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint514second # [ 0.303076] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]515test # [ 0.287348] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]516second # [ 0.303087] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]517test # [ 0.287359] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]518second # [ 0.303108] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519test # [ 0.287379] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520second # [ 0.303123] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]521test # [ 0.287690] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint522test # [ 0.287807] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]523second # [ 0.303422] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint524test # [ 0.287819] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]525second # [ 0.303541] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]526test # [ 0.287839] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527second # [ 0.303561] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528test # [ 0.287853] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]529second # [ 0.303845] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint530second # [ 0.303965] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]531test # [ 0.288130] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint532test # [ 0.288248] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]533second # [ 0.303985] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534test # [ 0.288268] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535second # [ 0.304240] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint536test # [ 0.288561] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint537second # [ 0.304357] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]538test # [ 0.288679] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]539second # [ 0.304529] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint540test # [ 0.288698] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541second # [ 0.304687] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]542second # [ 0.304700] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]543test # [ 0.288973] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint544second # [ 0.304758] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545test # [ 0.289086] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]546second # [ 0.305058] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint547test # [ 0.289246] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint548second # [ 0.305178] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]549test # [ 0.289460] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]550test # [ 0.289473] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]551second # [ 0.305189] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]552test # [ 0.289493] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553second # [ 0.305209] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]554second # [ 0.305612] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned555test # [ 0.289792] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint556test # [ 0.289908] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]557second # [ 0.305625] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned558test # [ 0.289919] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]559second # [ 0.305630] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned560test # [ 0.289939] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]561second # [ 0.305663] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned562test # [ 0.290348] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned563second # [ 0.305696] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned564test # [ 0.290360] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned565second # [ 0.305726] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned566test # [ 0.290364] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned567second # [ 0.305757] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned568test # [ 0.290398] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned569second # [ 0.305787] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned570test # [ 0.290429] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned571second # [ 0.305818] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned572test # [ 0.290460] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned573second # [ 0.305850] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned574test # [ 0.290491] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned575second # [ 0.305880] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned576test # [ 0.290520] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned577second # [ 0.305911] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned578test # [ 0.290551] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned579second # [ 0.306011] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned580test # [ 0.290583] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned581second # [ 0.306058] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned582test # [ 0.290614] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned583second # [ 0.306073] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned584test # [ 0.290645] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned585second # [ 0.306088] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned586second # [ 0.306103] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned587test # [ 0.290731] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned588second # [ 0.306118] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned589test # [ 0.290773] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned590second # [ 0.306132] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned591test # [ 0.290789] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned592second # [ 0.306148] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned593test # [ 0.290804] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned594second # [ 0.306163] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned595test # [ 0.290818] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned596second # [ 0.306177] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned597test # [ 0.290832] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned598second # [ 0.306193] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned599test # [ 0.290846] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned600second # [ 0.306208] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned601test # [ 0.290860] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned602second # [ 0.306222] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned603test # [ 0.290875] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned604second # [ 0.306237] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned605test # [ 0.290889] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned606second # [ 0.306252] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned607test # [ 0.290904] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned608second # [ 0.306266] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned609test # [ 0.290919] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned610second # [ 0.306281] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned611test # [ 0.290933] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned612second # [ 0.306302] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned613test # [ 0.290947] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned614second # [ 0.306316] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned615test # [ 0.290961] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned616second # [ 0.306330] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned617test # [ 0.290976] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned618second # [ 0.306344] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned619test # [ 0.290990] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned620second # [ 0.306360] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned621test # [ 0.291010] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned622second # [ 0.306379] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]623test # [ 0.291024] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned624second # [ 0.306385] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]625test # [ 0.291038] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned626second # [ 0.306389] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]627test # [ 0.291052] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned628test # [ 0.291067] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned629test # [ 0.291087] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]630test # [ 0.291093] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]631test # [ 0.291097] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]632second # [ 0.343159] pci 0000:00:0a.0: enabling device (0000 -> 0002)633test # [ 0.355874] pci 0000:00:0a.0: enabling device (0000 -> 0002)634second # [ 0.355525] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)635second # [ 0.357685] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)636test # [ 0.362758] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)637second # [ 0.368220] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)638second # [ 0.370084] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)639test # [ 0.372854] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)640test # [ 0.376547] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)641test # [ 0.378341] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)642second # [ 0.380705] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)643second # [ 0.383117] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)644second # [ 0.385073] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)645test # [ 0.389091] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)646test # [ 0.390958] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)647second # [ 0.395497] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)648second # [ 0.397186] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)649test # [ 0.402039] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)650second # [ 0.400227] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)651second # [ 0.402329] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)652test # [ 0.404685] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)653test # [ 0.406400] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)654second # [ 0.413315] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled655test # [ 0.416307] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)656test # [ 0.418449] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)657second # [ 0.414946] msm_serial: driver initialized658second # [ 0.415042] SuperH (H)SCI(F) driver initialized659second # [ 0.415078] STM32 USART driver initialized660test # [ 0.428280] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661test # [ 0.429919] msm_serial: driver initialized662test # [ 0.430022] SuperH (H)SCI(F) driver initialized663test # [ 0.430060] STM32 USART driver initialized664second # [ 0.442930] loop: module loaded665second # [ 0.443077] virtio_blk virtio5: 1/0/0 default/read/poll queues666second # [ 0.443719] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)667test # [ 0.455943] loop: module loaded668test # [ 0.456094] virtio_blk virtio5: 1/0/0 default/read/poll queues669test # [ 0.456696] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)670second # [ 0.450864] megasas: 07.734.00.00-rc1671second # [ 0.451377] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]672second # [ 0.453617] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000673second # [ 0.453641] Intel/Sharp Extended Query Table at 0x0031674second # [ 0.455479] Using buffer write method675second # [ 0.455540] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]676second # [ 0.457299] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000677second # [ 0.457320] Intel/Sharp Extended Query Table at 0x0031678second # [ 0.463448] Using buffer write method679second # [ 0.463481] Concatenating MTD devices:680second # [ 0.463484] (0): "0.flash"681second # [ 0.463487] (1): "0.flash"682second # [ 0.463489] into device "0.flash"683test # [ 0.467908] megasas: 07.734.00.00-rc1684test # [ 0.468415] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685test # [ 0.470396] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686test # [ 0.470421] Intel/Sharp Extended Query Table at 0x0031687test # [ 0.471980] Using buffer write method688test # [ 0.472042] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689test # [ 0.473785] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690test # [ 0.473806] Intel/Sharp Extended Query Table at 0x0031691test # [ 0.483486] Using buffer write method692test # [ 0.483513] Concatenating MTD devices:693test # [ 0.483516] (0): "0.flash"694test # [ 0.483519] (1): "0.flash"695test # [ 0.483522] into device "0.flash"696second # [ 0.681789] Freeing initrd memory: 25484K697test # [ 0.685898] Freeing initrd memory: 25484K698second # [ 0.690072] tun: Universal TUN/TAP device driver, 1.6699test # [ 0.694422] tun: Universal TUN/TAP device driver, 1.6700second # [ 0.692976] thunder_xcv, ver 1.0701second # [ 0.693013] thunder_bgx, ver 1.0702second # [ 0.693025] nicpf, ver 1.0703second # [ 0.693329] e1000: Intel(R) PRO/1000 Network Driver704second # [ 0.693336] e1000: Copyright (c) 1999-2006 Intel Corporation.705second # [ 0.693378] e1000e: Intel(R) PRO/1000 Network Driver706second # [ 0.693384] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.707test # [ 0.697301] thunder_xcv, ver 1.0708test # [ 0.697335] thunder_bgx, ver 1.0709second # [ 0.693398] igb: Intel(R) Gigabit Ethernet Network Driver710test # [ 0.697347] nicpf, ver 1.0711test # [ 0.697700] e1000: Intel(R) PRO/1000 Network Driver712second # [ 0.693401] igb: Copyright (c) 2007-2014 Intel Corporation.713test # [ 0.697708] e1000: Copyright (c) 1999-2006 Intel Corporation.714second # [ 0.693411] igbvf: Intel(R) Gigabit Virtual Function Network Driver715test # [ 0.697723] e1000e: Intel(R) PRO/1000 Network Driver716second # [ 0.693415] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.717test # [ 0.697730] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.718second # [ 0.693479] sky2: driver version 1.30719test # [ 0.697744] igb: Intel(R) Gigabit Ethernet Network Driver720second # [ 0.694289] usbcore: registered new interface driver usb-storage721test # [ 0.697747] igb: Copyright (c) 2007-2014 Intel Corporation.722second # [ 0.695054] ehci-pci 0000:00:0a.0: EHCI Host Controller723test # [ 0.697758] igbvf: Intel(R) Gigabit Virtual Function Network Driver724second # [ 0.695121] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1725test # [ 0.697761] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.726second # [ 0.695346] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000727test # [ 0.697828] sky2: driver version 1.30728test # [ 0.698632] usbcore: registered new interface driver usb-storage729test # [ 0.698739] usbcore: registered new interface driver usbserial_generic730second # [ 0.702032] usbcore: registered new interface driver usbserial_generic731test # [ 0.698748] usbserial: USB Serial support registered for generic732test # [ 0.699052] hv_vmbus: registering driver hyperv_keyboard733second # [ 0.702058] usbserial: USB Serial support registered for generic734test # [ 0.700077] ehci-pci 0000:00:0a.0: EHCI Host Controller735second # [ 0.702455] hv_vmbus: registering driver hyperv_keyboard736test # [ 0.700118] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1737test # [ 0.700318] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000738second # [ 0.704469] rtc-pl031 9010000.pl031: registered as rtc0739second # [ 0.704515] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T17:47:46 UTC (1790531266)740second # [ 0.704710] i2c_dev: i2c /dev entries driver741test # [ 0.708437] rtc-pl031 9010000.pl031: registered as rtc0742test # [ 0.708480] rtc-pl031 9010000.pl031: setting system clock to 2026-09-27T17:47:46 UTC (1790531266)743second # [ 0.706706] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00744test # [ 0.708708] i2c_dev: i2c /dev entries driver745second # [ 0.707052] hub 1-0:1.0: USB hub found746second # [ 0.707080] hub 1-0:1.0: 6 ports detected747second # [ 0.709617] sdhci: Secure Digital Host Controller Interface driver748second # [ 0.709636] sdhci: Copyright(c) Pierre Ossman749test # [ 0.711856] sdhci: Secure Digital Host Controller Interface driver750second # [ 0.709772] Synopsys Designware Multimedia Card Interface Driver751test # [ 0.711873] sdhci: Copyright(c) Pierre Ossman752second # [ 0.709943] sdhci-pltfm: SDHCI platform and OF driver helper753test # [ 0.712013] Synopsys Designware Multimedia Card Interface Driver754test # [ 0.712185] sdhci-pltfm: SDHCI platform and OF driver helper755test # [ 0.713124] hid: raw HID events driver (C) Jiri Kosina756test # [ 0.713301] usbcore: registered new interface driver usbhid757second # [ 0.712827] hid: raw HID events driver (C) Jiri Kosina758test # [ 0.713307] usbhid: USB HID core driver759second # [ 0.712989] usbcore: registered new interface driver usbhid760test # [ 0.716890] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00761second # [ 0.712994] usbhid: USB HID core driver762test # [ 0.717783] hub 1-0:1.0: USB hub found763test # [ 0.718136] hub 1-0:1.0: 6 ports detected764second # [ 0.714814] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available765second # [ 0.715583] drop_monitor: Initializing network drop monitor service766test # [ 0.718831] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available767second # [ 0.715783] NET: Registered PF_INET6 protocol family768test # [ 0.720437] drop_monitor: Initializing network drop monitor service769second # [ 0.718150] Segment Routing with IPv6770test # [ 0.720575] NET: Registered PF_INET6 protocol family771second # [ 0.718174] In-situ OAM (IOAM) with IPv6772second # [ 0.718219] NET: Registered PF_PACKET protocol family773test # [ 0.722243] Segment Routing with IPv6774test # [ 0.722265] In-situ OAM (IOAM) with IPv6775test # [ 0.722342] NET: Registered PF_PACKET protocol family776second # [ 0.719400] 9pnet: Installing 9P2000 support777second # [ 0.721139] Key type dns_resolver registered778test # [ 0.723488] 9pnet: Installing 9P2000 support779test # [ 0.725227] Key type dns_resolver registered780second # [ 0.724769] registered taskstats version 1781second # [ 0.724933] Loading compiled-in X.509 certificates782test # [ 0.728867] registered taskstats version 1783test # [ 0.729033] Loading compiled-in X.509 certificates784second # [ 0.730335] Demotion targets for Node 0: null785second # [ 0.731123] Key type .fscrypt registered786second # [ 0.731133] Key type fscrypt-provisioning registered787test # [ 0.734505] Demotion targets for Node 0: null788second # [ 0.731254] ima: No TPM chip found, activating TPM-bypass!789test # [ 0.734596] Key type .fscrypt registered790second # [ 0.731268] ima: Allocated hash algorithm: sha1791test # [ 0.734602] Key type fscrypt-provisioning registered792second # [ 0.731321] ima: No architecture policies found793test # [ 0.734711] ima: No TPM chip found, activating TPM-bypass!794test # [ 0.734724] ima: Allocated hash algorithm: sha1795second # [ 0.733809] input: gpio-keys as /devices/platform/gpio-keys/input/input0796test # [ 0.734797] ima: No architecture policies found797test # [ 0.737917] input: gpio-keys as /devices/platform/gpio-keys/input/input0798second # [ 0.744183] clk: Disabling unused clocks799second # [ 0.744208] PM: genpd: Disabling unused power domains800test # [ 0.748528] clk: Disabling unused clocks801test # [ 0.748553] PM: genpd: Disabling unused power domains802second # [ 0.747455] Freeing unused kernel memory: 4736K803second # [ 0.747585] Run /init as init process804test # [ 0.751802] Freeing unused kernel memory: 4736K805test # [ 0.751906] Run /init as init process806test # [ 0.773954] systemd[1]: Successfully made /usr/ read-only.807second # [ 0.773093] systemd[1]: Successfully made /usr/ read-only.808second # [ 0.954467] usb 1-1: new high-speed USB device number 2 using ehci-pci809test # [ 0.963489] usb 1-1: new high-speed USB device number 2 using ehci-pci810second # [ 1.101875] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1811test # [ 1.108729] systemd[1]: systemd 260.4 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)812test # [ 1.111517] systemd[1]: Detected virtualization qemu.813test # [ 1.111949] systemd[1]: Detected architecture arm64.814test # [ 1.112352] systemd[1]: Running in initrd.815test # [ 1.113457] systemd[1]: Initializing machine ID from random generator.816second # [ 1.107764] systemd[1]: systemd 260.4 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)817test # [ 1.114427] systemd[1]: Hostname set to <test>.818second # [ 1.112878] systemd[1]: Detected virtualization qemu.819second # [ 1.114097] systemd[1]: Detected architecture arm64.820second # [ 1.115442] systemd[1]: Running in initrd.821test # [ 1.118622] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1822second # [ 1.117506] systemd[1]: Initializing machine ID from random generator.823second # [ 1.119542] systemd[1]: Hostname set to <second>.824test # [ 1.183251] systemd[1]: Queued start job for default target Initrd Default Target.825second # [ 1.186699] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826test # [ 1.192881] systemd[1]: Created slice Slice /system/modprobe.827test # [ 1.193580] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.828test # [ 1.194265] systemd[1]: Expecting device /dev/disk/by-label/nixos...829test # [ 1.194812] systemd[1]: Reached target Path Units.830test # [ 1.195228] systemd[1]: Reached target Slice Units.831test # [ 1.195829] systemd[1]: Reached target Swaps.832test # [ 1.196223] systemd[1]: Reached target Timer Units.833test # [ 1.196842] systemd[1]: Listening on D-Bus System Message Bus Socket.834test # [ 1.197505] systemd[1]: Listening on Journal Socket (/dev/log).835test # [ 1.198167] systemd[1]: Listening on Journal Sockets.836test # [ 1.198794] systemd[1]: Listening on udev Control Socket.837test # [ 1.199399] systemd[1]: Listening on udev Kernel Socket.838test # [ 1.200015] systemd[1]: Reached target Socket Units.839test # [ 1.201633] systemd[1]: Starting Create List of Static Device Nodes...840test # [ 1.203625] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0841test # [ 1.207545] systemd[1]: Starting Load Kernel Module 9pnet_virtio...842test # [ 1.208130] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs843test # [ 1.214820] systemd[1]: Mounting Kernel Configuration File System...844second # [ 1.217270] systemd[1]: Queued start job for default target Initrd Default Target.845test # [ 1.223062] systemd[1]: Starting Journal Service...846second # [ 1.228401] systemd[1]: Created slice Slice /system/modprobe.847second # [ 1.229401] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848second # [ 1.230621] systemd[1]: Expecting device /dev/disk/by-label/nixos...849second # [ 1.231343] systemd[1]: Reached target Path Units.850second # [ 1.231896] systemd[1]: Reached target Slice Units.851second # [ 1.232444] systemd[1]: Reached target Swaps.852second # [ 1.232948] systemd[1]: Reached target Timer Units.853second # [ 1.233748] systemd[1]: Listening on D-Bus System Message Bus Socket.854second # [ 1.234823] systemd[1]: Listening on Journal Socket (/dev/log).855second # [ 1.235654] systemd[1]: Listening on Journal Sockets.856second # [ 1.236324] systemd[1]: Listening on udev Control Socket.857second # [ 1.237054] systemd[1]: Listening on udev Kernel Socket.858second # [ 1.237695] systemd[1]: Reached target Socket Units.859second # [ 1.239614] systemd[1]: Starting Create List of Static Device Nodes...860test # [ 1.241086] systemd[1]: Starting Load Kernel Modules...861test # [ 1.241151] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki862second # [ 1.243671] systemd[1]: Starting Load Kernel Module 9pnet_virtio...863second # [ 1.244421] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs864test # [ 1.256661] systemd[1]: Starting Coldplug All udev Devices...865second # [ 1.254587] systemd[1]: Mounting Kernel Configuration File System...866test # [ 1.260118] systemd-journald[73]: Collecting audit messages is disabled.867test # [ 1.260799] systemd[1]: Finished Create List of Static Device Nodes.868test # [ 1.262166] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.869test # [ 1.266048] systemd[1]: Finished Load Kernel Module 9pnet_virtio.870second # [ 1.263711] systemd[1]: Starting Journal Service...871test # [ 1.266821] systemd[1]: Mounted Kernel Configuration File System.872test # [ 1.284057] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...873second # [ 1.283482] systemd[1]: Starting Load Kernel Modules...874second # [ 1.284137] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki875second # [ 1.304534] systemd[1]: Starting Coldplug All udev Devices...876second # [ 1.306546] usb 1-2: new high-speed USB device number 3 using ehci-pci877test # [ 1.309872] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.878second # [ 1.309115] systemd-journald[73]: Collecting audit messages is disabled.879second # [ 1.309806] systemd[1]: Finished Create List of Static Device Nodes.880second # [ 1.311277] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.881second # [ 1.312118] systemd[1]: Finished Load Kernel Module 9pnet_virtio.882second # [ 1.312887] systemd[1]: Mounted Kernel Configuration File System.883test # [ 1.319570] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.884test # [ 1.321453] systemd[1]: Starting Create Static Device Nodes in /dev...885test # [ 1.324043] usb 1-2: new high-speed USB device number 3 using ehci-pci886second # [ 1.323153] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...887test # [ 1.332148] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev888test # [ 1.339619] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0889test # [ 1.339836] [drm] features: -virgl +edid -resource_blob -host_visible890test # [ 1.339842] [drm] features: -context_init891test # [ 1.340300] [drm] number of scanouts: 1892test # [ 1.340316] [drm] number of cap sets: 0893test # [ 1.345858] systemd-modules-load[75]: Inserted module 'dm_mod'894test # [ 1.354294] systemd[1]: Started Journal Service.895test # [ 1.352222] systemd-modules-load[75]: Module 'virtio_balloon' is built in896test # [ 1.353679] systemd-modules-load[75]: Module 'virtio_console' is built in897second # [ 1.358923] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.898test # [ 1.362655] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic899second # [ 1.360764] systemd[1]: Starting Create Static Device Nodes in /dev...900test # [ 1.362678] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0901second # [ 1.370500] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.902test # [ 1.372088] systemd[1]: Finished Create Static Device Nodes in /dev.903test # [ 1.372699] systemd[1]: Reached target Preparation for Local File Systems.904test # [ 1.373271] systemd[1]: Reached target Local File Systems.905second # [ 1.382497] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev906test # [ 1.383982] Console: switching to colour frame buffer device 160x50907test # [ 1.382345] systemd[1]: Starting Create System Files and Directories...908second # [ 1.396666] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0909second # [ 1.396897] [drm] features: -virgl +edid -resource_blob -host_visible910second # [ 1.396902] [drm] features: -context_init911second # [ 1.397456] [drm] number of scanouts: 1912second # [ 1.397472] [drm] number of cap sets: 0913test # [ 1.402399] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device914second # [ 1.400966] systemd[1]: Started Journal Service.915test # [ 1.388063] systemd[1]: Starting Rule-based Manager for Device Events and Files...916second # [ 1.395480] systemd-modules-load[75]: Inserted module 'dm_mod'917second # [ 1.396567] systemd-modules-load[75]: Module 'virtio_balloon' is built in918test # [ 1.400646] systemd-modules-load[75]: Inserted module 'virtio_gpu'919test # [ 1.401253] systemd-modules-load[75]: Module 'virtio_rng' is built in920second # [ 1.402085] systemd-modules-load[75]: Module 'virtio_console' is built in921second # [ 1.409824] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic922second # [ 1.409844] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0923test # [ 1.417873] systemd[1]: Finished Load Kernel Modules.924second # [ 1.417061] systemd[1]: Finished Create Static Device Nodes in /dev.925second # [ 1.419116] systemd[1]: Reached target Preparation for Local File Systems.926second # [ 1.425478] systemd[1]: Reached target Local File Systems.927second # [ 1.426050] systemd[1]: Starting Create System Files and Directories...928test # [ 1.434495] systemd[1]: Starting Apply Kernel Variables...929second # [ 1.442542] Console: switching to colour frame buffer device 160x50930test # [ 1.439203] systemd[1]: Finished Create System Files and Directories.931second # [ 1.443131] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device932second # [ 1.440439] systemd[1]: Starting Rule-based Manager for Device Events and Files...933second # [ 1.446740] systemd-modules-load[75]: Inserted module 'virtio_gpu'934second # [ 1.447326] systemd-modules-load[75]: Module 'virtio_rng' is built in935second # [ 1.447907] systemd[1]: Finished Load Kernel Modules.936second # [ 1.458823] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2937test # [ 1.456797] systemd[1]: Finished Apply Kernel Variables.938second # [ 1.460581] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0939test # [ 1.463819] systemd-udevd[87]: Using default interface naming scheme 'v260'.940test # [ 1.472277] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2941test # [ 1.474240] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0942second # [ 1.472287] systemd[1]: Starting Apply Kernel Variables...943test # [ 1.495199] systemd[1]: Started Rule-based Manager for Device Events and Files.944second # [ 1.508857] systemd[1]: Finished Create System Files and Directories.945second # [ 1.524124] systemd[1]: Finished Apply Kernel Variables.946second # [ 1.524877] systemd-udevd[87]: Using default interface naming scheme 'v260'.947second # [ 1.540256] systemd[1]: Started Rule-based Manager for Device Events and Files.948test # [ 1.573886] systemd[1]: Starting Virtual Console Setup...949second # [ 1.590496] systemd[1]: Starting Virtual Console Setup...950test # [ 1.629990] systemd-vconsole-setup[105]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.631731] systemd[1]: Finished Virtual Console Setup.952second # [ 1.645744] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.953second # [ 1.646797] systemd[1]: Finished Virtual Console Setup.954test # [ 1.884956] systemd[1]: Finished Coldplug All udev Devices.955test # [ 1.885550] systemd[1]: Reached target System Initialization.956test # [ 1.886049] systemd[1]: Reached target Basic System.957second # [ 1.901840] systemd[1]: Finished Coldplug All udev Devices.958second # [ 1.902437] systemd[1]: Reached target System Initialization.959second # [ 1.902939] systemd[1]: Reached target Basic System.960test # [ 1.987469] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.961test # [ 1.991561] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.004207] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.033329] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.964test # [ 2.060317] systemd[1]: Found device /dev/disk/by-label/nixos.965test # [ 2.062460] systemd[1]: Reached target Initrd Root Device.966test # [ 2.064077] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...967second # [ 2.072212] systemd[1]: Found device /dev/disk/by-label/nixos.968second # [ 2.073730] systemd[1]: Reached target Initrd Root Device.969second # [ 2.076151] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...970test # [ 2.115298] systemd-fsck[114]: nixos: clean, 12/65536 files, 13019/262144 blocks971test # [ 2.122339] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972second # [ 2.124303] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks973second # [ 2.131816] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974test # [ 2.252618] systemd[1]: Mounting /sysroot...975test # [ 2.289540] EXT4-fs (vda): mounted filesystem bba0b3bf-2dfc-43d7-809a-06a144864fc1 r/w with ordered data mode. Quota mode: none.976test # [ 2.287349] systemd[1]: Mounted /sysroot.977test # [ 2.288066] systemd[1]: Reached target Initrd Root File System.978test # [ 2.292102] systemd[1]: Starting Mountpoints Configured in the Real Root...979second # [ 2.301223] systemd[1]: Mounting /sysroot...980test # [ 2.310778] systemd-sysroot-fstab-check[122]: /sysroot should be mounted in the initrd, will request daemon-reload.981test # [ 2.314627] systemd[1]: Reload requested from client PID 122 ('systemd-sysroot') (unit initrd-parse-etc.service)...982test # [ 2.317947] systemd[1]: Reloading...983second # [ 2.334082] EXT4-fs (vda): mounted filesystem b50d5f76-e1ab-4550-8c09-0f75b7130bb0 r/w with ordered data mode. Quota mode: none.984second # [ 2.332876] systemd[1]: Mounted /sysroot.985second # [ 2.335705] systemd[1]: Reached target Initrd Root File System.986second # [ 2.337096] systemd[1]: Starting Mountpoints Configured in the Real Root...987second # [ 2.359843] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.988second # [ 2.366427] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...989second # [ 2.370387] systemd[1]: Reloading...990test # [ 2.506728] systemd[1]: Reloading finished in 190 ms.991test # [ 2.520121] systemd-sysroot-fstab-check[122]: Requesting initrd-fs.target/start/replace...992test # [ 2.523009] systemd-sysroot-fstab-check[122]: Requesting swap.target/start/replace...993test # [ 2.526928] systemd[1]: Starting Load Kernel Module 9pnet_virtio...994test # [ 2.528466] systemd[1]: initrd-parse-etc.service: Deactivated successfully.995test # [ 2.535277] systemd[1]: Finished Mountpoints Configured in the Real Root.996test # [ 2.536538] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.997test # [ 2.552094] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.998test # [ 2.552782] systemd[1]: Finished Load Kernel Module 9pnet_virtio.999second # [ 2.556949] systemd[1]: Reloading finished in 187 ms.1000second # [ 2.563931] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...1001second # [ 2.566895] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...1002second # [ 2.572581] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1003second # [ 2.576909] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004second # [ 2.578225] systemd[1]: Finished Mountpoints Configured in the Real Root.1005second # [ 2.579851] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1006second # [ 2.596493] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1007second # [ 2.597905] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1008test # [ 2.923496] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1009test # [ 2.927582] (udev-worker)[97]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1010test # [ 2.935445] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1011test # [ 2.936816] systemd[1]: Stopped Virtual Console Setup.1012test # [ 2.940931] systemd[1]: Stopping Virtual Console Setup...1013test # [ 2.941575] systemd[1]: Starting Virtual Console Setup...1014second # [ 2.950774] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1015test # [ 2.956623] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1016test # [ 2.957585] systemd[1]: Stopped Virtual Console Setup.1017test # [ 2.959026] systemd[1]: Starting Virtual Console Setup...1018second # [ 2.955605] (udev-worker)[102]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1019test # [ 2.973886] systemd-vconsole-setup[145]: Configuration of first virtual console was skipped, ignoring remaining ones.1020test # [ 2.976491] systemd[1]: Finished Virtual Console Setup.1021second # [ 2.976262] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1022second # [ 2.977177] systemd[1]: Stopped Virtual Console Setup.1023second # [ 2.977809] systemd[1]: Stopping Virtual Console Setup...1024second # [ 2.979089] systemd[1]: Starting Virtual Console Setup...1025second # [ 2.993489] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1026second # [ 2.996229] systemd[1]: Stopped Virtual Console Setup.1027second # [ 2.996762] systemd[1]: Starting Virtual Console Setup...1028second # [ 3.010485] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.1029second # [ 3.012956] systemd[1]: Finished Virtual Console Setup.1030test # [ 3.255840] systemd[1]: Mounting /sysroot/nix/.ro-store...1031test # [ 3.264301] systemd[1]: Mounting /sysroot/nix/.rw-store...1032test # [ 3.272323] systemd[1]: Mounting /sysroot/run...1033test # [ 3.285508] systemd[1]: Mounting /sysroot/tmp/shared...1034second # [ 3.304124] systemd[1]: Mounting /sysroot/nix/.ro-store...1035second # [ 3.309478] systemd[1]: Mounting /sysroot/nix/.rw-store...1036test # [ 3.312681] systemd[1]: Mounting /sysroot/tmp/xchg...1037second # [ 3.320709] systemd[1]: Mounting /sysroot/run...1038second # [ 3.328270] systemd[1]: Mounting /sysroot/tmp/shared...1039test # [ 3.333209] systemd[1]: Mounted /sysroot/nix/.ro-store.1040test # [ 3.336805] systemd[1]: Mounted /sysroot/nix/.rw-store.1041test # [ 3.339394] systemd[1]: Mounted /sysroot/run.1042test # [ 3.343099] systemd[1]: Mounted /sysroot/tmp/shared.1043second # [ 3.344745] systemd[1]: Mounting /sysroot/tmp/xchg...1044test # [ 3.360320] systemd[1]: Starting rw-sysroot-nix-store.service...1045test # [ 3.365169] systemd[1]: Mounted /sysroot/tmp/xchg.1046test # [ 3.378036] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1047test # [ 3.379276] systemd[1]: Finished rw-sysroot-nix-store.service.1048second # [ 3.381580] systemd[1]: Mounted /sysroot/nix/.ro-store.1049second # [ 3.386535] systemd[1]: Mounted /sysroot/nix/.rw-store.1050second # [ 3.392300] systemd[1]: Starting rw-sysroot-nix-store.service...1051second # [ 3.396854] systemd[1]: Mounted /sysroot/run.1052second # [ 3.403143] systemd[1]: Mounted /sysroot/tmp/shared.1053second # [ 3.404830] systemd[1]: Mounted /sysroot/tmp/xchg.1054second # [ 3.418920] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1055second # [ 3.421031] systemd[1]: Finished rw-sysroot-nix-store.service.1056test # [ 4.252960] systemd[1]: Mounting /sysroot/nix/store...1057test # [ 4.299459] systemd[1]: Mounted /sysroot/nix/store.1058second # [ 4.301856] systemd[1]: Mounting /sysroot/nix/store...1059test # [ 4.303344] systemd[1]: Reached target Initrd File Systems.1060test # [ 4.305628] systemd[1]: Starting Find NixOS closure...1061test # [ 4.313426] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062second # [ 4.349586] systemd[1]: Mounted /sysroot/nix/store.1063second # [ 4.352413] systemd[1]: Reached target Initrd File Systems.1064test # [ 4.355832] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1065second # [ 4.355678] systemd[1]: Starting Find NixOS closure...1066test # [ 4.357700] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1067second # [ 4.364333] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1068test # [ 4.373024] systemd[1]: Finished Find NixOS closure.1069test # [ 4.375472] systemd[1]: Reached target Initrd Default Target.1070test # [ 4.377170] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1071test # [ 4.408622] systemd[1]: Stopped target Initrd Default Target.1072test # [ 4.412365] systemd[1]: Stopped target Basic System.1073second # [ 4.410239] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1074test # [ 4.413271] systemd[1]: Stopped target Initrd Root Device.1075test # [ 4.414857] systemd[1]: Stopped target Path Units.1076second # [ 4.411960] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1077test # [ 4.417263] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1078test # [ 4.420119] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1079test # [ 4.424130] systemd[1]: Stopped target Slice Units.1080test # [ 4.424928] systemd[1]: Stopped target Socket Units.1081test # [ 4.425665] systemd[1]: Stopped target System Initialization.1082test # [ 4.426426] systemd[1]: Stopped target Swaps.1083test # [ 4.427742] systemd[1]: Stopped target Timer Units.1084test # [ 4.429956] systemd[1]: dbus.socket: Deactivated successfully.1085second # [ 4.429816] systemd[1]: Finished Find NixOS closure.1086test # [ 4.432127] systemd[1]: Closed D-Bus System Message Bus Socket.1087test # [ 4.433014] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1088second # [ 4.432187] systemd[1]: Reached target Initrd Default Target.1089second # [ 4.433728] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1090test # [ 4.437395] systemd[1]: Stopped Find NixOS closure.1091test # [ 4.439871] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1092test # [ 4.442508] systemd[1]: Starting rw-sysroot-nix-store.service...1093test # [ 4.443151] systemd[1]: systemd-sysctl.service: Deactivated successfully.1094test # [ 4.443854] systemd[1]: Stopped Apply Kernel Variables.1095test # [ 4.450233] systemd[1]: systemd-modules-load.service: Deactivated successfully.1096test # [ 4.451034] systemd[1]: Stopped Load Kernel Modules.1097test # [ 4.451499] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1098test # [ 4.455014] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1099test # [ 4.455849] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1100test # [ 4.457785] systemd[1]: Stopped Create System Files and Directories.1101test # [ 4.459430] systemd[1]: Stopped target Local File Systems.1102test # [ 4.460964] systemd[1]: Stopped target Preparation for Local File Systems.1103test # [ 4.462165] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1104test # [ 4.462866] systemd[1]: Stopped Coldplug All udev Devices.1105test # [ 4.463493] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1106second # [ 4.468606] systemd[1]: Stopped target Initrd Default Target.1107test # [ 4.470840] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1108test # [ 4.471511] systemd[1]: Stopped Virtual Console Setup.1109second # [ 4.469491] systemd[1]: Stopped target Basic System.1110second # [ 4.470335] systemd[1]: Stopped target Initrd Root Device.1111second # [ 4.471024] systemd[1]: Stopped target Path Units.1112test # [ 4.473441] systemd[1]: systemd-udevd.service: Deactivated successfully.1113second # [ 4.472100] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1114test # [ 4.474375] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1115second # [ 4.474101] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1116test # [ 4.476486] systemd[1]: initrd-cleanup.service: Deactivated successfully.1117second # [ 4.476208] systemd[1]: Stopped target Slice Units.1118test # [ 4.478386] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1119test # [ 4.478990] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1120test # [ 4.479972] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1121test # [ 4.481896] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1122second # [ 4.480207] systemd[1]: Stopped target Socket Units.1123test # [ 4.483105] systemd[1]: Finished rw-sysroot-nix-store.service.1124second # [ 4.481382] systemd[1]: Stopped target System Initialization.1125second # [ 4.482090] systemd[1]: Stopped target Swaps.1126test # [ 4.484184] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1127second # [ 4.482628] systemd[1]: Stopped target Timer Units.1128second # [ 4.483963] systemd[1]: dbus.socket: Deactivated successfully.1129second # [ 4.485803] systemd[1]: Closed D-Bus System Message Bus Socket.1130test # [ 4.488116] systemd[1]: Closed udev Control Socket.1131test # [ 4.488591] systemd[1]: Starting Cleanup udev Database...1132test # [ 4.489061] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1133test # [ 4.489725] systemd[1]: Stopped Create Static Device Nodes in /dev.1134second # [ 4.488111] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1135test # [ 4.490258] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1136test # [ 4.490937] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1137second # [ 4.489579] systemd[1]: Stopped Find NixOS closure.1138test # [ 4.491523] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1139test # [ 4.492574] systemd[1]: Stopped Create List of Static Device Nodes.1140second # [ 4.490987] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1141second # [ 4.493491] systemd[1]: Starting rw-sysroot-nix-store.service...1142second # [ 4.494238] systemd[1]: systemd-sysctl.service: Deactivated successfully.1143second # [ 4.496134] systemd[1]: Stopped Apply Kernel Variables.1144second # [ 4.497736] systemd[1]: systemd-modules-load.service: Deactivated successfully.1145second # [ 4.501063] systemd[1]: Stopped Load Kernel Modules.1146second # [ 4.504098] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1147second # [ 4.506185] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1148second # [ 4.508786] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1149second # [ 4.513403] systemd[1]: Stopped Create System Files and Directories.1150second # [ 4.514134] systemd[1]: Stopped target Local File Systems.1151second # [ 4.515666] systemd[1]: Stopped target Preparation for Local File Systems.1152test # [ 4.517657] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1153test # [ 4.519425] systemd[1]: Finished Cleanup udev Database.1154test # [ 4.519981] systemd[1]: Reached target Switch Root.1155test # [ 4.520842] systemd[1]: Starting NixOS Activation...1156second # [ 4.522886] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1157second # [ 4.523740] systemd[1]: Stopped Coldplug All udev Devices.1158second # [ 4.528370] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1159second # [ 4.529169] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1160second # [ 4.529989] systemd[1]: Stopped Virtual Console Setup.1161second # [ 4.530530] systemd[1]: systemd-udevd.service: Deactivated successfully.1162second # [ 4.536699] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1163second # [ 4.537512] systemd[1]: initrd-cleanup.service: Deactivated successfully.1164second # [ 4.538184] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1165second # [ 4.539839] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1166second # [ 4.541833] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1167second # [ 4.544118] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1168second # [ 4.544813] systemd[1]: Closed udev Control Socket.1169second # [ 4.545296] systemd[1]: Starting Cleanup udev Database...1170second # [ 4.546368] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1171second # [ 4.548130] systemd[1]: Stopped Create Static Device Nodes in /dev.1172second # [ 4.550600] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1173second # [ 4.551360] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1174second # [ 4.552826] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1175second # [ 4.553521] systemd[1]: Stopped Create List of Static Device Nodes.1176second # [ 4.554091] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1177second # [ 4.554718] systemd[1]: Finished rw-sysroot-nix-store.service.1178second # [ 4.575786] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1179second # [ 4.578334] systemd[1]: Finished Cleanup udev Database.1180second # [ 4.578819] systemd[1]: Reached target Switch Root.1181second # [ 4.580808] systemd[1]: Starting NixOS Activation...1182test # [ 4.691768] initrd-nixos-activation-start[182]: booting system configuration /nix/store/4xlf8h0kwvz1rdm88ndanzdwijzj0mfy-nixos-system-test-test1183test # [ 4.759025] initrd-nixos-activation-start[182]: running activation script...1184second # [ 4.791487] initrd-nixos-activation-start[181]: booting system configuration /nix/store/f8z2991bmvsmmq1q37nk6ir6pmwgm714-nixos-system-second-test1185second # [ 4.868805] initrd-nixos-activation-start[181]: running activation script...1186test # [ 5.236655] initrd-nixos-activation-start[205]: setting up /etc...1187second # [ 5.390906] initrd-nixos-activation-start[204]: setting up /etc...1188test # [ 5.522379] initrd-nixos-activation-start[205]: setting up age secrets...1189second # [ 5.686073] initrd-nixos-activation-start[204]: setting up age secrets...1190test # [ 5.851223] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191test # [ 5.853026] systemd[1]: Finished NixOS Activation.1192test # [ 5.853852] systemd[1]: Starting Switch Root...1193test # [ 5.868925] systemd[1]: Switching root.1194test # [ 5.924080] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195second # [ 5.931352] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1196second # [ 5.933049] systemd[1]: Finished NixOS Activation.1197second # [ 5.933816] systemd[1]: Starting Switch Root...1198second # [ 5.948531] systemd[1]: Switching root.1199second # [ 6.003207] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1200test # [ 6.514988] systemd[1]: systemd 260.4 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1201test # [ 6.522300] systemd[1]: Detected virtualization qemu.1202test # [ 6.524110] systemd[1]: Detected architecture arm64.1203test # [ 6.526306] systemd[1]: Detected first boot.1204test # [ 6.532380] systemd[1]: Initializing machine ID from random generator.1205second # [ 6.586092] systemd[1]: systemd 260.4 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)1206second # [ 6.593404] systemd[1]: Detected virtualization qemu.1207second # [ 6.595179] systemd[1]: Detected architecture arm64.1208second # [ 6.597484] systemd[1]: Detected first boot.1209second # [ 6.604200] systemd[1]: Initializing machine ID from random generator.1210test # [ 6.823936] systemd[1]: bpf-restrict-fs: LSM BPF program attached1211second # [ 6.891223] systemd[1]: bpf-restrict-fs: LSM BPF program attached1212test # [ 7.021248] systemd[1]: Applying preset policy.1213second # [ 7.077232] systemd[1]: Applying preset policy.1214test # [ 7.637460] systemd[1]: Populated /etc with preset unit settings.1215second # [ 7.686928] systemd[1]: Populated /etc with preset unit settings.1216test # [ 8.209444] systemd[1]: initrd-switch-root.service: Deactivated successfully.1217test # [ 8.210331] systemd[1]: Stopped initrd-switch-root.service.1218test # [ 8.212388] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1219test # [ 8.214216] systemd[1]: Created slice Slice /system/getty.1220test # [ 8.215858] systemd[1]: Created slice User and Session Slice.1221test # [ 8.217082] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1222test # [ 8.218466] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1223test # [ 8.219949] systemd[1]: Expecting device /dev/hvc0...1224test # [ 8.221014] systemd[1]: Expecting device /dev/ttyAMA0...1225test # [ 8.222103] systemd[1]: Reached target Local Encrypted Volumes.1226test # [ 8.223657] systemd[1]: Stopped target initrd-fs.target.1227test # [ 8.224593] systemd[1]: Stopped target initrd-root-fs.target.1228test # [ 8.225612] systemd[1]: Stopped target initrd-switch-root.target.1229test # [ 8.227175] systemd[1]: Reached target Virtual Machines and Containers.1230test # [ 8.228844] systemd[1]: Reached target Path Units.1231test # [ 8.230280] systemd[1]: Reached target Remote File Systems.1232test # [ 8.231796] systemd[1]: Reached target Slice Units.1233test # [ 8.232724] systemd[1]: Reached target Swaps.1234test # [ 8.237263] systemd[1]: Listening on Process Core Dump Socket.1235test # [ 8.241196] systemd[1]: Listening on Credential Encryption/Decryption.1236test # [ 8.246051] systemd[1]: Starting Journal Log Access Socket...1237test # [ 8.248221] systemd[1]: Listening on Journal Audit Socket.1238test # [ 8.249869] systemd[1]: Listening on Network Management Metrics Varlink Socket.1239test # [ 8.251886] systemd[1]: Listening on Network Management Netlink Socket.1240test # [ 8.253742] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1241test # [ 8.255514] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1242test # [ 8.257991] systemd[1]: Listening on udev Control Socket.1243test # [ 8.262088] systemd[1]: Mounting Huge Pages File System...1244test # [ 8.266341] systemd[1]: Mounting POSIX Message Queue File System...1245second # [ 8.268626] systemd[1]: initrd-switch-root.service: Deactivated successfully.1246test # [ 8.271556] systemd[1]: Mounting Kernel Debug File System...1247second # [ 8.269505] systemd[1]: Stopped initrd-switch-root.service.1248second # [ 8.271460] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1249second # [ 8.273270] systemd[1]: Created slice Slice /system/getty.1250second # [ 8.274892] systemd[1]: Created slice User and Session Slice.1251second # [ 8.276150] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1252test # [ 8.280066] systemd[1]: Mounting Kernel Trace File System...1253second # [ 8.277536] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1254second # [ 8.278978] systemd[1]: Expecting device /dev/hvc0...1255second # [ 8.280014] systemd[1]: Expecting device /dev/ttyAMA0...1256second # [ 8.281111] systemd[1]: Reached target Local Encrypted Volumes.1257second # [ 8.282060] systemd[1]: Stopped target initrd-fs.target.1258second # [ 8.283075] systemd[1]: Stopped target initrd-root-fs.target.1259test # [ 8.286824] systemd[1]: Starting Create List of Static Device Nodes...1260second # [ 8.284065] systemd[1]: Stopped target initrd-switch-root.target.1261second # [ 8.285152] systemd[1]: Reached target Virtual Machines and Containers.1262second # [ 8.286880] systemd[1]: Reached target Path Units.1263second # [ 8.288342] systemd[1]: Reached target Remote File Systems.1264second # [ 8.289795] systemd[1]: Reached target Slice Units.1265second # [ 8.290733] systemd[1]: Reached target Swaps.1266second # [ 8.295251] systemd[1]: Listening on Process Core Dump Socket.1267test # [ 8.301566] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1268second # [ 8.298987] systemd[1]: Listening on Credential Encryption/Decryption.1269test # [ 8.302966] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1270second # [ 8.303726] systemd[1]: Starting Journal Log Access Socket...1271second # [ 8.305256] systemd[1]: Listening on Journal Audit Socket.1272second # [ 8.306351] systemd[1]: Listening on Network Management Metrics Varlink Socket.1273second # [ 8.308104] systemd[1]: Listening on Network Management Netlink Socket.1274test # [ 8.311814] systemd[1]: Mounting Kernel Configuration File System...1275second # [ 8.309271] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1276test # [ 8.312979] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1277second # [ 8.311193] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1278second # [ 8.312538] systemd[1]: Listening on udev Control Socket.1279test # [ 8.315683] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1280second # [ 8.317232] systemd[1]: Mounting Huge Pages File System...1281second # [ 8.321284] systemd[1]: Mounting POSIX Message Queue File System...1282test # [ 8.327623] systemd[1]: Starting Load Kernel Module fuse...1283second # [ 8.324668] systemd[1]: Mounting Kernel Debug File System...1284test # [ 8.328857] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671285second # [ 8.330531] systemd[1]: Mounting Kernel Trace File System...1286second # [ 8.341593] systemd[1]: Starting Create List of Static Device Nodes...1287second # [ 8.349916] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1288second # [ 8.351071] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1289second # [ 8.360137] systemd[1]: Mounting Kernel Configuration File System...1290test # [ 8.364533] systemd[1]: Starting Journal Service...1291second # [ 8.363072] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1292second # [ 8.366222] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1293second # [ 8.380716] systemd[1]: Starting Load Kernel Module fuse...1294second # [ 8.384429] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671295test # [ 8.387970] systemd[1]: Starting Load Kernel Modules...1296test # [ 8.416860] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1297second # [ 8.420191] systemd[1]: Starting Journal Service...1298test # [ 8.429076] fuse: init (API version 7.45)1299second # [ 8.435268] systemd[1]: Starting Load Kernel Modules...1300test # [ 8.439644] systemd[1]: Starting Remount Root and Kernel File Systems...1301test # [ 8.443025] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1302second # [ 8.460125] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1303test # [ 8.463555] systemd[1]: Starting Coldplug All udev Devices...1304test # [ 8.465418] systemd[1]: Listening on Journal Log Access Socket.1305test # [ 8.465800] systemd[1]: Mounted Huge Pages File System.1306test # [ 8.466176] systemd[1]: Mounted POSIX Message Queue File System.1307test # [ 8.466541] systemd[1]: Mounted Kernel Debug File System.1308test # [ 8.466900] systemd[1]: Mounted Kernel Trace File System.1309second # [ 8.483098] fuse: init (API version 7.45)1310second # [ 8.483652] systemd[1]: Starting Remount Root and Kernel File Systems...1311second # [ 8.486774] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1312test # [ 8.490667] systemd[1]: Finished Create List of Static Device Nodes.1313test # [ 8.494423] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1314test # [ 8.498332] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1315test # [ 8.501792] systemd[1]: Mounted Kernel Configuration File System.1316second # [ 8.501186] systemd[1]: Starting Coldplug All udev Devices...1317test # [ 8.504804] systemd-journald[326]: Collecting audit messages is enabled.1318test # [ 8.509338] systemd[1]: modprobe@fuse.service: Deactivated successfully.1319test # [ 8.513730] systemd[1]: Finished Load Kernel Module fuse.1320test # [ 8.519611] systemd[1]: Finished Load Kernel Modules.1321test # [ 8.515590] systemd[1]: Queued start job for default target Multi-User System.1322test # [ 8.518136] systemd[1]: systemd-journald.service: Deactivated successfully.1323test # [ 8.518921] systemd-modules-load[327]: Module 'atkbd' is built in1324test # [ 8.519571] systemd-modules-load[327]: Module 'ctr' is built in1325test # [ 8.524923] systemd[1]: Started Journal Service.1326second # [ 8.522758] systemd[1]: Listening on Journal Log Access Socket.1327second # [ 8.523225] systemd[1]: Mounted Huge Pages File System.1328second # [ 8.523607] systemd[1]: Mounted POSIX Message Queue File System.1329second # [ 8.523958] systemd[1]: Mounted Kernel Debug File System.1330second # [ 8.524302] systemd[1]: Mounted Kernel Trace File System.1331test # [ 8.528253] systemd-modules-load[327]: Module 'loop' is built in1332second # [ 8.539191] systemd[1]: Finished Create List of Static Device Nodes.1333second # [ 8.543704] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1334test # [ 8.541780] systemd[1]: Mounting FUSE Control File System...1335second # [ 8.546623] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1336second # [ 8.549259] systemd[1]: Mounted Kernel Configuration File System.1337test # [ 8.546507] systemd[1]: Starting Firewall...1338second # [ 8.551867] systemd[1]: modprobe@fuse.service: Deactivated successfully.1339second # [ 8.554086] systemd[1]: Finished Load Kernel Module fuse.1340test # [ 8.556161] systemd[1]: Starting Apply Kernel Variables...1341second # [ 8.561400] systemd[1]: Mounting FUSE Control File System...1342second # [ 8.571072] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1343test # [ 8.567532] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1344test # [ 8.583502] EXT4-fs (vda): re-mounted bba0b3bf-2dfc-43d7-809a-06a144864fc1.1345second # [ 8.579743] systemd[1]: Queued start job for default target Multi-User System.1346second # [ 8.582862] systemd-journald[303]: Collecting audit messages is enabled.1347second # [ 8.588858] systemd[1]: Started Journal Service.1348second # [ 8.589764] systemd[1]: systemd-journald.service: Deactivated successfully.1349second # [ 8.593493] systemd-modules-load[304]: Module 'atkbd' is built in1350second # [ 8.602221] systemd-modules-load[304]: Module 'ctr' is built in1351second # [ 8.602824] systemd-modules-load[304]: Module 'loop' is built in1352second # [ 8.603404] systemd[1]: Finished Load Kernel Modules.1353second # [ 8.603968] systemd[1]: Starting Firewall...1354test # [ 8.612710] systemd[1]: Finished Remount Root and Kernel File Systems.1355test # [ 8.616707] systemd-oomd[328]: No swap; memory pressure usage will be degraded1356second # [ 8.625321] systemd[1]: Starting Apply Kernel Variables...1357test # [ 8.629527] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1358second # [ 8.644901] EXT4-fs (vda): re-mounted b50d5f76-e1ab-4550-8c09-0f75b7130bb0.1359test # [ 8.646049] systemd[1]: Starting Flush Journal to Persistent Storage...1360test # [ 8.648518] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1361test # [ 8.660273] systemd[1]: Starting Load/Save OS Random Seed...1362second # [ 8.662404] systemd[1]: Finished Remount Root and Kernel File Systems.1363test # [ 8.664273] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1364second # [ 8.675004] systemd-oomd[305]: No swap; memory pressure usage will be degraded1365second # [ 8.694896] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1366second # [ 8.705083] systemd[1]: Starting Flush Journal to Persistent Storage...1367second # [ 8.706048] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1368second # [ 8.710529] systemd[1]: Starting Load/Save OS Random Seed...1369second # [ 8.715874] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1370test # [ 8.724771] systemd[1]: Mounted FUSE Control File System.1371test # [ 8.740531] systemd[1]: Finished Apply Kernel Variables.1372second # [ 8.743870] systemd[1]: Mounted FUSE Control File System.1373test # [ 8.746126] systemd[1]: Starting Network Name Resolution...1374test # [ 8.772298] systemd-journald[326]: Received client request to flush runtime journal.1375second # [ 8.780899] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1376second # [ 8.791470] systemd[1]: Starting Create Static Device Nodes in /dev...1377second # [ 8.822579] systemd-journald[303]: Received client request to flush runtime journal.1378test # [ 8.846036] systemd[1]: Finished Load/Save OS Random Seed.1379test # [ 8.846884] systemd[1]: Reached target First Boot Complete.1380test # [ 8.848947] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1381test # [ 8.851433] systemd[1]: Starting Create Static Device Nodes in /dev...1382test # [ 8.854898] systemd[1]: Finished Flush Journal to Persistent Storage.1383second # [ 8.881864] systemd[1]: Finished Apply Kernel Variables.1384second # [ 8.885197] systemd[1]: Starting Network Name Resolution...1385second # [ 8.887925] systemd[1]: Finished Load/Save OS Random Seed.1386second # [ 8.892969] systemd[1]: Reached target First Boot Complete.1387second # [ 8.900382] systemd[1]: Finished Flush Journal to Persistent Storage.1388test # [ 8.919678] systemd-resolved[355]: Positive Trust Anchors:1389test # [ 8.920639] systemd-resolved[355]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1390test # [ 8.923419] systemd-resolved[355]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161391second # [ 8.932071] systemd[1]: Finished Create Static Device Nodes in /dev.1392second # [ 8.932824] systemd[1]: Reached target Preparation for Local File Systems.1393test # [ 8.928785] systemd-resolved[355]: 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 test1394second # [ 8.938077] systemd[1]: Starting Rule-based Manager for Device Events and Files...1395test # [ 8.960116] systemd[1]: Finished Create Static Device Nodes in /dev.1396test # [ 8.961061] systemd[1]: Reached target Preparation for Local File Systems.1397test # [ 8.963927] systemd[1]: Starting Rule-based Manager for Device Events and Files...1398second # [ 9.023330] systemd-resolved[337]: Positive Trust Anchors:1399second # [ 9.028244] systemd-resolved[337]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1400second # [ 9.029898] systemd-resolved[337]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161401second # [ 9.033515] systemd-resolved[337]: 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 test1402second # [ 9.078414] systemd-udevd[345]: Using default interface naming scheme 'v260'.1403test # [ 9.084989] systemd-udevd[369]: Using default interface naming scheme 'v260'.1404test # [ 9.163271] systemd-resolved[355]: Using system hostname 'test'.1405test # [ 9.172100] systemd[1]: Started Network Name Resolution.1406test # [ 9.209971] systemd[1]: Mounting /run/wrappers...1407second # [ 9.256358] systemd-resolved[337]: Using system hostname 'second'.1408second # [ 9.271070] systemd[1]: Mounting /run/wrappers...1409test # [ 9.274618] systemd[1]: Mounted /run/wrappers.1410second # [ 9.272989] systemd[1]: Started Network Name Resolution.1411test # [ 9.276432] systemd[1]: Reached target Local File Systems.1412test # [ 9.284261] systemd[1]: Listening on Boot Loader Control Service Socket.1413test # [ 9.287483] systemd[1]: Starting register-nix-paths.service...1414test # [ 9.291201] systemd[1]: Starting Create SUID/SGID Wrappers...1415test # [ 9.292702] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416test # [ 9.299122] systemd[1]: Starting Save Transient machine-id to Disk...1417test # [ 9.302516] systemd[1]: Starting Create System Files and Directories...1418second # [ 9.334725] systemd[1]: Mounted /run/wrappers.1419second # [ 9.335631] systemd[1]: Reached target Local File Systems.1420second # [ 9.339271] systemd[1]: Listening on Boot Loader Control Service Socket.1421second # [ 9.343776] systemd[1]: Starting register-nix-paths.service...1422second # [ 9.352204] systemd[1]: Starting Create SUID/SGID Wrappers...1423second # [ 9.353105] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1424second # [ 9.356647] systemd[1]: Starting Save Transient machine-id to Disk...1425second # [ 9.360795] systemd[1]: Starting Create System Files and Directories...1426second # [ 9.414957] systemd[1]: Started Rule-based Manager for Device Events and Files.1427test # [ 9.443303] systemd[1]: Started Rule-based Manager for Device Events and Files.1428test # [ 9.453221] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1429test # [ 9.460561] systemd[1]: Finished Save Transient machine-id to Disk.1430second # [ 9.497835] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1431second # [ 9.510992] systemd[1]: Finished Save Transient machine-id to Disk.1432test # [ 9.530861] systemd[1]: Finished Create System Files and Directories.1433test # [ 9.543056] systemd[1]: Starting Rebuild Journal Catalog...1434test # [ 9.545774] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1435second # [ 9.579593] systemd[1]: Finished Create System Files and Directories.1436second # [ 9.602647] systemd[1]: Starting Rebuild Journal Catalog...1437second # [ 9.605613] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1438test # [ 9.680294] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1439second # [ 9.721187] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1440test # [ 9.752052] systemd[1]: Finished Rebuild Journal Catalog.1441test # [ 9.764273] systemd[1]: Starting Update is Completed...1442second # [ 9.802825] systemd[1]: Finished Coldplug All udev Devices.1443second # [ 9.809773] systemd[1]: Finished Rebuild Journal Catalog.1444second # [ 9.815404] systemd[1]: Starting Update is Completed...1445test # [ 9.863600] systemd[1]: Finished Update is Completed.1446second # [ 9.881876] systemd[1]: Finished Update is Completed.1447test # [ 9.887043] systemd[1]: Finished Coldplug All udev Devices.1448second # [ 9.948542] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1449test # [ 10.077879] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1450second # [ 10.208891] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1451second # [ 10.261539] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1452test # [ 10.367180] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1453second # [ 10.394420] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1454second # [ 10.397096] systemd[1]: Finished Create SUID/SGID Wrappers.1455test # [ 10.405088] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1456test # [ 10.408184] systemd[1]: Finished Create SUID/SGID Wrappers.1457test # [ 10.433861] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1458second # [ 10.571295] (udev-worker)[387]: Network interface NamePolicy= disabled on kernel command line.1459second # [ 10.575362] (udev-worker)[385]: Network interface NamePolicy= disabled on kernel command line.1460test # [ 10.582373] systemd[1]: Finished Firewall.1461test # [ 10.582884] systemd[1]: Reached target Preparation for Network.1462second # [ 10.583352] systemd[1]: Finished Firewall.1463second # [ 10.583833] systemd[1]: Reached target Preparation for Network.1464test # [ 10.587898] systemd[1]: Starting Network Management...1465second # [ 10.588553] systemd[1]: Starting Network Management...1466test # [ 10.646187] systemd[1]: Finished register-nix-paths.service.1467test # [ 10.646786] systemd[1]: Reached target System Initialization.1468test # [ 10.649102] systemd[1]: Started Discard unused filesystem blocks once a week.1469test # [ 10.652258] systemd[1]: Started Daily Cleanup of Temporary Directories.1470test # [ 10.654368] systemd[1]: Reached target Timer Units.1471test # [ 10.656570] systemd[1]: Listening on D-Bus System Message Bus Socket.1472test # [ 10.658527] systemd[1]: Listening on Nix Daemon Socket.1473second # [ 10.661264] systemd[1]: Finished register-nix-paths.service.1474second # [ 10.661860] systemd[1]: Reached target System Initialization.1475second # [ 10.662359] systemd[1]: Started Discard unused filesystem blocks once a week.1476test # [ 10.664269] systemd[1]: Listening on Authorization Manager Agent Helper.1477second # [ 10.662951] systemd[1]: Started Daily Cleanup of Temporary Directories.1478test # [ 10.664870] systemd[1]: Listening on Hostname Service Socket.1479second # [ 10.663538] systemd[1]: Reached target Timer Units.1480test # [ 10.665421] systemd[1]: Reached target Socket Units.1481second # [ 10.667747] systemd[1]: Listening on D-Bus System Message Bus Socket.1482test # [ 10.670796] systemd[1]: Starting D-Bus System Message Bus...1483second # [ 10.673690] systemd[1]: Listening on Nix Daemon Socket.1484second # [ 10.680174] systemd[1]: Listening on Authorization Manager Agent Helper.1485second # [ 10.680806] systemd[1]: Listening on Hostname Service Socket.1486second # [ 10.684179] systemd[1]: Reached target Socket Units.1487second # [ 10.687807] systemd[1]: Starting D-Bus System Message Bus...1488test # [ 10.776311] (udev-worker)[421]: Network interface NamePolicy= disabled on kernel command line.1489test # [ 10.790605] (udev-worker)[409]: Network interface NamePolicy= disabled on kernel command line.1490second # [ 10.822363] cfg80211: Loading compiled-in X.509 certificates for regulatory database1491test # [ 10.837930] cfg80211: Loading compiled-in X.509 certificates for regulatory database1492second # [ 10.840492] mousedev: PS/2 mouse device common for all mice1493second # [ 10.863150] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1494second # [ 10.863670] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1495test # [ 10.878082] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1496test # [ 10.878596] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1497second # [ 10.876148] dbus-broker-launch[541]: Looking up NSS user entry for 'systemd-timesync'...1498test # [ 10.879927] dbus-broker-launch[561]: Looking up NSS user entry for 'systemd-timesync'...1499second # [ 10.895059] dbus-broker-launch[541]: NSS returned no entry for 'systemd-timesync'1500second # [ 10.896553] dbus-broker-launch[541]: Invalid user-name in /nix/store/vrp11q0q409lcndzx1pryawi6yqrnvdy-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1501test # [ 10.899732] dbus-broker-launch[561]: NSS returned no entry for 'systemd-timesync'1502test # [ 10.902127] dbus-broker-launch[561]: Invalid user-name in /nix/store/vrp11q0q409lcndzx1pryawi6yqrnvdy-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1503second # [ 10.923982] systemd-networkd[536]: /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.1504second # [ 10.929335] systemd-networkd[536]: /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.1505second # [ 10.936891] systemd-networkd[536]: lo: Link UP1506second # [ 10.937377] systemd-networkd[536]: lo: Gained carrier1507second # [ 10.940937] systemd[1]: Started Network Management.1508test # [ 10.944214] systemd-networkd[558]: /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.1509second # [ 10.943604] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1510test # [ 10.948308] systemd-networkd[558]: /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.1511second # [ 10.948260] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1512test # [ 10.954429] systemd-networkd[558]: lo: Link UP1513test # [ 10.956428] systemd-networkd[558]: lo: Gained carrier1514second # [ 10.958152] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1515second # [ 10.958884] systemd-networkd[536]: eth1: Link UP1516second # [ 10.959299] systemd-networkd[536]: eth1: Gained carrier1517test # [ 10.961270] systemd[1]: Started Network Management.1518second # [ 10.959764] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1519test # [ 10.966683] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1520test # [ 10.969851] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1521test # [ 10.976305] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1522second # [ 10.977439] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1523second # [ 10.980610] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1524second # [ 10.985746] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1525test # [ 10.988647] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1526test # [ 11.001131] mousedev: PS/2 mouse device common for all mice1527second # [ 10.993330] dbus-broker-launch[541]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1528test # [ 10.998472] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1529test # [ 11.002540] systemd-networkd[558]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1530second # [ 11.003828] systemd[1]: Started D-Bus System Message Bus.1531second # [ 11.006036] systemd[1]: Reached target Basic System.1532second # [ 11.008740] systemd[1]: Starting Network Manager Script Dispatcher Service...1533test # [ 11.007276] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1534test # [ 11.014857] systemd-networkd[558]: eth1: Link UP1535second # [ 11.013178] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1536second # [ 11.017870] systemd[1]: Starting Network Manager...1537test # [ 11.018238] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1538test # [ 11.027544] systemd-networkd[558]: eth1: Gained carrier1539test # [ 11.036177] systemd-networkd[558]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1540test # [ 11.041047] systemd[1]: Started D-Bus System Message Bus.1541test # [ 11.043125] systemd[1]: Reached target Basic System.1542second # [ 11.046768] systemd[1]: Started backdoor.service.1543test # [ 11.050327] systemd[1]: Starting Network Manager Script Dispatcher Service...1544test # [ 11.052791] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545test # [ 11.056141] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1546test # [ 11.057677] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1547second # [ 11.063854] systemd[1]: Starting Import lastlog data into lastlog2 database...1548second # [ 11.068237] dbus-broker-launch[541]: Ready1549test # [ 11.086585] dbus-broker-launch[561]: Ready1550second # [ 11.094810] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1551test # [ 11.101019] systemd[1]: Starting Network Manager...1552second # [ 11.105431] systemd[1]: Starting Post-Boot Actions...1553test # [ 11.114632] systemd[1]: Started backdoor.service.1554second # [ 11.123207] systemd[1]: Started Reset console on configuration changes.1555test # [ 11.137369] systemd[1]: Starting Import lastlog data into lastlog2 database...1556second # [ 11.137006] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1557second # [ 11.173729] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[551]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1558test # [ 11.185279] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1559second # connecting to host...1560test # [ 11.192488] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1561test # [ 11.202657] 9zw00hrl9ri87shidqszb96sxar6g9fp-wifi-secrets[575]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1562test # [ 11.217775] 4bb5snallvknp7ll9gml95rlfxqybb0y-wifi-secrets[574]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1563second # [ 11.217395] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1564test # [ 11.223127] systemd[1]: Starting Post-Boot Actions...1565second # [ 11.226678] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1566test # [ 11.258796] systemd[1]: Started Reset console on configuration changes.1567test # [ 11.275570] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1568test # [ 11.292148] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1569test # [ 11.297582] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1570second # [ 11.295787] systemd[1]: Finished Post-Boot Actions.1571test # [ 11.302493] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1572test # [ 11.310864] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573test # [ 11.317134] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1574test # [ 11.324372] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575second # [ 11.325659] systemd[1]: Finished Import lastlog data into lastlog2 database.1576second # [ 11.337171] nsncd[555]: Sep 27 17:47:57.138 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1577second # [ 11.343333] systemd[1]: Started Name Service Cache Daemon (nsncd).1578second # [ 11.344612] systemd[1]: Reached target Host and Network Name Lookups.1579second # [ 11.348315] systemd[1]: Reached target User and Group Name Lookups.1580second # [ 11.352927] systemd[1]: Starting User Login Management...1581test # connecting to host...1582test # [ 11.376821] systemd[1]: Finished Post-Boot Actions.1583second # [ 11.388871] systemd[1]: Started Network Manager Script Dispatcher Service.1584test: Guest shell says: b'Spawning backdoor root shell...\n'1585test: connected to guest root shell1586test: (connecting took 11.79 seconds)1587test: (finished: waiting for the VM to finish booting, in 11.79 seconds)1588test # [ 11.426536] systemd[1]: Started Name Service Cache Daemon (nsncd).1589test # [ 11.430386] systemd[1]: Reached target Host and Network Name Lookups.1590test # [ 11.435380] nsncd[580]: Sep 27 17:47:57.228 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1591test # [ 11.441780] systemd[1]: Reached target User and Group Name Lookups.1592test # [ 11.447801] systemd[1]: Starting User Login Management...1593test # [ 11.474366] systemd[1]: Finished Import lastlog data into lastlog2 database.1594second # [ 11.483155] systemd[1]: Starting Hostname Service...1595test # [ 11.496648] systemd[1]: Started Network Manager Script Dispatcher Service.1596second # [ 11.505990] systemd-logind[585]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1597second # [ 11.506772] systemd-logind[585]: Watching system buttons on /dev/input/event0 (gpio-keys)1598second # [ 11.507408] systemd-logind[585]: New seat seat0.1599second # [ 11.513546] systemd[1]: Started User Login Management.1600second # [ 11.517609] systemd[1]: Starting linger-users.service...1601second # [ 11.597480] systemd[1]: linger-users.service: Deactivated successfully.1602second # [ 11.598079] systemd[1]: Finished linger-users.service.1603second # [ 11.616988] systemd[1]: Started Hostname Service.1604test # [ 11.618076] systemd-logind[618]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1605test # [ 11.621584] systemd-logind[618]: Watching system buttons on /dev/input/event0 (gpio-keys)1606test # [ 11.622252] systemd-logind[618]: New seat seat0.1607test # [ 11.628313] systemd[1]: Started User Login Management.1608test # [ 11.634840] systemd[1]: Starting linger-users.service...1609test # [ 11.638660] systemd[1]: Starting Hostname Service...1610second # [ 11.712646] systemd-networkd[536]: eth0: Link UP1611second # [ 11.713133] systemd-networkd[536]: eth0: Gained carrier1612test # [ 11.719636] systemd[1]: linger-users.service: Deactivated successfully.1613test # [ 11.724618] systemd[1]: Finished linger-users.service.1614second # [ 11.729786] systemd[1]: Started Network Manager.1615second # [ 11.732418] systemd[1]: Reached target Network.1616second # [ 11.736509] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1617second # [ 11.744350] systemd[1]: Starting Permit User Sessions...1618test # [ 11.768180] systemd[1]: Started Hostname Service.1619test # [ 11.866453] systemd-networkd[558]: eth0: Link UP1620test # [ 11.866964] systemd-networkd[558]: eth0: Gained carrier1621test # [ 11.889041] systemd[1]: Started Network Manager.1622test # [ 11.890591] systemd[1]: Reached target Network.1623test # [ 11.896280] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1624second # [ 11.899656] systemd[1]: Finished Permit User Sessions.1625test # [ 11.902434] systemd[1]: Starting Permit User Sessions...1626second # [ 11.907018] systemd-resolved[337]: eth0: Bus client set default route setting: yes1627second # [ 11.913640] systemd[1]: Started Getty on tty1.1628second # [ 11.916463] systemd[1]: Reached target Login Prompts.1629second # [ 11.916933] systemd-resolved[337]: eth0: Bus client set DNS server list to: 10.0.2.31630second # [ 11.963339] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1631second # [ 11.966781] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1632test # [ 12.063956] systemd[1]: Finished Permit User Sessions.1633test # [ 12.071879] systemd[1]: Started Getty on tty1.1634test # [ 12.073817] systemd[1]: Reached target Login Prompts.1635test # [ 12.118370] NetworkManager[576]: <warn> [1790531277.9117] keyfile: load: "/var/run/NetworkManager/system-connections/open.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1636test # [ 12.123373] NetworkManager[576]: <warn> [1790531277.9198] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1637test # [ 12.136808] systemd-resolved[355]: eth0: Bus client set default route setting: yes1638test # [ 12.138622] systemd-resolved[355]: eth0: Bus client set DNS server list to: 10.0.2.31639second # [ 12.193255] systemd[1]: Reached target Multi-User System.1640second # [ 12.194650] systemd[1]: Startup finished in 749ms (kernel) + 5.340s (initrd) + 6.105s (userspace) = 12.194s.1641test # [ 12.195842] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1642test # [ 12.197757] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1643second # [ 12.288810] systemd-networkd[536]: eth1: Gained IPv6LL1644test # [ 12.335772] systemd[1]: Reached target Multi-User System.1645test # [ 12.337991] systemd[1]: Startup finished in 752ms (kernel) + 5.258s (initrd) + 6.326s (userspace) = 12.337s.1646test # [ 12.480606] systemd-networkd[558]: eth1: Gained IPv6LL1647second # [ 12.485593] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31648test: (finished: waiting for unit NetworkManager.service, in 13.04 seconds)1649test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1650second # [ 12.729266] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1651test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1652test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1653second # [ 12.771676] systemd[1]: Starting Virtual Console Setup...1654test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1655test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1656second # [ 12.794509] systemd-logind[585]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1657test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1658(finished: run the VM test script, in 13.62 seconds)1659test # [ 12.864816] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31660test script finished in 13.69s1661cleanup1662kill QemuMachine (pid 14)1663second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1664kill QemuMachine (pid 15)1665test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1666(finished: cleanup, in 0.26 seconds)