vm-test-run-wifi
checks.aarch64-linux.wifi
· build #810
· raw
1build01: building qj3m139cihb6fnccm2264bqm01xad6x2-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14test # Disk image does not exist, creating the virtualisation disk image...15test # Formatting '/build/vm-state-test/tmp.Pykkx8hkgq', fmt=raw size=107374182416test # mke2fs 1.47.4 (6-Mar-2025)17test: QEMU running (pid 48)18test # Discarding device blocks: 0/262144 done19test # Creating filesystem with 262144 4k blocks and 65536 inodes20test # Filesystem UUID: 1b9e8164-0260-4be9-9c99-5d1b48d422da21test # Superblock backups stored on blocks:22test # 32768, 98304, 163840, 22937623test # 24test # Allocating group tables: 0/8 done25test # Writing inode tables: 0/8 done26test # Creating journal (8192 blocks): done27test # Writing superblocks and filesystem accounting information: 0/8 done28test # 29test # Virtualisation disk image created.30test # Starting virtiofs daemons...31second # Disk image does not exist, creating the virtualisation disk image...32second: QEMU running (pid 47)33second # Formatting '/build/vm-state-second/tmp.OVM3LunDf0', fmt=raw size=107374182434test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)35second # mke2fs 1.47.4 (6-Mar-2025)36test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether37second # Discarding device blocks: 0/262144 done38test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)39(finished: start all VMs, in 0.47 seconds)40test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether41second # Creating filesystem with 262144 4k blocks and 65536 inodes42test # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...43second # Filesystem UUID: ab648fab-26fb-4371-acdd-33617e28837144test # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...45second # Superblock backups stored on blocks:46test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)47second # 32768, 98304, 163840, 22937648test # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether49second # 50second # Allocating group tables: 0/8 done51test: waiting for unit NetworkManager.service52second # Writing inode tables: 0/8 done53test # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...54test: waiting for the VM to finish booting55second # Creating journal (8192 blocks): done56test # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests57second # Writing superblocks and filesystem accounting information: 0/8 done58test # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests59second # 60test # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests61second # Virtualisation disk image created.62second # Starting virtiofs daemons...63second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)64second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether65second # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)70second # [2026-09-30T11:06:01Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether71second # [2026-09-30T11:06:01Z INFO virtiofsd] Waiting for vhost-user socket connection...72second # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests73second # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests74second # [2026-09-30T11:06:01Z INFO virtiofsd] Client connected, servicing requests75second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202677second # [ 0.000000] KASLR enabled78second # [ 0.000000] random: crng init done79second # [ 0.000000] Machine model: linux,dummy-virt80second # [ 0.000000] efi: UEFI not found.81second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT82second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]83second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]84second # [ 0.000000] Zone ranges:85second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]86second # [ 0.000000] DMA32 empty87second # [ 0.000000] Normal empty88second # [ 0.000000] Device empty89second # [ 0.000000] Movable zone start for each node90second # [ 0.000000] Early memory node ranges91second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]92second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]93second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000094second # [ 0.000000] psci: probing for conduit method from DT.95second # [ 0.000000] psci: PSCIv1.3 detected in firmware.96second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs97second # [ 0.000000] psci: Trusted OS migration not required98second # [ 0.000000] psci: SMC Calling Convention v1.199second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296101test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]102second # [ 0.000000] Detected PIPT I-cache on CPU0103second # [ 0.000000] CPU features: detected: GICv3 CPU interface104second # [ 0.000000] CPU features: detected: Spectre-v4105test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026106test # [ 0.000000] KASLR enabled107second # [ 0.000000] CPU features: detected: Spectre-BHB108test # [ 0.000000] random: crng init done109test # [ 0.000000] Machine model: linux,dummy-virt110second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR111test # [ 0.000000] efi: UEFI not found.112second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)113test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT114second # [ 0.000000] CPU features: detected: ARM erratum 1418040115test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]116second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion117test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]118second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing119test # [ 0.000000] Zone ranges:120second # [ 0.000000] alternatives: applying boot alternatives121test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]122test # [ 0.000000] DMA32 empty123test # [ 0.000000] Normal empty124test # [ 0.000000] Device empty125test # [ 0.000000] Movable zone start for each node126test # [ 0.000000] Early memory node ranges127test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]128second # [ 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/1wi0m9k81yh1n7kws32my0qkss38ix8x-nixos-system-second-test/init regInfo=/nix/.ro-store/7ryb74xh6nhdz7dwnpm2smis79f487pm-closure-info/registration console=ttyAMA0,115200n8 console=tty0129test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]130test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000131test # [ 0.000000] psci: probing for conduit method from DT.132second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/7ryb74xh6nhdz7dwnpm2smis79f487pm-closure-info/registration", will be passed to user space.133test # [ 0.000000] psci: PSCIv1.3 detected in firmware.134test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs135second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes136test # [ 0.000000] psci: Trusted OS migration not required137second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)138test # [ 0.000000] psci: SMC Calling Convention v1.1139second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)140test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)141second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB142test # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296143second # [ 0.000000] software IO TLB: area num 1.144test # [ 0.000000] Detected PIPT I-cache on CPU0145second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)146test # [ 0.000000] CPU features: detected: GICv3 CPU interface147second # [ 0.000000] Fallback order for Node 0: 0148test # [ 0.000000] CPU features: detected: Spectre-v4149test # [ 0.000000] CPU features: detected: Spectre-BHB150second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144151second # [ 0.000000] Policy zone: DMA152test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR153second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off154test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)155second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1156test # [ 0.000000] CPU features: detected: ARM erratum 1418040157second # [ 0.000000] allocated 2097152 bytes of page_ext158test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion159second # [ 0.000000] ftrace: allocating 75026 entries in 294 pages160test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing161second # [ 0.000000] ftrace: allocated 294 pages with 4 groups162test # [ 0.000000] alternatives: applying boot alternatives163second # [ 0.000000] rcu: Hierarchical RCU implementation.164second # [ 0.000000] rcu: RCU event tracing is enabled.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] 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/i605dag5qn4ryyazc1pw3cd538qrrn58-nixos-system-test-test/init regInfo=/nix/.ro-store/yik6m9r4cvzj8ir4mnl2gpvg2kadzxs4-closure-info/registration console=ttyAMA0,115200n8 console=tty0170second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.171second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1172test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/yik6m9r4cvzj8ir4mnl2gpvg2kadzxs4-closure-info/registration", will be passed to user space.173second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes175second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.176test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)177second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.178test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)179second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0180test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB181second # [ 0.000000] GICv3: 256 SPIs implemented182test # [ 0.000000] software IO TLB: area num 1.183second # [ 0.000000] GICv3: 0 Extended SPIs implemented184second # [ 0.000000] Root IRQ handler: gic_handle_irq185test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)186second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI187test # [ 0.000000] Fallback order for Node 0: 0188second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0189test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144190test # [ 0.000000] Policy zone: DMA191second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000192second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]193test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off194test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1195second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)196test # [ 0.000000] allocated 2097152 bytes of page_ext197test # [ 0.000000] ftrace: allocating 75026 entries in 294 pages198second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)199test # [ 0.000000] ftrace: allocated 294 pages with 4 groups200second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000201test # [ 0.000000] rcu: Hierarchical RCU implementation.202second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000203test # [ 0.000000] rcu: RCU event tracing is enabled.204second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.205test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.206test # [ 0.000000] Trampoline variant of Tasks RCU enabled.207second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns208test # [ 0.000000] Rude variant of Tasks RCU enabled.209test # [ 0.000000] Tracing variant of Tasks RCU enabled.210second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040211second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).212test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.213test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1214second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns215test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.216second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns217second # [ 0.000053] arm-pv: using stolen time PV218test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.219second # [ 0.000476] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)220test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.221second # [ 0.000792] Console: colour dummy device 80x25222test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0223second # [ 0.000805] printk: legacy console [tty0] enabled224test # [ 0.000000] GICv3: 256 SPIs implemented225test # [ 0.000000] GICv3: 0 Extended SPIs implemented226second # [ 0.001042] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)227test # [ 0.000000] Root IRQ handler: gic_handle_irq228second # [ 0.001047] pid_max: default: 32768 minimum: 301229test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI230second # [ 0.001126] LSM: initializing lsm=capability,landlock,yama,bpf,ima231test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0232second # [ 0.001262] landlock: Up and running.233second # [ 0.001264] Yama: becoming mindful.234test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000235second # [ 0.001757] LSM support for eBPF active236test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]237second # [ 0.001977] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)238test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)239second # [ 0.001997] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)240second # [ 0.004719] rcu: Hierarchical SRCU implementation.241test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)242second # [ 0.004727] rcu: Max phase no-delay instances is 1000.243test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000244second # [ 0.005676] fsl-mc MSI: its@8080000 domain created245test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000246second # [ 0.005879] EFI services will not be available.247second # [ 0.006012] smp: Bringing up secondary CPUs ...248test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.249second # [ 0.006087] smp: Brought up 1 node, 1 CPU250second # [ 0.006092] SMP: Total of 1 processors activated.251test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns252second # [ 0.006094] CPU: All CPU(s) started at EL1253second # [ 0.006105] CPU features: detected: 32-bit EL0 Support254test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040255test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).256second # [ 0.006108] CPU features: detected: Data cache clean to the PoU not required for I/D coherence257second # [ 0.006111] CPU features: detected: Common not Private translations258test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns259second # [ 0.006113] CPU features: detected: CRC32 instructions260test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns261second # [ 0.006115] CPU features: detected: Data cache clean to Point of Persistence262test # [ 0.000052] arm-pv: using stolen time PV263second # [ 0.006117] CPU features: detected: Enhanced Virtualization Traps264second # [ 0.006128] CPU features: detected: RCpc load-acquire (LDAPR)265test # [ 0.000477] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)266second # [ 0.006131] CPU features: detected: LSE atomic instructions267test # [ 0.000791] Console: colour dummy device 80x25268second # [ 0.006132] CPU features: detected: Privileged Access Never269test # [ 0.000806] printk: legacy console [tty0] enabled270second # [ 0.006134] CPU features: detected: PMUv3271second # [ 0.006136] CPU features: detected: RAS Extension Support272test # [ 0.001075] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)273test # [ 0.001080] pid_max: default: 32768 minimum: 301274second # [ 0.006140] CPU features: detected: Speculative Store Bypassing Safe (SSBS)275test # [ 0.001155] LSM: initializing lsm=capability,landlock,yama,bpf,ima276second # [ 0.006160] alternatives: applying system-wide alternatives277test # [ 0.001321] landlock: Up and running.278test # [ 0.001323] Yama: becoming mindful.279second # [ 0.007606] CPU features: detected: Hardware dirty bit management on CPU0280test # [ 0.001749] LSM support for eBPF active281test # [ 0.001927] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)282second # [ 0.007797] Memory: 891188K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116068K reserved, 32768K cma-reserved)283second # [ 0.008304] devtmpfs: initialized284test # [ 0.001946] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)285second # [ 0.009749] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)286test # [ 0.004784] rcu: Hierarchical SRCU implementation.287test # [ 0.004791] rcu: Max phase no-delay instances is 1000.288second # [ 0.009772] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).289test # [ 0.005746] fsl-mc MSI: its@8080000 domain created290second # [ 0.009907] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL291test # [ 0.005950] EFI services will not be available.292second # [ 0.009910] 0 pages in range for non-PLT usage293test # [ 0.006069] smp: Bringing up secondary CPUs ...294test # [ 0.006123] smp: Brought up 1 node, 1 CPU295second # [ 0.009911] 508208 pages in range for PLT usage296test # [ 0.006127] SMP: Total of 1 processors activated.297second # [ 0.010041] pinctrl core: initialized pinctrl subsystem298test # [ 0.006129] CPU: All CPU(s) started at EL1299second # [ 0.010838] DMI not present or invalid.300test # [ 0.006145] CPU features: detected: 32-bit EL0 Support301second # [ 0.014546] NET: Registered PF_NETLINK/PF_ROUTE protocol family302test # [ 0.006148] CPU features: detected: Data cache clean to the PoU not required for I/D coherence303second # [ 0.017133] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations304test # [ 0.006150] CPU features: detected: Common not Private translations305second # [ 0.017270] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations306test # [ 0.006152] CPU features: detected: CRC32 instructions307second # [ 0.017416] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations308test # [ 0.006154] CPU features: detected: Data cache clean to Point of Persistence309second # [ 0.017435] audit: initializing netlink subsys (disabled)310test # [ 0.006157] CPU features: detected: Enhanced Virtualization Traps311second # [ 0.018042] thermal_sys: Registered thermal governor 'fair_share'312test # [ 0.006173] CPU features: detected: RCpc load-acquire (LDAPR)313second # [ 0.018044] thermal_sys: Registered thermal governor 'bang_bang'314test # [ 0.006176] CPU features: detected: LSE atomic instructions315second # [ 0.018047] thermal_sys: Registered thermal governor 'step_wise'316test # [ 0.006178] CPU features: detected: Privileged Access Never317second # [ 0.018049] thermal_sys: Registered thermal governor 'user_space'318test # [ 0.006180] CPU features: detected: PMUv3319test # [ 0.006181] CPU features: detected: RAS Extension Support320second # [ 0.018051] thermal_sys: Registered thermal governor 'power_allocator'321test # [ 0.006186] CPU features: detected: Speculative Store Bypassing Safe (SSBS)322second # [ 0.018086] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1323second # [ 0.018096] cpuidle: using governor ladder324test # [ 0.006208] alternatives: applying system-wide alternatives325second # [ 0.018100] cpuidle: using governor menu326test # [ 0.007689] CPU features: detected: Hardware dirty bit management on CPU0327second # [ 0.018226] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.328second # [ 0.018247] ASID allocator initialised with 32768 entries329second # [ 0.018956] Serial: AMBA PL011 UART driver330test # [ 0.007909] Memory: 891004K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116040K reserved, 32768K cma-reserved)331test # [ 0.008479] devtmpfs: initialized332second # [ 0.022538] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333test # [ 0.009914] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)334second # [ 0.022682] printk: console [ttyAMA0] enabled335test # [ 0.009936] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).336test # [ 0.010079] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL337test # [ 0.010082] 0 pages in range for non-PLT usage338test # [ 0.010083] 508208 pages in range for PLT usage339test # [ 0.010191] pinctrl core: initialized pinctrl subsystem340test # [ 0.010949] DMI not present or invalid.341test # [ 0.014356] NET: Registered PF_NETLINK/PF_ROUTE protocol family342test # [ 0.016686] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations343test # [ 0.016821] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations344test # [ 0.016968] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations345test # [ 0.016985] audit: initializing netlink subsys (disabled)346test # [ 0.017567] thermal_sys: Registered thermal governor 'fair_share'347test # [ 0.017569] thermal_sys: Registered thermal governor 'bang_bang'348test # [ 0.017572] thermal_sys: Registered thermal governor 'step_wise'349test # [ 0.017574] thermal_sys: Registered thermal governor 'user_space'350test # [ 0.017576] thermal_sys: Registered thermal governor 'power_allocator'351test # [ 0.017611] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352test # [ 0.017619] cpuidle: using governor ladder353test # [ 0.017624] cpuidle: using governor menu354test # [ 0.017742] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.355test # [ 0.017763] ASID allocator initialised with 32768 entries356test # [ 0.018437] Serial: AMBA PL011 UART driver357test # [ 0.021885] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1358test # [ 0.022054] printk: console [ttyAMA0] enabled359second # [ 0.096570] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages360second # [ 0.096585] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page361second # [ 0.096588] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages362second # [ 0.096592] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page363second # [ 0.096594] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364second # [ 0.096596] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page365second # [ 0.096598] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages366second # [ 0.096600] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page367second # [ 0.097630] fbcon: Taking over console368second # [ 0.097649] ACPI: Interpreter disabled.369second # [ 0.098315] iommu: Default domain type: Translated370second # [ 0.098320] iommu: DMA domain TLB invalidation policy: strict mode371second # [ 0.098624] SCSI subsystem initialized372second # [ 0.098827] usbcore: registered new interface driver usbfs373second # [ 0.098857] usbcore: registered new interface driver hub374second # [ 0.098873] usbcore: registered new device driver usb375second # [ 0.099109] pps_core: LinuxPPS API ver. 1 registered376second # [ 0.099113] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>377second # [ 0.099118] PTP clock support registered378second # [ 0.099157] EDAC MC: Ver: 3.0.0379test # [ 0.096847] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages380test # [ 0.096862] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page381test # [ 0.096865] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages382test # [ 0.096869] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page383second # [ 0.108844] scmi_core: SCMI protocol bus registered384second # [ 0.109197] FPGA manager framework385second # [ 0.112751] vgaarb: loaded386test # [ 0.096871] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages387test # [ 0.096873] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page388test # [ 0.096875] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages389test # [ 0.096877] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page390test # [ 0.097868] fbcon: Taking over console391test # [ 0.097885] ACPI: Interpreter disabled.392test # [ 0.098547] iommu: Default domain type: Translated393test # [ 0.098551] iommu: DMA domain TLB invalidation policy: strict mode394test # [ 0.098832] SCSI subsystem initialized395test # [ 0.099044] usbcore: registered new interface driver usbfs396second # [ 0.113828] clocksource: Switched to clocksource arch_sys_counter397test # [ 0.099081] usbcore: registered new interface driver hub398test # [ 0.099098] usbcore: registered new device driver usb399test # [ 0.099309] pps_core: LinuxPPS API ver. 1 registered400test # [ 0.099312] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401test # [ 0.099317] PTP clock support registered402test # [ 0.099353] EDAC MC: Ver: 3.0.0403test # [ 0.108993] scmi_core: SCMI protocol bus registered404test # [ 0.109348] FPGA manager framework405test # [ 0.112906] vgaarb: loaded406second # [ 0.123420] VFS: Disk quotas dquot_6.6.0407second # [ 0.123445] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)408second # [ 0.124979] netfs: FS-Cache loaded409second # [ 0.125096] pnp: PnP ACPI: disabled410test # [ 0.113993] clocksource: Switched to clocksource arch_sys_counter411second # [ 0.128337] NET: Registered PF_INET protocol family412second # [ 0.128509] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)413test # [ 0.123282] VFS: Disk quotas dquot_6.6.0414test # [ 0.123309] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)415test # [ 0.124829] netfs: FS-Cache loaded416test # [ 0.124956] pnp: PnP ACPI: disabled417test # [ 0.128291] NET: Registered PF_INET protocol family418test # [ 0.128468] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419second # [ 0.153479] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420second # [ 0.153503] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421second # [ 0.153516] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422second # [ 0.153552] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)423second # [ 0.153618] TCP: Hash tables configured (established 8192 bind 8192)424second # [ 0.153713] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)425second # [ 0.153745] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)426second # [ 0.153803] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)427test # [ 0.152529] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)428test # [ 0.152554] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)429test # [ 0.152566] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)430test # [ 0.152603] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)431test # [ 0.152669] TCP: Hash tables configured (established 8192 bind 8192)432test # [ 0.152764] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)433test # [ 0.152828] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)434test # [ 0.152881] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)435test # [ 0.152993] NET: Registered PF_UNIX/PF_LOCAL protocol family436test # [ 0.153068] NET: Registered PF_XDP protocol family437second # [ 0.158599] NET: Registered PF_UNIX/PF_LOCAL protocol family438test # [ 0.153089] PCI: CLS 0 bytes, default 64439second # [ 0.158635] NET: Registered PF_XDP protocol family440second # [ 0.158663] PCI: CLS 0 bytes, default 64441test # [ 0.153318] Trying to unpack rootfs image as initramfs...442second # [ 0.158888] Trying to unpack rootfs image as initramfs...443second # [ 0.161970] kvm [1]: HYP mode not available444test # [ 0.166024] kvm [1]: HYP mode not available445second # [ 0.237161] Initialise system trusted keyrings446second # [ 0.237291] workingset: timestamp_bits=42 max_order=18 bucket_order=0447second # [ 0.237653] squashfs: version 4.0 (2009/01/31) Phillip Lougher448second # [ 0.237688] 9p: Installing v9fs 9p2000 file system support449test # [ 0.230471] Initialise system trusted keyrings450test # [ 0.230592] workingset: timestamp_bits=42 max_order=18 bucket_order=0451test # [ 0.230946] squashfs: version 4.0 (2009/01/31) Phillip Lougher452test # [ 0.230981] 9p: Installing v9fs 9p2000 file system support453second # [ 0.258333] Key type asymmetric registered454second # [ 0.258351] Asymmetric key parser 'x509' registered455second # [ 0.258411] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)456second # [ 0.263211] io scheduler mq-deadline registered457second # [ 0.263218] io scheduler kyber registered458test # [ 0.249789] Key type asymmetric registered459test # [ 0.249807] Asymmetric key parser 'x509' registered460test # [ 0.249873] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)461test # [ 0.259344] io scheduler mq-deadline registered462test # [ 0.259351] io scheduler kyber registered463test # [ 0.261807] pl061_gpio 9030000.pl061: PL061 GPIO chip registered464test # [ 0.263364] ledtrig-cpu: registered to indicate activity on CPUs465test # [ 0.263666] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:466second # [ 0.273908] pl061_gpio 9030000.pl061: PL061 GPIO chip registered467test # [ 0.263684] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000468second # [ 0.274312] ledtrig-cpu: registered to indicate activity on CPUs469test # [ 0.263698] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000470second # [ 0.274642] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:471test # [ 0.263702] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000472second # [ 0.274659] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000473test # [ 0.263754] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits474second # [ 0.274676] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000475test # [ 0.263774] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]476second # [ 0.274680] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000477test # [ 0.263856] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00478second # [ 0.274720] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits479test # [ 0.263873] pci_bus 0000:00: root bus resource [bus 00-ff]480test # [ 0.263877] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]481second # [ 0.274742] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]482test # [ 0.263880] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]483second # [ 0.274832] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00484test # [ 0.263882] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]485second # [ 0.274847] pci_bus 0000:00: root bus resource [bus 00-ff]486second # [ 0.274850] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]487test # [ 0.263996] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint488second # [ 0.274853] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]489test # [ 0.264479] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint490second # [ 0.274855] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]491test # [ 0.264614] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]492test # [ 0.264623] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]493second # [ 0.274990] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint494test # [ 0.264642] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]495second # [ 0.275456] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint496test # [ 0.264652] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]497second # [ 0.275585] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]498second # [ 0.275594] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.264982] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint500test # [ 0.265110] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]501second # [ 0.275613] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]502test # [ 0.265119] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]503second # [ 0.275623] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]504test # [ 0.265137] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505second # [ 0.275943] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint506second # [ 0.276068] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]507test # [ 0.265458] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint508second # [ 0.276078] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]509test # [ 0.265585] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]510test # [ 0.265594] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]511second # [ 0.276097] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512test # [ 0.265612] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513second # [ 0.276419] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint514second # [ 0.276545] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]515test # [ 0.265935] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint516second # [ 0.276554] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]517second # [ 0.276573] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518second # [ 0.276890] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint519second # [ 0.277017] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]520second # [ 0.277026] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]521second # [ 0.277045] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522second # [ 0.277053] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]523second # [ 0.277376] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint524second # [ 0.277504] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]525second # [ 0.277522] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526second # [ 0.296258] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint527second # [ 0.296390] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]528second # [ 0.296409] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529second # [ 0.296741] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint530second # [ 0.296865] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]531test # [ 0.286140] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]532second # [ 0.297092] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint533test # [ 0.286156] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]534second # [ 0.297220] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]535test # [ 0.286175] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536second # [ 0.297238] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537test # [ 0.286184] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]538second # [ 0.297561] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint539test # [ 0.286564] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint540second # [ 0.297689] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]541test # [ 0.286698] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]542second # [ 0.297707] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543test # [ 0.286716] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544test # [ 0.287043] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint545test # [ 0.287171] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]546test # [ 0.287189] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547test # [ 0.287480] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint548second # [ 0.303961] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint549test # [ 0.287606] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]550second # [ 0.304090] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]551test # [ 0.287835] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint552second # [ 0.304108] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553test # [ 0.287965] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]554second # [ 0.304437] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint555test # [ 0.287984] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556second # [ 0.304666] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]557second # [ 0.304676] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]558test # [ 0.288319] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint559second # [ 0.304695] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560test # [ 0.288449] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]561test # [ 0.288467] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562second # [ 0.305015] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint563second # [ 0.305142] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]564test # [ 0.288781] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint565second # [ 0.305151] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]566test # [ 0.288911] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]567second # [ 0.305169] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568test # [ 0.288929] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569second # [ 0.305604] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned570test # [ 0.289249] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint571second # [ 0.305612] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned572test # [ 0.289490] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]573second # [ 0.305615] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned574test # [ 0.289500] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]575test # [ 0.289518] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]576second # [ 0.305650] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned577test # [ 0.289841] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint578second # [ 0.305683] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned579test # [ 0.289968] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]580second # [ 0.305715] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned581test # [ 0.289977] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]582second # [ 0.305747] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned583second # [ 0.305777] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned584second # [ 0.305809] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned585test # [ 0.304282] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]586test # [ 0.304798] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned587test # [ 0.304805] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned588test # [ 0.304808] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned589test # [ 0.304845] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned590test # [ 0.304878] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned591test # [ 0.304909] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned592test # [ 0.304941] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned593test # [ 0.304970] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned594test # [ 0.305001] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned595test # [ 0.305030] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned596test # [ 0.305059] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned597test # [ 0.305089] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned598test # [ 0.305152] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned599test # [ 0.305181] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned600test # [ 0.305193] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned601second # [ 0.321948] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned602test # [ 0.305204] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned603second # [ 0.321988] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned604test # [ 0.305216] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned605test # [ 0.305228] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned606second # [ 0.322020] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned607test # [ 0.305241] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned608second # [ 0.322087] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned609test # [ 0.305310] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned610second # [ 0.322118] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned611test # [ 0.305323] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned612second # [ 0.322133] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned613test # [ 0.305335] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned614second # [ 0.322177] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned615test # [ 0.305348] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned616second # [ 0.322191] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned617test # [ 0.305359] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned618second # [ 0.322205] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned619test # [ 0.305372] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620second # [ 0.322218] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned621test # [ 0.305383] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned622second # [ 0.322231] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned623test # [ 0.305395] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned624second # [ 0.322244] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned625test # [ 0.305407] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned626second # [ 0.322256] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned627test # [ 0.305419] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned628second # [ 0.322268] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned629test # [ 0.305431] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned630second # [ 0.322280] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned631test # [ 0.305444] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned632test # [ 0.305461] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]633second # [ 0.322293] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned634test # [ 0.305465] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]635second # [ 0.322304] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned636test # [ 0.305474] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]637second # [ 0.322316] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned638second # [ 0.322328] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned639second # [ 0.322340] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640second # [ 0.322351] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned641second # [ 0.322363] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned642second # [ 0.322382] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]643second # [ 0.322387] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]644second # [ 0.322396] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]645second # [ 0.322962] pci 0000:00:07.0: enabling device (0000 -> 0002)646test # [ 0.330095] pci 0000:00:07.0: enabling device (0000 -> 0002)647second # [ 0.343073] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)648second # [ 0.344655] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)649test # [ 0.344870] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)650second # [ 0.354093] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)651second # [ 0.356319] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)652test # [ 0.347088] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)653test # [ 0.348557] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)654test # [ 0.349948] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)655second # [ 0.366000] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)656second # [ 0.368654] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)657test # [ 0.359193] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)658test # [ 0.361001] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)659test # [ 0.363599] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)660test # [ 0.365176] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)661second # [ 0.378289] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)662second # [ 0.379957] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)663second # [ 0.381563] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)664second # [ 0.384733] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)665test # [ 0.374485] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)666test # [ 0.376116] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)667test # [ 0.379245] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)668second # [ 0.394342] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)669second # [ 0.398414] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled670second # [ 0.399426] msm_serial: driver initialized671second # [ 0.399498] SuperH (H)SCI(F) driver initialized672second # [ 0.399539] STM32 USART driver initialized673test # [ 0.390759] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled674test # [ 0.391792] msm_serial: driver initialized675test # [ 0.391876] SuperH (H)SCI(F) driver initialized676test # [ 0.391915] STM32 USART driver initialized677second # [ 0.423457] loop: module loaded678second # [ 0.423588] virtio_blk virtio2: 1/0/0 default/read/poll queues679second # [ 0.424263] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)680test # [ 0.416342] loop: module loaded681test # [ 0.416481] virtio_blk virtio2: 1/0/0 default/read/poll queues682test # [ 0.417124] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)683test # [ 0.422440] megasas: 07.734.00.00-rc1684test # [ 0.422936] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685test # [ 0.425983] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686test # [ 0.427721] Intel/Sharp Extended Query Table at 0x0031687test # [ 0.429304] Using buffer write method688test # [ 0.429334] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689second # [ 0.427562] megasas: 07.734.00.00-rc1690second # [ 0.428140] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]691test # [ 0.439110] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000692test # [ 0.439160] Intel/Sharp Extended Query Table at 0x0031693test # [ 0.440855] Using buffer write method694test # [ 0.440869] Concatenating MTD devices:695test # [ 0.440871] (0): "0.flash"696second # [ 0.450941] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000697test # [ 0.440873] (1): "0.flash"698second # [ 0.451010] Intel/Sharp Extended Query Table at 0x0031699test # [ 0.440874] into device "0.flash"700second # [ 0.452653] Using buffer write method701second # [ 0.452700] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]702second # [ 0.456511] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000703second # [ 0.456532] Intel/Sharp Extended Query Table at 0x0031704second # [ 0.465843] Using buffer write method705second # [ 0.465857] Concatenating MTD devices:706second # [ 0.465859] (0): "0.flash"707second # [ 0.465861] (1): "0.flash"708second # [ 0.465863] into device "0.flash"709second # [ 0.673732] Freeing initrd memory: 26948K710test # [ 0.664797] Freeing initrd memory: 26920K711second # [ 0.681675] tun: Universal TUN/TAP device driver, 1.6712test # [ 0.672785] tun: Universal TUN/TAP device driver, 1.6713second # [ 0.684408] thunder_xcv, ver 1.0714second # [ 0.684425] thunder_bgx, ver 1.0715second # [ 0.684434] nicpf, ver 1.0716second # [ 0.684774] e1000: Intel(R) PRO/1000 Network Driver717second # [ 0.684778] e1000: Copyright (c) 1999-2006 Intel Corporation.718second # [ 0.684796] e1000e: Intel(R) PRO/1000 Network Driver719second # [ 0.684800] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.720second # [ 0.684812] igb: Intel(R) Gigabit Ethernet Network Driver721second # [ 0.684814] igb: Copyright (c) 2007-2014 Intel Corporation.722test # [ 0.675444] thunder_xcv, ver 1.0723test # [ 0.675460] thunder_bgx, ver 1.0724second # [ 0.684824] igbvf: Intel(R) Gigabit Virtual Function Network Driver725test # [ 0.675469] nicpf, ver 1.0726test # [ 0.675799] e1000: Intel(R) PRO/1000 Network Driver727second # [ 0.684828] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728second # [ 0.684895] sky2: driver version 1.30729test # [ 0.675804] e1000: Copyright (c) 1999-2006 Intel Corporation.730test # [ 0.675818] e1000e: Intel(R) PRO/1000 Network Driver731second # [ 0.685751] usbcore: registered new interface driver usb-storage732test # [ 0.675822] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.733test # [ 0.675833] igb: Intel(R) Gigabit Ethernet Network Driver734test # [ 0.675835] igb: Copyright (c) 2007-2014 Intel Corporation.735test # [ 0.675845] igbvf: Intel(R) Gigabit Virtual Function Network Driver736test # [ 0.675850] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.737test # [ 0.675915] sky2: driver version 1.30738test # [ 0.676755] usbcore: registered new interface driver usb-storage739test # [ 0.676799] usbcore: registered new interface driver usbserial_generic740test # [ 0.676818] usbserial: USB Serial support registered for generic741test # [ 0.677114] hv_vmbus: registering driver hyperv_keyboard742second # [ 0.691425] ehci-pci 0000:00:07.0: EHCI Host Controller743second # [ 0.691458] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1744second # [ 0.691658] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000745second # [ 0.691704] usbcore: registered new interface driver usbserial_generic746second # [ 0.691730] usbserial: USB Serial support registered for generic747second # [ 0.692084] hv_vmbus: registering driver hyperv_keyboard748second # [ 0.692621] rtc-pl031 9010000.pl031: registered as rtc0749test # [ 0.684433] ehci-pci 0000:00:07.0: EHCI Host Controller750test # [ 0.684464] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1751second # [ 0.692650] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T11:06:02 UTC (1790766362)752second # [ 0.692814] i2c_dev: i2c /dev entries driver753test # [ 0.684695] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000754second # [ 0.694458] sdhci: Secure Digital Host Controller Interface driver755test # [ 0.684874] rtc-pl031 9010000.pl031: registered as rtc0756second # [ 0.694463] sdhci: Copyright(c) Pierre Ossman757test # [ 0.684901] rtc-pl031 9010000.pl031: setting system clock to 2026-09-30T11:06:02 UTC (1790766362)758second # [ 0.694619] Synopsys Designware Multimedia Card Interface Driver759test # [ 0.685087] i2c_dev: i2c /dev entries driver760second # [ 0.694805] sdhci-pltfm: SDHCI platform and OF driver helper761test # [ 0.686704] sdhci: Secure Digital Host Controller Interface driver762test # [ 0.686710] sdhci: Copyright(c) Pierre Ossman763second # [ 0.695737] hid: raw HID events driver (C) Jiri Kosina764second # [ 0.695874] usbcore: registered new interface driver usbhid765test # [ 0.686857] Synopsys Designware Multimedia Card Interface Driver766second # [ 0.695876] usbhid: USB HID core driver767test # [ 0.687034] sdhci-pltfm: SDHCI platform and OF driver helper768second # [ 0.701994] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00769test # [ 0.687925] hid: raw HID events driver (C) Jiri Kosina770test # [ 0.688062] usbcore: registered new interface driver usbhid771test # [ 0.688065] usbhid: USB HID core driver772second # [ 0.704383] hub 1-0:1.0: USB hub found773second # [ 0.704412] hub 1-0:1.0: 6 ports detected774second # [ 0.704704] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available775second # [ 0.705596] drop_monitor: Initializing network drop monitor service776second # [ 0.705766] NET: Registered PF_INET6 protocol family777test # [ 0.694329] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00778test # [ 0.694722] hub 1-0:1.0: USB hub found779test # [ 0.694746] hub 1-0:1.0: 6 ports detected780second # [ 0.708688] Segment Routing with IPv6781test # [ 0.695028] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available782second # [ 0.708707] In-situ OAM (IOAM) with IPv6783second # [ 0.708754] NET: Registered PF_PACKET protocol family784test # [ 0.695888] drop_monitor: Initializing network drop monitor service785second # [ 0.708814] 9pnet: Installing 9P2000 support786test # [ 0.696004] NET: Registered PF_INET6 protocol family787second # [ 0.708848] Key type dns_resolver registered788test # [ 0.696669] Segment Routing with IPv6789test # [ 0.696697] In-situ OAM (IOAM) with IPv6790test # [ 0.696754] NET: Registered PF_PACKET protocol family791test # [ 0.696819] 9pnet: Installing 9P2000 support792test # [ 0.696856] Key type dns_resolver registered793second # [ 0.713370] registered taskstats version 1794second # [ 0.713522] Loading compiled-in X.509 certificates795test # [ 0.703999] registered taskstats version 1796test # [ 0.704152] Loading compiled-in X.509 certificates797test # [ 0.709535] Demotion targets for Node 0: null798test # [ 0.709615] Key type .fscrypt registered799test # [ 0.709622] Key type fscrypt-provisioning registered800second # [ 0.718978] Demotion targets for Node 0: null801second # [ 0.719067] Key type .fscrypt registered802test # [ 0.709725] ima: No TPM chip found, activating TPM-bypass!803test # [ 0.709736] ima: Allocated hash algorithm: sha1804second # [ 0.719072] Key type fscrypt-provisioning registered805test # [ 0.709795] ima: No architecture policies found806second # [ 0.719183] ima: No TPM chip found, activating TPM-bypass!807second # [ 0.719194] ima: Allocated hash algorithm: sha1808test # [ 0.710358] input: gpio-keys as /devices/platform/gpio-keys/input/input0809second # [ 0.719253] ima: No architecture policies found810second # [ 0.719776] input: gpio-keys as /devices/platform/gpio-keys/input/input0811second # [ 0.732463] clk: Disabling unused clocks812second # [ 0.732483] PM: genpd: Disabling unused power domains813test # [ 0.722868] clk: Disabling unused clocks814test # [ 0.722880] PM: genpd: Disabling unused power domains815test # [ 0.725243] Freeing unused kernel memory: 4736K816test # [ 0.725332] Run /init as init process817second # [ 0.735594] Freeing unused kernel memory: 4736K818second # [ 0.735679] Run /init as init process819second # [ 0.756587] systemd[1]: Successfully made /usr/ read-only.820test # [ 0.748749] systemd[1]: Successfully made /usr/ read-only.821second # [ 0.953919] usb 1-1: new high-speed USB device number 2 using ehci-pci822test # [ 0.946085] usb 1-1: new high-speed USB device number 2 using ehci-pci823second # [ 1.090792] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)824second # [ 1.090837] systemd[1]: Detected virtualization qemu.825second # [ 1.090877] systemd[1]: Detected architecture arm64.826test # [ 1.082862] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)827second # [ 1.090886] systemd[1]: Running in initrd.828test # [ 1.082895] systemd[1]: Detected virtualization qemu.829second # [ 1.091718] systemd[1]: Initializing machine ID from random generator.830test # [ 1.082939] systemd[1]: Detected architecture arm64.831second # [ 1.092090] systemd[1]: Hostname set to <second>.832test # [ 1.082953] systemd[1]: Running in initrd.833test # [ 1.083883] systemd[1]: Initializing machine ID from random generator.834test # [ 1.084289] systemd[1]: Hostname set to <test>.835second # [ 1.113175] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1836test # [ 1.105438] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1837second # [ 1.202002] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838test # [ 1.194170] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0839second # [ 1.228011] systemd[1]: bpf-restrict-fs: LSM BPF program attached840test # [ 1.220377] systemd[1]: bpf-restrict-fs: LSM BPF program attached841second # [ 1.276642] systemd[1]: Queued start job for default target Initrd Default Target.842test # [ 1.269110] systemd[1]: Queued start job for default target Initrd Default Target.843second # [ 1.283228] systemd[1]: Created slice Slice /system/modprobe.844second # [ 1.283393] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.845second # [ 1.283423] systemd[1]: Expecting device /dev/disk/by-label/nixos...846second # [ 1.283442] systemd[1]: Reached target Path Units.847second # [ 1.283455] systemd[1]: Reached target Slice Units.848second # [ 1.283465] systemd[1]: Reached target Swaps.849second # [ 1.283477] systemd[1]: Reached target Timer Units.850test # [ 1.275660] systemd[1]: Created slice Slice /system/modprobe.851second # [ 1.283600] systemd[1]: Listening on D-Bus System Message Bus Socket.852second # [ 1.283706] systemd[1]: Listening on Journal Socket (/dev/log).853test # [ 1.275805] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.854second # [ 1.283816] systemd[1]: Listening on Journal Sockets.855test # [ 1.275835] systemd[1]: Expecting device /dev/disk/by-label/nixos...856test # [ 1.275855] systemd[1]: Reached target Path Units.857test # [ 1.275866] systemd[1]: Reached target Slice Units.858test # [ 1.275877] systemd[1]: Reached target Swaps.859test # [ 1.275889] systemd[1]: Reached target Timer Units.860test # [ 1.276016] systemd[1]: Listening on D-Bus System Message Bus Socket.861test # [ 1.276128] systemd[1]: Listening on Journal Socket (/dev/log).862second # [ 1.283881] systemd[1]: Listening on udev Control Socket.863second # [ 1.283923] systemd[1]: Listening on udev Kernel Socket.864second # [ 1.283935] systemd[1]: Reached target Socket Units.865second # [ 1.285011] systemd[1]: Starting Create List of Static Device Nodes...866second # [ 1.285063] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs867test # [ 1.276369] systemd[1]: Listening on Journal Sockets.868test # [ 1.276449] systemd[1]: Listening on udev Control Socket.869test # [ 1.276493] systemd[1]: Listening on udev Kernel Socket.870test # [ 1.276507] systemd[1]: Reached target Socket Units.871test # [ 1.277555] systemd[1]: Starting Create List of Static Device Nodes...872test # [ 1.277603] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs873second # [ 1.297949] systemd[1]: Mounting Kernel Configuration File System...874test # [ 1.293857] systemd[1]: Mounting Kernel Configuration File System...875second # [ 1.302517] systemd[1]: Starting Journal Service...876test # [ 1.299101] systemd[1]: Starting Journal Service...877test # [ 1.305465] systemd[1]: Starting Load Kernel Modules...878test # [ 1.305530] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os879second # [ 1.318104] systemd[1]: Starting Load Kernel Modules...880second # [ 1.318172] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os881second # [ 1.321922] usb 1-2: new high-speed USB device number 3 using ehci-pci882test # [ 1.314078] usb 1-2: new high-speed USB device number 3 using ehci-pci883second # [ 1.325990] systemd[1]: Starting Coldplug All udev Devices...884test # [ 1.322212] systemd[1]: Starting Coldplug All udev Devices...885second # [ 1.345971] systemd[1]: Finished Create List of Static Device Nodes.886second # [ 1.346974] systemd[1]: Mounted Kernel Configuration File System.887test # [ 1.342532] systemd[1]: Finished Create List of Static Device Nodes.888test # [ 1.344393] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...889test # [ 1.344554] systemd[1]: Mounted Kernel Configuration File System.890second # [ 1.354770] systemd-journald[71]: Collecting audit messages is disabled.891second # [ 1.358630] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...892test # [ 1.356020] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.893test # [ 1.361872] systemd-journald[71]: Collecting audit messages is disabled.894second # [ 1.376632] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.895test # [ 1.370112] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev896second # [ 1.381907] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev897second # [ 1.383157] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.898second # [ 1.384139] systemd[1]: Starting Create Static Device Nodes in /dev...899test # [ 1.382691] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.900test # [ 1.383777] systemd[1]: Starting Create Static Device Nodes in /dev...901second # [ 1.394898] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0902second # [ 1.395127] [drm] features: -virgl +edid -resource_blob -host_visible903second # [ 1.395129] [drm] features: -context_init904second # [ 1.395585] [drm] number of scanouts: 1905second # [ 1.395593] [drm] number of cap sets: 0906test # [ 1.391980] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0907test # [ 1.392192] [drm] features: -virgl +edid -resource_blob -host_visible908test # [ 1.392194] [drm] features: -context_init909test # [ 1.392778] [drm] number of scanouts: 1910test # [ 1.392786] [drm] number of cap sets: 0911second # [ 1.410173] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic912second # [ 1.410184] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0913test # [ 1.402307] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic914test # [ 1.402315] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0915second # [ 1.431069] systemd[1]: Finished Create Static Device Nodes in /dev.916test # [ 1.422110] Console: switching to colour frame buffer device 160x50917second # [ 1.431216] systemd[1]: Reached target Preparation for Local File Systems.918second # [ 1.431236] systemd[1]: Reached target Local File Systems.919second # [ 1.432469] Console: switching to colour frame buffer device 160x50920second # [ 1.439823] systemd[1]: Starting Rule-based Manager for Device Events and Files...921test # [ 1.430413] systemd[1]: Finished Create Static Device Nodes in /dev.922test # [ 1.430554] systemd[1]: Reached target Preparation for Local File Systems.923test # [ 1.430574] systemd[1]: Reached target Local File Systems.924test # [ 1.431753] systemd[1]: Starting Rule-based Manager for Device Events and Files...925second # [ 1.440367] systemd-modules-load[73]: Inserted module 'dm_mod'926second # [ 1.441131] systemd-modules-load[73]: Module 'virtio_balloon' is built in927second # [ 1.441866] systemd-modules-load[73]: Module 'virtio_console' is built in928second # [ 1.448821] systemd[1]: Started Journal Service.929second # [ 1.456340] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device930second # [ 1.453438] systemd-modules-load[73]: Inserted module 'virtio_gpu'931second # [ 1.454068] systemd-modules-load[73]: Module 'virtio_rng' is built in932test # [ 1.448834] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device933second # [ 1.457376] systemd[1]: Finished Load Kernel Modules.934test # [ 1.448453] systemd-modules-load[72]: Inserted module 'dm_mod'935test # [ 1.449362] systemd-modules-load[72]: Module 'virtio_balloon' is built in936test # [ 1.453703] systemd-modules-load[72]: Module 'virtio_console' is built in937second # [ 1.469680] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2938test # [ 1.455821] systemd-modules-load[72]: Inserted module 'virtio_gpu'[ 1.458719] systemd[1]: Started Journal Service.939test # 940second # [ 1.468825] systemd[1]: Starting Apply Kernel Variables...941test # [ 1.458410] systemd-modules-load[72]: Module 'virtio_rng' is built in942test # [ 1.461413] systemd[1]: Finished Load Kernel Modules.943second # [ 1.474027] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0944test # [ 1.475408] systemd[1]: Starting Apply Kernel Variables...945test # [ 1.479193] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2946test # [ 1.479360] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0947test # [ 1.479363] systemd[1]: Starting Create System Files and Directories...948second # [ 1.494087] systemd[1]: Starting Create System Files and Directories...949test # [ 1.506853] systemd-udevd[79]: Using default interface naming scheme 'v261'.950second # [ 1.517630] systemd-udevd[78]: Using default interface naming scheme 'v261'.951second # [ 1.521768] systemd[1]: Finished Apply Kernel Variables.952second # [ 1.525982] systemd[1]: Finished Create System Files and Directories.953test # [ 1.525488] systemd[1]: Finished Create System Files and Directories.954test # [ 1.529125] systemd[1]: Finished Apply Kernel Variables.955second # [ 1.552186] systemd[1]: Started Rule-based Manager for Device Events and Files.956test # [ 1.546712] systemd[1]: Started Rule-based Manager for Device Events and Files.957test # [ 1.595122] systemd[1]: Starting Virtual Console Setup...958second # [ 1.606045] systemd[1]: Starting Virtual Console Setup...959second # [ 1.640331] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.960second # [ 1.644643] systemd[1]: Finished Virtual Console Setup.961test # [ 1.640353] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.962test # [ 1.642589] systemd[1]: Finished Virtual Console Setup.963second # [ 1.857631] systemd[1]: Finished Coldplug All udev Devices.964second # [ 1.858248] systemd[1]: Reached target System Initialization.965second # [ 1.860086] systemd[1]: Reached target Basic System.966test # [ 1.864914] systemd[1]: Finished Coldplug All udev Devices.967test # [ 1.865537] systemd[1]: Reached target System Initialization.968test # [ 1.866450] systemd[1]: Reached target Basic System.969test # [ 1.958783] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.970second # [ 1.979010] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.971test # [ 1.980665] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.972second # [ 2.003189] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.973test # [ 2.032094] systemd[1]: Found device /dev/disk/by-label/nixos.974test # [ 2.037460] systemd[1]: Reached target Initrd Root Device.975second # [ 2.051658] systemd[1]: Found device /dev/disk/by-label/nixos.976test # [ 2.042321] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...977second # [ 2.058949] systemd[1]: Reached target Initrd Root Device.978second # [ 2.063311] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...979test # [ 2.084912] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks980second # [ 2.100125] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks981test # [ 2.089563] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.982second # [ 2.104788] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.983second # [ 2.322877] systemd[1]: Mounting /sysroot...984test # [ 2.320113] systemd[1]: Mounting /sysroot...985test # [ 2.352191] EXT4-fs (vda): mounted filesystem 1b9e8164-0260-4be9-9c99-5d1b48d422da r/w with ordered data mode. Quota mode: none.986test # [ 2.350521] systemd[1]: Mounted /sysroot.987test # [ 2.352560] systemd[1]: Reached target Initrd Root File System.988test # [ 2.355587] systemd[1]: Starting Mountpoints Configured in the Real Root...989second # [ 2.378858] EXT4-fs (vda): mounted filesystem ab648fab-26fb-4371-acdd-33617e288371 r/w with ordered data mode. Quota mode: none.990second # [ 2.378939] systemd[1]: Mounted /sysroot.991second # [ 2.381478] systemd[1]: Reached target Initrd Root File System.992second # [ 2.382677] systemd[1]: Starting Mountpoints Configured in the Real Root...993test # [ 2.374078] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.994test # [ 2.378872] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...995test # [ 2.380098] systemd[1]: Reloading...996second # [ 2.402096] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.997second # [ 2.406272] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...998second # [ 2.409991] systemd[1]: Reloading...999test # [ 2.556062] systemd[1]: Reloading finished in 174 ms.1000second # [ 2.580773] systemd[1]: Reloading finished in 171 ms.1001test # [ 2.579593] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1002test # [ 2.582119] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1003test # [ 2.584812] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004test # [ 2.586989] systemd[1]: Finished Mountpoints Configured in the Real Root.1005test # [ 2.589034] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1006second # [ 2.608848] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1007second # [ 2.610698] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1008second # [ 2.612776] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1009second # [ 2.615662] systemd[1]: Finished Mountpoints Configured in the Real Root.1010second # [ 2.617481] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1011second # [ 2.797759] fuse: init (API version 7.45)1012second # [ 2.818474] virtiofs virtio6: discovered new tag: nix-store1013second # [ 2.818995] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1014test # [ 2.816975] fuse: init (API version 7.45)1015second # [ 2.836110] virtiofs virtio7: discovered new tag: shared1016second # [ 2.836644] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1017second # [ 2.839408] (udev-worker)[100]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1018second # [ 2.841257] (udev-worker)[100]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1019test # [ 2.843593] virtiofs virtio6: discovered new tag: nix-store1020test # [ 2.844100] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1021second # [ 2.856532] virtiofs virtio8: discovered new tag: xchg1022second # [ 2.857060] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1023second # [ 2.857777] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1024second # [ 2.860385] systemd[1]: Stopped Virtual Console Setup.1025second # [ 2.861939] systemd[1]: Stopping Virtual Console Setup...1026second # [ 2.864817] systemd[1]: Starting Virtual Console Setup...1027test # [ 2.858487] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1028test # [ 2.862525] (udev-worker)[96]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1029second # [ 2.884152] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1030second # [ 2.884904] systemd[1]: Stopped Virtual Console Setup.1031second # [ 2.885409] systemd[1]: Starting Virtual Console Setup...1032test # [ 2.879685] virtiofs virtio7: discovered new tag: shared1033test # [ 2.880232] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1034test # [ 2.888930] virtiofs virtio8: discovered new tag: xchg1035test # [ 2.889431] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1036test # [ 2.885800] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1037test # [ 2.887177] systemd[1]: Stopped Virtual Console Setup.1038second # [ 2.898446] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones.1039test # [ 2.889680] systemd[1]: Stopping Virtual Console Setup...1040second # [ 2.900773] systemd[1]: Finished Virtual Console Setup.1041test # [ 2.890705] systemd[1]: Starting Virtual Console Setup...1042test # [ 2.906132] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1043test # [ 2.906843] systemd[1]: Stopped Virtual Console Setup.1044test # [ 2.910630] systemd[1]: Starting Virtual Console Setup...1045test # [ 2.927359] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones.1046test # [ 2.929917] systemd[1]: Finished Virtual Console Setup.1047second # [ 3.324937] systemd[1]: Mounting /sysroot/nix/.ro-store...1048test # [ 3.321374] systemd[1]: Mounting /sysroot/nix/.ro-store...1049second # [ 3.336311] systemd[1]: Mounting /sysroot/nix/.rw-store...1050test # [ 3.328775] systemd[1]: Mounting /sysroot/nix/.rw-store...1051second # [ 3.339766] systemd[1]: Mounting /sysroot/run...1052test # [ 3.340375] systemd[1]: Mounting /sysroot/run...1053second # [ 3.352292] systemd[1]: Mounting /sysroot/tmp/shared...1054test # [ 3.348281] systemd[1]: Mounting /sysroot/tmp/shared...1055second # [ 3.371216] systemd[1]: Mounting /sysroot/tmp/xchg...1056test # [ 3.376306] systemd[1]: Mounting /sysroot/tmp/xchg...1057second # [ 3.408653] systemd[1]: Mounted /sysroot/nix/.rw-store.1058second # [ 3.413263] systemd[1]: Mounted /sysroot/nix/.ro-store.1059test # [ 3.404284] systemd[1]: Mounted /sysroot/nix/.ro-store.1060second # [ 3.416981] systemd[1]: Mounted /sysroot/run.1061second # [ 3.418454] systemd[1]: Mounted /sysroot/tmp/shared.1062test # [ 3.411979] systemd[1]: Mounted /sysroot/nix/.rw-store.1063second # [ 3.423239] systemd[1]: Mounted /sysroot/tmp/xchg.1064test # [ 3.413247] systemd[1]: Mounted /sysroot/run.1065second # [ 3.428256] systemd[1]: Starting rw-sysroot-nix-store.service...1066test # [ 3.419749] systemd[1]: Mounted /sysroot/tmp/shared.1067test # [ 3.428071] systemd[1]: Starting rw-sysroot-nix-store.service...1068test # [ 3.430280] systemd[1]: Mounted /sysroot/tmp/xchg.1069second # [ 3.442936] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1070second # [ 3.444103] systemd[1]: Finished rw-sysroot-nix-store.service.1071test # [ 3.438745] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1072test # [ 3.439830] systemd[1]: Finished rw-sysroot-nix-store.service.1073second # [ 4.323161] systemd[1]: Mounting /sysroot/nix/store...1074test # [ 4.319716] systemd[1]: Mounting /sysroot/nix/store...1075second # [ 4.371489] systemd[1]: Mounted /sysroot/nix/store.1076second # [ 4.373969] systemd[1]: Reached target Initrd File Systems.1077second # [ 4.378343] systemd[1]: Starting Find NixOS closure...1078test # [ 4.372129] systemd[1]: Mounted /sysroot/nix/store.1079second # [ 4.385607] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1080test # [ 4.376124] systemd[1]: Reached target Initrd File Systems.1081test # [ 4.380390] systemd[1]: Starting Find NixOS closure...1082test # [ 4.388310] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1083second # [ 4.420362] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1084second # [ 4.421653] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1085second # [ 4.435663] systemd[1]: Finished Find NixOS closure.1086second # [ 4.438836] systemd[1]: Reached target Initrd Default Target.1087test # [ 4.427721] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1088test # [ 4.432344] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1089second # [ 4.444628] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1090test # [ 4.446087] systemd[1]: Finished Find NixOS closure.1091test # [ 4.448293] systemd[1]: Reached target Initrd Default Target.1092test # [ 4.452237] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1093second # [ 4.472354] systemd[1]: Stopped target Initrd Default Target.1094second # [ 4.473540] systemd[1]: Stopped target Basic System.1095second # [ 4.474324] systemd[1]: Stopped target Initrd Root Device.1096second # [ 4.475192] systemd[1]: Stopped target Path Units.1097second # [ 4.475901] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1098second # [ 4.478097] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1099second # [ 4.480310] systemd[1]: Stopped target Slice Units.1100second # [ 4.482830] systemd[1]: Stopped target Socket Units.1101second # [ 4.485369] systemd[1]: Stopped target System Initialization.1102second # [ 4.489365] systemd[1]: Stopped target Swaps.1103second # [ 4.489941] systemd[1]: Stopped target Timer Units.1104second # [ 4.491172] systemd[1]: dbus.socket: Deactivated successfully.1105test # [ 4.484680] systemd[1]: Stopped target Initrd Default Target.1106second # [ 4.495862] systemd[1]: Closed D-Bus System Message Bus Socket.1107second # [ 4.498772] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1108test # [ 4.488328] systemd[1]: Stopped target Basic System.1109second # [ 4.499605] systemd[1]: Stopped Find NixOS closure.1110test # [ 4.489162] systemd[1]: Stopped target Initrd Root Device.1111test # [ 4.490805] systemd[1]: Stopped target Path Units.1112second # [ 4.502357] systemd[1]: Starting rw-sysroot-nix-store.service...1113second # [ 4.503015] systemd[1]: systemd-sysctl.service: Deactivated successfully.1114second # [ 4.503719] systemd[1]: Stopped Apply Kernel Variables.1115test # [ 4.493138] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1116test # [ 4.494620] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1117second # [ 4.506501] systemd[1]: systemd-modules-load.service: Deactivated successfully.1118second # [ 4.507300] systemd[1]: Stopped Load Kernel Modules.1119second # [ 4.507853] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1120second # [ 4.508887] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1121second # [ 4.509853] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1122second # [ 4.510611] systemd[1]: Stopped Create System Files and Directories.1123test # [ 4.500127] systemd[1]: Stopped target Slice Units.1124second # [ 4.511253] systemd[1]: Stopped target Local File Systems.1125test # [ 4.500847] systemd[1]: Stopped target Socket Units.1126second # [ 4.511815] systemd[1]: Stopped target Preparation for Local File Systems.1127test # [ 4.501501] systemd[1]: Stopped target System Initialization.1128test # [ 4.502411] systemd[1]: Stopped target Swaps.1129test # [ 4.502993] systemd[1]: Stopped target Timer Units.1130test # [ 4.503615] systemd[1]: dbus.socket: Deactivated successfully.1131test # [ 4.507223] systemd[1]: Closed D-Bus System Message Bus Socket.1132second # [ 4.518162] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1133second # [ 4.518894] systemd[1]: Stopped Coldplug All udev Devices.1134second # [ 4.519498] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1135test # [ 4.509619] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1136test # [ 4.512985] systemd[1]: Stopped Find NixOS closure.1137second # [ 4.523880] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1138second # [ 4.524855] systemd[1]: Stopped Virtual Console Setup.1139second # [ 4.526366] systemd[1]: initrd-cleanup.service: Deactivated successfully.1140second # [ 4.526997] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1141test # [ 4.516323] systemd[1]: Starting rw-sysroot-nix-store.service...1142test # [ 4.518675] systemd[1]: systemd-sysctl.service: Deactivated successfully.1143second # [ 4.531213] systemd[1]: systemd-udevd.service: Deactivated successfully.1144test # [ 4.520837] systemd[1]: Stopped Apply Kernel Variables.1145second # [ 4.531840] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1146second # [ 4.532608] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1147second # [ 4.533332] systemd[1]: Closed udev Control Socket.1148second # [ 4.533770] systemd[1]: Starting Cleanup udev Database...1149second # [ 4.534249] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1150second # [ 4.534898] systemd[1]: Stopped Create Static Device Nodes in /dev.1151second # [ 4.535427] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1152test # [ 4.523328] systemd[1]: systemd-modules-load.service: Deactivated successfully.1153second # [ 4.537392] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1154second # [ 4.538027] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1155second # [ 4.538701] systemd[1]: Stopped Create List of Static Device Nodes.1156second # [ 4.539262] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1157second # [ 4.539915] systemd[1]: Finished rw-sysroot-nix-store.service.1158test # [ 4.526095] systemd[1]: Stopped Load Kernel Modules.1159test # [ 4.526785] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1160test # [ 4.527767] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1161test # [ 4.530597] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1162test # [ 4.531321] systemd[1]: Stopped Create System Files and Directories.1163test # [ 4.531916] systemd[1]: Stopped target Local File Systems.1164test # [ 4.537620] systemd[1]: Stopped target Preparation for Local File Systems.1165test # [ 4.540192] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1166test # [ 4.541741] systemd[1]: Stopped Coldplug All udev Devices.1167test # [ 4.544227] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1168test # [ 4.544942] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1169test # [ 4.545621] systemd[1]: Stopped Virtual Console Setup.1170test # [ 4.546758] systemd[1]: initrd-cleanup.service: Deactivated successfully.1171test # [ 4.548294] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1172test # [ 4.549940] systemd[1]: systemd-udevd.service: Deactivated successfully.1173test # [ 4.550768] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1174second # [ 4.562081] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1175test # [ 4.551557] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1176second # [ 4.562835] systemd[1]: Finished Cleanup udev Database.1177test # [ 4.552410] systemd[1]: Closed udev Control Socket.1178second # [ 4.563313] systemd[1]: Reached target Switch Root.1179test # [ 4.552891] systemd[1]: Starting Cleanup udev Database...1180test # [ 4.553398] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1181test # [ 4.554114] systemd[1]: Stopped Create Static Device Nodes in /dev.1182test # [ 4.554683] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1183test # [ 4.555424] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1184second # [ 4.567291] systemd[1]: Starting NixOS Activation...1185test # [ 4.559431] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1186test # [ 4.564140] systemd[1]: Stopped Create List of Static Device Nodes.1187test # [ 4.565002] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1188test # [ 4.565672] systemd[1]: Finished rw-sysroot-nix-store.service.1189test # [ 4.577463] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1190test # [ 4.580881] systemd[1]: Finished Cleanup udev Database.1191test # [ 4.581455] systemd[1]: Reached target Switch Root.1192test # [ 4.581943] systemd[1]: Starting NixOS Activation...1193second # [ 4.640284] initrd-nixos-activation-start[174]: booting system configuration /nix/store/1wi0m9k81yh1n7kws32my0qkss38ix8x-nixos-system-second-test1194second # [ 4.668773] initrd-nixos-activation-start[174]: running activation script...1195test # [ 4.661962] initrd-nixos-activation-start[174]: booting system configuration /nix/store/i605dag5qn4ryyazc1pw3cd538qrrn58-nixos-system-test-test1196test # [ 4.690636] initrd-nixos-activation-start[174]: running activation script...1197second # [ 4.881726] initrd-nixos-activation-start[197]: setting up /etc...1198test # [ 4.931657] initrd-nixos-activation-start[197]: setting up /etc...1199second # [ 4.981806] initrd-nixos-activation-start[197]: setting up age secrets...1200test # [ 5.029682] initrd-nixos-activation-start[197]: setting up age secrets...1201second # [ 5.075324] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1202second # [ 5.076204] systemd[1]: Finished NixOS Activation.1203second # [ 5.076974] systemd[1]: Starting Switch Root...1204second # [ 5.097158] systemd[1]: Switching root.1205test # [ 5.154902] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1206test # [ 5.156824] systemd[1]: Finished NixOS Activation.1207test # [ 5.157612] systemd[1]: Starting Switch Root...1208test # [ 5.176911] systemd[1]: Switching root.1209second # [ 5.262321] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1210test # [ 5.346400] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1211second # [ 5.786507] systemd[1]: systemd 261.3 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)1212second # [ 5.786621] systemd[1]: Detected virtualization qemu.1213second # [ 5.786680] systemd[1]: Detected architecture arm64.1214second # [ 5.786791] systemd[1]: Detected first boot.1215second # [ 5.789485] systemd[1]: Initializing machine ID from random generator.1216test # [ 5.883916] systemd[1]: systemd 261.3 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)1217test # [ 5.884010] systemd[1]: Detected virtualization qemu.1218test # [ 5.884069] systemd[1]: Detected architecture arm64.1219test # [ 5.884233] systemd[1]: Detected first boot.1220test # [ 5.896566] systemd[1]: Initializing machine ID from random generator.1221second # [ 5.935520] systemd[1]: bpf-restrict-fs: LSM BPF program attached1222second # [ 6.027983] systemd[1]: Applying preset policy.1223test # [ 6.030688] systemd[1]: bpf-restrict-fs: LSM BPF program attached1224test # [ 6.121315] systemd[1]: Applying preset policy.1225second # [ 6.199460] systemd[1]: Populated /etc with preset unit settings.1226test # [ 6.313658] systemd[1]: Populated /etc with preset unit settings.1227second # [ 6.416891] systemd[1]: initrd-switch-root.service: Deactivated successfully.1228second # [ 6.417605] systemd[1]: Stopped initrd-switch-root.service.1229second # [ 6.421245] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1230second # [ 6.424016] systemd[1]: Created slice Slice /system/getty.1231second # [ 6.425092] systemd[1]: Created slice User and Session Slice.1232second # [ 6.425724] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1233second # [ 6.428457] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1234second # [ 6.429013] systemd[1]: Expecting device /dev/hvc0...1235second # [ 6.429579] systemd[1]: Expecting device /dev/ttyAMA0...1236second # [ 6.432092] systemd[1]: Reached target Local Encrypted Volumes.1237second # [ 6.432605] systemd[1]: Stopped target initrd-fs.target.1238second # [ 6.433167] systemd[1]: Stopped target initrd-root-fs.target.1239second # [ 6.433749] systemd[1]: Stopped target initrd-switch-root.target.1240second # [ 6.436669] systemd[1]: Reached target Virtual Machines and Containers.1241second # [ 6.437174] systemd[1]: Reached target Path Units.1242second # [ 6.437736] systemd[1]: Reached target Remote File Systems.1243second # [ 6.440146] systemd[1]: Reached target Slice Units.1244second # [ 6.440644] systemd[1]: Reached target Swaps.1245second # [ 6.443584] systemd[1]: Listening on Query the User Interactively for a Password.1246second # [ 6.445724] systemd[1]: Listening on Process Core Dump Socket.1247second # [ 6.448583] systemd[1]: Listening on Credential Encryption/Decryption.1248second # [ 6.450981] systemd[1]: Listening on Factory Reset Management.1249second # [ 6.451566] systemd[1]: Listening on Hostname Service Socket.1250second # [ 6.455252] systemd[1]: Starting Journal Log Access Socket...1251second # [ 6.456546] systemd[1]: Listening on Journal Audit Socket.1252second # [ 6.459849] systemd[1]: Listening on Console Output Muting Service Socket.1253second # [ 6.460576] systemd[1]: Listening on Network Management Metrics Varlink Socket.1254second # [ 6.461337] systemd[1]: Listening on Network Management Varlink Socket.1255second # [ 6.464613] systemd[1]: Listening on Network Management Netlink Socket.1256second # [ 6.466555] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1257second # [ 6.467079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1258second # [ 6.467615] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1259second # [ 6.473234] systemd[1]: Listening on Disk Repartitioning Service Socket.1260second # [ 6.473799] systemd[1]: Listening on Resolve Monitor Varlink Socket.1261second # [ 6.475415] systemd[1]: Listening on Resolve Service Varlink Socket.1262second # [ 6.476005] systemd[1]: Listening on udev Control Socket.1263second # [ 6.476594] systemd[1]: Listening on udev Varlink Socket.1264second # [ 6.480329] systemd[1]: Mounting Huge Pages File System...1265second # [ 6.483025] systemd[1]: Mounting POSIX Message Queue File System...1266second # [ 6.494464] systemd[1]: Mounting Kernel Debug File System...1267second # [ 6.503346] systemd[1]: Mounting Kernel Trace File System...1268second # [ 6.512222] systemd[1]: Starting Create List of Static Device Nodes...1269second # [ 6.514569] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1270second # [ 6.534306] systemd[1]: Mounting Kernel Configuration File System...1271second # [ 6.534693] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1272second # [ 6.535004] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1273second # [ 6.535268] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1274test # [ 6.537139] systemd[1]: initrd-switch-root.service: Deactivated successfully.1275test # [ 6.537864] systemd[1]: Stopped initrd-switch-root.service.1276test # [ 6.541537] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1277test # [ 6.544464] systemd[1]: Created slice Slice /system/getty.1278test # [ 6.545515] systemd[1]: Created slice User and Session Slice.1279second # [ 6.558143] systemd[1]: Mounting FUSE Control File System...1280test # [ 6.547606] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1281test # [ 6.548191] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1282test # [ 6.548774] systemd[1]: Expecting device /dev/hvc0...1283second # [ 6.558666] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671284test # [ 6.549351] systemd[1]: Expecting device /dev/ttyAMA0...1285test # [ 6.549919] systemd[1]: Reached target Local Encrypted Volumes.1286test # [ 6.553567] systemd[1]: Stopped target initrd-fs.target.1287test # [ 6.555111] systemd[1]: Stopped target initrd-root-fs.target.1288test # [ 6.555594] systemd[1]: Stopped target initrd-switch-root.target.1289test # [ 6.556252] systemd[1]: Reached target Virtual Machines and Containers.1290test # [ 6.556802] systemd[1]: Reached target Path Units.1291test # [ 6.557356] systemd[1]: Reached target Remote File Systems.1292test # [ 6.557920] systemd[1]: Reached target Slice Units.1293test # [ 6.561639] systemd[1]: Reached target Swaps.1294test # [ 6.564678] systemd[1]: Listening on Query the User Interactively for a Password.1295test # [ 6.567495] systemd[1]: Listening on Process Core Dump Socket.1296test # [ 6.569122] systemd[1]: Listening on Credential Encryption/Decryption.1297second # [ 6.580784] systemd[1]: Starting Journal Service...1298test # [ 6.572078] systemd[1]: Listening on Factory Reset Management.1299test # [ 6.572649] systemd[1]: Listening on Hostname Service Socket.1300test # [ 6.576326] systemd[1]: Starting Journal Log Access Socket...1301test # [ 6.577543] systemd[1]: Listening on Journal Audit Socket.1302test # [ 6.581348] systemd[1]: Listening on Console Output Muting Service Socket.1303second # [ 6.592032] systemd[1]: Starting Load Kernel Modules...1304test # [ 6.583308] systemd[1]: Listening on Network Management Metrics Varlink Socket.1305test # [ 6.584528] systemd[1]: Listening on Network Management Varlink Socket.1306test # [ 6.585700] systemd[1]: Listening on Network Management Netlink Socket.1307test # [ 6.588561] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1308test # [ 6.589043] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1309test # [ 6.589606] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1310second # [ 6.604987] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1311test # [ 6.595840] systemd[1]: Listening on Disk Repartitioning Service Socket.1312test # [ 6.596401] systemd[1]: Listening on Resolve Monitor Varlink Socket.1313test # [ 6.597003] systemd[1]: Listening on Resolve Service Varlink Socket.1314test # [ 6.597593] systemd[1]: Listening on udev Control Socket.1315test # [ 6.600781] systemd[1]: Listening on udev Varlink Socket.1316test # [ 6.603472] systemd[1]: Mounting Huge Pages File System...1317test # [ 6.605695] systemd[1]: Mounting POSIX Message Queue File System...1318second # [ 6.623692] systemd[1]: Starting Remount Root and Kernel File Systems...1319second # [ 6.624105] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1320test # [ 6.617858] systemd[1]: Mounting Kernel Debug File System...1321test # [ 6.623841] systemd[1]: Mounting Kernel Trace File System...1322test # [ 6.629524] systemd[1]: Starting Create List of Static Device Nodes...1323second # [ 6.639783] systemd[1]: Starting Coldplug All udev Devices...1324second # [ 6.641705] systemd[1]: Listening on Journal Log Access Socket.1325test # [ 6.634108] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1326second # [ 6.646581] systemd[1]: Mounted Huge Pages File System.1327second # [ 6.647134] systemd[1]: Mounted POSIX Message Queue File System.1328second # [ 6.647526] systemd[1]: Mounted Kernel Debug File System.1329second # [ 6.647897] systemd[1]: Mounted Kernel Trace File System.1330test # [ 6.643363] systemd[1]: Mounting Kernel Configuration File System...1331second # [ 6.656474] systemd[1]: Finished Create List of Static Device Nodes.1332test # [ 6.646629] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1333test # [ 6.651891] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1334test # [ 6.652381] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1335second # [ 6.667292] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1336second # [ 6.680377] systemd[1]: Finished Load Kernel Modules.1337test # [ 6.672957] systemd[1]: Mounting FUSE Control File System...1338test # [ 6.673365] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671339second # [ 6.684289] systemd-journald[300]: Collecting audit messages is enabled.1340second # [ 6.690420] systemd[1]: Starting Firewall...1341second # [ 6.698215] systemd[1]: Queued start job for default target Multi-User System.1342second # [ 6.699330] systemd[1]: systemd-journald.service: Deactivated successfully.1343second # [ 6.700896] systemd-modules-load[301]: Module 'atkbd' is built in1344second # [ 6.701826] systemd-modules-load[301]: Module 'ctr' is built in1345second # [ 6.702458] systemd-modules-load[301]: Module 'loop' is built in1346test # [ 6.705338] systemd[1]: Starting Journal Service...1347second # [ 6.715724] systemd[1]: Starting Apply Kernel Variables...1348second # [ 6.716237] systemd[1]: Started Journal Service.1349second # [ 6.713208] systemd[1]: Mounted Kernel Configuration File System.1350second # [ 6.722264] EXT4-fs (vda): re-mounted ab648fab-26fb-4371-acdd-33617e288371.1351test # [ 6.717590] systemd[1]: Starting Load Kernel Modules...1352test # [ 6.730631] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1353test # [ 6.739569] systemd[1]: Starting Remount Root and Kernel File Systems...1354test # [ 6.739960] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1355second # [ 6.749558] systemd[1]: Finished Remount Root and Kernel File Systems.1356second # [ 6.755816] systemd-oomd[302]: No swap; memory pressure usage will be degraded1357test # [ 6.754140] systemd[1]: Starting Coldplug All udev Devices...1358test # [ 6.756083] systemd[1]: Listening on Journal Log Access Socket.1359test # [ 6.756510] systemd[1]: Mounted Huge Pages File System.1360test # [ 6.756873] systemd[1]: Mounted POSIX Message Queue File System.1361test # [ 6.757199] systemd[1]: Mounted Kernel Debug File System.1362test # [ 6.757525] systemd[1]: Mounted Kernel Trace File System.1363second # [ 6.770203] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1364test # [ 6.766320] systemd[1]: Finished Create List of Static Device Nodes.1365test # [ 6.766851] systemd[1]: Mounted Kernel Configuration File System.1366test # [ 6.772075] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1367second # [ 6.779856] systemd[1]: Mounted FUSE Control File System.1368second # [ 6.780740] systemd[1]: Listening on Disk Image Download Service Socket.1369second # [ 6.787519] systemd[1]: Starting Flush Journal to Persistent Storage...1370second # [ 6.789208] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1371second # [ 6.800091] systemd[1]: Starting Load/Save OS Random Seed...1372second # [ 6.800679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1373test # [ 6.823486] systemd[1]: Mounted FUSE Control File System.1374test # [ 6.826056] systemd[1]: Finished Load Kernel Modules.1375test # [ 6.830252] systemd[1]: Starting Firewall...1376test # [ 6.835245] systemd[1]: Starting Apply Kernel Variables...1377test # [ 6.848462] systemd-journald[322]: Collecting audit messages is enabled.1378test # [ 6.863132] systemd[1]: Queued start job for default target Multi-User System.1379test # [ 6.868112] systemd[1]: Started Journal Service.1380second # [ 6.877366] systemd[1]: Finished Load/Save OS Random Seed.1381second # [ 6.877954] systemd[1]: Reached target First Boot Complete.1382second # [ 6.885515] systemd-journald[300]: Received client request to flush runtime journal.1383test # [ 6.883186] EXT4-fs (vda): re-mounted 1b9e8164-0260-4be9-9c99-5d1b48d422da.1384second # [ 6.925625] systemd[1]: Finished Flush Journal to Persistent Storage.1385second # [ 6.934201] systemd[1]: Finished Apply Kernel Variables.1386second # [ 6.937477] systemd[1]: Starting Network Name Resolution...1387second # [ 6.938219] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1388second # [ 6.945456] systemd[1]: Starting Create Static Device Nodes in /dev...1389second # [ 7.094184] systemd-resolved[330]: Positive Trust Anchors:1390second # [ 7.094802] systemd-resolved[330]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1391second # [ 7.095825] systemd-resolved[330]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161392second # [ 7.100993] systemd-resolved[330]: 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 test1393second # [ 7.206104] systemd[1]: Finished Create Static Device Nodes in /dev.1394second # [ 7.206760] systemd[1]: Reached target Preparation for Local File Systems.1395second # [ 7.217079] systemd[1]: Starting Rule-based Manager for Device Events and Files...1396second # [ 7.268068] systemd[1]: Finished Coldplug All udev Devices.1397second # [ 7.275623] systemd-resolved[330]: Using system hostname 'second'.1398second # [ 7.281331] systemd[1]: Started Network Name Resolution.1399second # [ 7.313249] systemd-udevd[341]: Using default interface naming scheme 'v261'.1400second # [ 7.388117] systemd[1]: Started Rule-based Manager for Device Events and Files.1401second # [ 7.420820] systemd[1]: Mounting /run/wrappers...1402test # [ 6.865939] systemd[1]: systemd-journald.service: Deactivated successfully.1403test # [ 7.494817] systemd-journald[322]: Received client request to flush runtime journal.1404second # [ 7.512364] systemd[1]: Mounted /run/wrappers.1405second # [ 7.512885] systemd[1]: Reached target Local File Systems.1406second # [ 7.517738] systemd[1]: Listening on Boot Loader Control Service Socket.1407second # [ 7.521771] systemd[1]: Starting register-nix-paths.service...1408second # [ 7.525490] systemd[1]: Starting Create SUID/SGID Wrappers...1409second # [ 7.526045] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1410second # [ 7.529691] systemd[1]: Starting Save Transient machine-id to Disk...1411test # [ 7.522633] systemd-modules-load[323]: Module 'atkbd' is built in1412test # [ 7.523362] systemd-modules-load[323]: Module 'ctr' is built in1413test # [ 7.523991] systemd-modules-load[323]: Module 'loop' is built in1414test # [ 7.529119] systemd-oomd[324]: No swap; memory pressure usage will be degraded1415test # [ 7.530058] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1416test # [ 7.530758] systemd[1]: Finished Remount Root and Kernel File Systems.1417test # [ 7.531445] systemd[1]: Listening on Disk Image Download Service Socket.1418test # [ 7.540313] systemd[1]: Starting Flush Journal to Persistent Storage...1419test # [ 7.541028] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1420test # [ 7.542068] systemd[1]: Starting Load/Save OS Random Seed...1421test # [ 7.542720] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1422test # [ 7.543771] systemd[1]: Finished Load/Save OS Random Seed.1423second # [ 7.558472] systemd[1]: Starting Create System Files and Directories...1424test # [ 7.550873] systemd[1]: Reached target First Boot Complete.1425second # [ 7.561399] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1426test # [ 7.551493] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1427test # [ 7.556989] systemd[1]: Starting Create Static Device Nodes in /dev...1428test # [ 7.557701] systemd[1]: Finished Apply Kernel Variables.1429test # [ 7.558298] systemd[1]: Starting Network Name Resolution...1430second # [ 7.569150] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1431test # [ 7.559086] systemd-resolved[341]: Positive Trust Anchors:1432test # [ 7.559588] systemd-resolved[341]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1433test # [ 7.568773] systemd-resolved[341]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161434test # [ 7.569655] systemd-resolved[341]: 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 test1435test # [ 7.592519] systemd[1]: Finished Create Static Device Nodes in /dev.1436test # [ 7.593159] systemd[1]: Reached target Preparation for Local File Systems.1437test # [ 7.597384] systemd[1]: Starting Rule-based Manager for Device Events and Files...1438test # [ 7.598068] systemd[1]: Finished Coldplug All udev Devices.1439test # [ 7.598642] systemd-resolved[341]: Using system hostname 'test'.1440test # [ 7.599181] systemd[1]: Started Network Name Resolution.1441test # [ 7.599855] systemd-udevd[348]: Using default interface naming scheme 'v261'.1442test # [ 7.603942] systemd[1]: Started Rule-based Manager for Device Events and Files.1443test # [ 7.606010] systemd[1]: Mounting /run/wrappers...1444test # [ 7.606472] systemd[1]: Finished Flush Journal to Persistent Storage.1445test # [ 7.620949] systemd[1]: Mounted /run/wrappers.1446test # [ 7.621615] systemd[1]: Reached target Local File Systems.1447test # [ 7.627177] systemd[1]: Listening on Boot Loader Control Service Socket.1448test # [ 7.630995] systemd[1]: Starting register-nix-paths.service...1449test # [ 7.634269] systemd[1]: Starting Create SUID/SGID Wrappers...1450test # [ 7.638958] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1451test # [ 7.651605] systemd[1]: Starting Save Transient machine-id to Disk...1452test # [ 7.671428] systemd[1]: Starting Create System Files and Directories...1453test # [ 7.672205] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1454test # [ 7.673217] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1455second # [ 7.704805] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1456second # [ 7.713658] systemd[1]: Finished Save Transient machine-id to Disk.1457second # [ 7.716522] systemd[1]: Finished Create System Files and Directories.1458second # [ 7.729848] systemd[1]: Starting Rebuild Journal Catalog...1459second # [ 7.738305] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1460test # [ 7.760943] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1461test # [ 7.767081] systemd[1]: Finished Save Transient machine-id to Disk.1462second # [ 7.818494] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1463test # [ 7.818495] systemd[1]: Finished Create System Files and Directories.1464test # [ 7.829976] systemd[1]: Starting Rebuild Journal Catalog...1465test # [ 7.838313] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1466second # [ 7.901430] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1467test # [ 7.906123] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1468second # [ 7.927331] systemd[1]: Finished Rebuild Journal Catalog.1469second # [ 7.931459] systemd[1]: Starting Update is Completed...1470second # [ 7.956767] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1471test # [ 7.974187] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1472second # [ 7.995715] systemd[1]: Finished Update is Completed.1473test # [ 8.012069] systemd[1]: Finished Rebuild Journal Catalog.1474test # [ 8.012662] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1475test # [ 8.018503] systemd[1]: Starting Update is Completed...1476test # [ 8.086302] systemd[1]: Finished Update is Completed.1477second # [ 8.238782] (udev-worker)[375]: Network interface NamePolicy= disabled on kernel command line.1478second # [ 8.242640] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.1479test # [ 8.273282] (udev-worker)[398]: Network interface NamePolicy= disabled on kernel command line.1480test # [ 8.295210] (udev-worker)[396]: Network interface NamePolicy= disabled on kernel command line.1481second # [ 8.439477] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1482second # [ 8.444100] systemd[1]: Finished Create SUID/SGID Wrappers.1483second # [ 8.473601] mousedev: PS/2 mouse device common for all mice1484second # [ 8.469938] systemd[1]: Finished register-nix-paths.service.1485second # [ 8.470489] systemd[1]: Reached target System Initialization.1486second # [ 8.471015] systemd[1]: Started Discard unused filesystem blocks once a week.1487second # [ 8.471644] systemd[1]: Started Daily Cleanup of Temporary Directories.1488second # [ 8.477601] systemd[1]: Reached target Timer Units.1489second # [ 8.478115] systemd[1]: Listening on D-Bus System Message Bus Socket.1490second # [ 8.478686] systemd[1]: Listening on Nix Daemon Socket.1491second # [ 8.484497] systemd[1]: Listening on Authorization Manager Agent Helper.1492test # [ 8.474118] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1493second # [ 8.485186] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1494test # [ 8.474840] systemd[1]: Finished Create SUID/SGID Wrappers.1495second # [ 8.485926] systemd[1]: Reached target Socket Units.1496second # [ 8.491207] systemd[1]: Starting D-Bus System Message Bus...1497test # [ 8.530811] systemd[1]: Finished register-nix-paths.service.1498test # [ 8.531415] systemd[1]: Reached target System Initialization.1499test # [ 8.531929] systemd[1]: Started Discard unused filesystem blocks once a week.1500test # [ 8.535158] systemd[1]: Started Daily Cleanup of Temporary Directories.1501test # [ 8.535757] systemd[1]: Reached target Timer Units.1502test # [ 8.539805] systemd[1]: Listening on D-Bus System Message Bus Socket.1503test # [ 8.542954] systemd[1]: Listening on Nix Daemon Socket.1504test # [ 8.547505] systemd[1]: Listening on Authorization Manager Agent Helper.1505test # [ 8.548185] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1506test # [ 8.548906] systemd[1]: Reached target Socket Units.1507test # [ 8.552315] systemd[1]: Starting D-Bus System Message Bus...1508test # [ 8.583132] mousedev: PS/2 mouse device common for all mice1509second # [ 8.617193] dbus-broker-launch[495]: Looking up NSS user entry for 'systemd-timesync'...1510second # [ 8.625183] dbus-broker-launch[495]: NSS returned no entry for 'systemd-timesync'1511second # [ 8.625890] dbus-broker-launch[495]: Invalid user-name in /nix/store/g9bl0w6b8vdr2n1hq1fcll7aw9sfyz2v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1512second # [ 8.662387] dbus-broker-launch[495]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1513second # [ 8.666644] dbus-broker-launch[495]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1514second # [ 8.671898] dbus-broker-launch[495]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1515second # [ 8.679039] dbus-broker-launch[495]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1516second # [ 8.686429] systemd[1]: Started D-Bus System Message Bus.1517second # [ 8.686952] systemd[1]: Reached target Basic System.1518second # [ 8.687424] systemd[1]: Starting Network Manager Script Dispatcher Service...1519second # [ 8.694129] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1520test # [ 8.686442] dbus-broker-launch[519]: Looking up NSS user entry for 'systemd-timesync'...1521second # [ 8.704240] systemd[1]: Started backdoor.service.1522test # [ 8.694486] dbus-broker-launch[519]: NSS returned no entry for 'systemd-timesync'1523test # [ 8.695182] dbus-broker-launch[519]: Invalid user-name in /nix/store/g9bl0w6b8vdr2n1hq1fcll7aw9sfyz2v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1524second # [ 8.717198] systemd[1]: Starting Import lastlog data into lastlog2 database...1525second # [ 8.739757] dbus-broker-launch[495]: Ready1526test # [ 8.731163] dbus-broker-launch[519]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1527second # [ 8.746444] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1528test # [ 8.735767] dbus-broker-launch[519]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1529test # [ 8.740272] dbus-broker-launch[519]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1530test # [ 8.748063] dbus-broker-launch[519]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1531test # [ 8.754638] systemd[1]: Started D-Bus System Message Bus.1532test # [ 8.755147] systemd[1]: Reached target Basic System.1533test # [ 8.755595] systemd[1]: Starting Network Manager Script Dispatcher Service...1534test # [ 8.762045] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1535second # [ 8.773315] systemd[1]: Starting Post-Boot Actions...1536test # [ 8.777626] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1537second # [ 8.790584] systemd[1]: Started Reset console on configuration changes.1538test # [ 8.788318] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1539test # [ 8.815105] systemd[1]: Started backdoor.service.1540test # [ 8.820236] dbus-broker-launch[519]: Ready1541test # [ 8.844525] systemd[1]: Starting Import lastlog data into lastlog2 database...1542second # [ 8.863700] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[514]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1543test # [ 8.855640] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1544second # [ 8.883586] systemd[1]: Finished Post-Boot Actions.1545second # connecting to host...1546test # [ 8.898168] systemd[1]: Starting Post-Boot Actions...1547second # [ 8.920922] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1548second # [ 8.929326] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1549test # [ 8.919569] systemd[1]: Started Reset console on configuration changes.1550test # [ 8.932522] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[536]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1551second # [ 8.951499] systemd[1]: Started Name Service Cache Daemon (nsncd).1552second # [ 8.957542] systemd[1]: Reached target Host and Network Name Lookups.1553second # [ 8.958174] systemd[1]: Reached target User and Group Name Lookups.1554second # [ 8.958926] nsncd[518]: Sep 30 11:06:10.768 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1555second # [ 8.972133] systemd[1]: Starting User Login Management...1556second # [ 8.980322] systemd[1]: Finished Firewall.1557second # [ 8.980959] systemd[1]: Reached target Preparation for Network.1558second # [ 8.981501] systemd[1]: Listening on Network Management Resolve Hook Socket.1559test # [ 8.977183] nj6y3zgv8ppidbs8s4xlaglnzsav95yb-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one1560second # [ 8.992228] systemd[1]: Starting Network Manager...1561test # [ 8.989548] w20vy84jlc9q97pm5z31jcyz4xpvlr4j-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open1562test # [ 9.001603] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1563second # [ 9.014867] systemd[1]: Starting Network Management...1564test # [ 9.006291] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1565second # [ 9.026607] systemd[1]: Finished Import lastlog data into lastlog2 database.1566test # connecting to host...1567test # [ 9.022748] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1568test # [ 9.028853] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1569test # [ 9.038089] systemd[1]: Finished Firewall.1570test # [ 9.038541] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1571test # [ 9.044579] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1572second # [ 9.062656] systemd[1]: Started Network Manager Script Dispatcher Service.1573test # [ 9.077943] systemd[1]: Reached target Preparation for Network.1574test # [ 9.078548] systemd[1]: Listening on Network Management Resolve Hook Socket.1575test: Guest shell says: b'Spawning backdoor root shell...\n'1576test # [ 9.090752] systemd[1]: Starting Network Manager...1577test # [ 9.102307] systemd[1]: Starting Network Management...1578test # [ 9.102996] nsncd[545]: Sep 30 11:06:10.923 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1579test # [ 9.114319] systemd[1]: Started Name Service Cache Daemon (nsncd).1580test # [ 9.125084] systemd[1]: Reached target Host and Network Name Lookups.1581test # [ 9.125657] systemd[1]: Reached target User and Group Name Lookups.1582test: connected to guest root shell1583test: (connecting took 9.44 seconds)1584test: (finished: waiting for the VM to finish booting, in 9.44 seconds)1585test # [ 9.149808] systemd[1]: Starting User Login Management...1586test # [ 9.155221] systemd[1]: Finished Import lastlog data into lastlog2 database.1587test # [ 9.155833] systemd[1]: Started Network Manager Script Dispatcher Service.1588test # [ 9.169956] systemd[1]: Finished Post-Boot Actions.1589second # [ 9.181243] systemd-logind[547]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1590second # [ 9.184136] systemd-logind[547]: Watching system buttons on /dev/input/event0 (gpio-keys)1591second # [ 9.184862] systemd-logind[547]: New seat seat0.1592second # [ 9.189620] systemd[1]: Started User Login Management.1593second # [ 9.192206] systemd[1]: Starting linger-users.service...1594second # [ 9.309642] systemd[1]: linger-users.service: Deactivated successfully.1595second # [ 9.311567] systemd[1]: Finished linger-users.service.1596second # [ 9.319247] cfg80211: Loading compiled-in X.509 certificates for regulatory database1597test # [ 9.333101] systemd-logind[593]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1598test # [ 9.336386] systemd-logind[593]: Watching system buttons on /dev/input/event0 (gpio-keys)1599test # [ 9.338912] systemd-logind[593]: New seat seat0.1600test # [ 9.342013] systemd[1]: Started User Login Management.1601test # [ 9.349189] systemd[1]: Starting linger-users.service...1602second # [ 9.397384] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1603second # [ 9.400997] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1604second # [ 9.425980] systemd[1]: Starting Hostname Service...1605second # [ 9.451926] systemd-networkd[557]: /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.1606second # [ 9.459076] systemd-networkd[557]: /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.1607second # [ 9.463613] systemd-networkd[557]: lo: Link UP1608second # [ 9.465233] systemd-networkd[557]: lo: Gained carrier1609test # [ 9.461692] cfg80211: Loading compiled-in X.509 certificates for regulatory database1610second # [ 9.469124] systemd[1]: Started Network Management.1611second # [ 9.476615] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1612second # [ 9.477321] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1613second # [ 9.478596] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1614second # [ 9.479308] systemd-networkd[557]: eth1: Link UP1615second # [ 9.490068] systemd-networkd[557]: eth1: Gained carrier1616second # [ 9.490572] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1617test # [ 9.490809] systemd[1]: linger-users.service: Deactivated successfully.1618test # [ 9.491518] systemd[1]: Finished linger-users.service.1619test # [ 9.521292] systemd[1]: Starting Hostname Service...1620test # [ 9.539265] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1621test # [ 9.539764] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1622second # [ 9.579635] systemd[1]: Started Hostname Service.1623second # [ 9.591623] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1624test # [ 9.580378] systemd-networkd[578]: /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.1625test # [ 9.589234] systemd-networkd[578]: /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.1626test # [ 9.590944] systemd-networkd[578]: lo: Link UP1627test # [ 9.591365] systemd-networkd[578]: lo: Gained carrier1628test # [ 9.602919] systemd[1]: Started Network Management.1629test # [ 9.603421] systemd-networkd[578]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1630test # [ 9.614497] systemd-networkd[578]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1631test # [ 9.615239] systemd-networkd[578]: eth1: Link UP1632test # [ 9.615643] systemd-networkd[578]: eth1: Gained carrier1633test # [ 9.620836] systemd-networkd[578]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1634test # [ 9.622179] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1635second # [ 9.654085] systemd-networkd[557]: eth0: Link UP1636second # [ 9.656513] systemd-networkd[557]: eth0: Gained carrier1637second # [ 9.676253] systemd[1]: Started Network Manager.1638second # [ 9.678642] systemd[1]: Reached target Network.1639test # [ 9.673711] systemd[1]: Started Hostname Service.1640second # [ 9.684531] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1641second # [ 9.692496] systemd[1]: Starting Permit User Sessions...1642test # [ 9.697799] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1643test # [ 9.743004] systemd-networkd[578]: eth0: Link UP1644test # [ 9.743505] systemd-networkd[578]: eth0: Gained carrier1645test # [ 9.765514] systemd[1]: Started Network Manager.1646test # [ 9.767200] systemd[1]: Reached target Network.1647test # [ 9.774568] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1648test # [ 9.796837] systemd[1]: Starting Permit User Sessions...1649second # [ 9.819070] systemd[1]: Finished Permit User Sessions.1650second # [ 9.828570] systemd[1]: Started Getty on tty1.1651second # [ 9.830647] systemd[1]: Reached target Login Prompts.1652second # [ 9.875547] systemd-resolved[330]: eth0: Bus client set default route setting: yes1653second # [ 9.878890] systemd-resolved[330]: eth0: Bus client set DNS server list to: 10.0.2.31654test # [ 9.899843] systemd[1]: Finished Permit User Sessions.1655test # [ 9.913526] systemd[1]: Started Getty on tty1.1656test # [ 9.915860] systemd[1]: Reached target Login Prompts.1657second # [ 9.951079] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1658second # [ 9.956046] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1659test # [ 9.986761] systemd-resolved[341]: eth0: Bus client set default route setting: yes1660test # [ 9.991193] systemd-resolved[341]: eth0: Bus client set DNS server list to: 10.0.2.31661test # [ 10.024179] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1662test # [ 10.029003] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1663second # [ 10.047139] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31664test # [ 10.188680] systemd[1]: Reached target Multi-User System.1665test # [ 10.191730] systemd[1]: Startup finished in 726ms (kernel) + 4.686s (initrd) + 4.777s (userspace) = 10.190s.1666test # [ 10.206627] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31667second # [ 10.286197] systemd[1]: Reached target Multi-User System.1668second # [ 10.288539] systemd[1]: Startup finished in 735ms (kernel) + 4.591s (initrd) + 4.960s (userspace) = 10.287s.1669second # [ 10.414161] systemd[1]: Starting Virtual Console Setup...1670test: (finished: waiting for unit NetworkManager.service, in 10.70 seconds)1671test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1672second # [ 10.430383] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1673second # [ 10.432430] systemd[1]: Stopped Virtual Console Setup.1674second # [ 10.439272] systemd[1]: Starting Virtual Console Setup...1675test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.02 seconds)1676test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open1677second # [ 10.455253] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1678second # [ 10.478252] systemd-logind[547]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1679test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.09 seconds)1680test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1681second # [ 10.560386] systemd-vconsole-setup[635]: Configuration of first virtual console was skipped, ignoring remaining ones.1682second # [ 10.562955] systemd[1]: Finished Virtual Console Setup.1683test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.03 seconds)1684(finished: run the VM test script, in 11.31 seconds)1685test # [ 10.632120] systemd[1]: Starting Virtual Console Setup...1686test script finished in 11.41s1687cleanup1688kill QemuMachine (pid 47)1689second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1690second # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1691test # [ 10.660311] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1692second # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1693second # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1694test # [ 10.668401] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1695test # [ 10.670963] systemd[1]: Stopped Virtual Console Setup.1696test # [ 10.676831] systemd[1]: Starting Virtual Console Setup...1697test # [ 10.681639] systemd-logind[593]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1698kill QemuMachine (pid 48)1699test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1700test # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1701test # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1702test # [2026-09-30T11:06:12Z INFO virtiofsd] Client disconnected, shutting down1703(finished: cleanup, in 0.23 seconds)