vm-test-run-wifi
checks.aarch64-linux.wifi
· build #999
· raw
1build01: building b27d3p963xsanli4lfycliz8zdkqp6fj-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 vm14second: QEMU running (pid 47)15second # Disk image does not exist, creating the virtualisation disk image...16test # Disk image does not exist, creating the virtualisation disk image...17test: QEMU running (pid 48)18test # Formatting '/build/vm-state-test/tmp.gcffrqzuLW', fmt=raw size=107374182419second # Formatting '/build/vm-state-second/tmp.MwAWf58IrQ', fmt=raw size=107374182420test # mke2fs 1.47.4 (6-Mar-2025)21(finished: start all VMs, in 0.41 seconds)22test # Discarding device blocks: 0/262144 done23test: waiting for unit NetworkManager.service24test # Creating filesystem with 262144 4k blocks and 65536 inodes25test: waiting for the VM to finish booting26test # Filesystem UUID: 401b84c3-d12b-4087-8e20-e606cb9d1b3f27second # mke2fs 1.47.4 (6-Mar-2025)28second # Discarding device blocks: 0/262144 done29test # Superblock backups stored on blocks:30second # Creating filesystem with 262144 4k blocks and 65536 inodes31test # 32768, 98304, 163840, 22937632second # Filesystem UUID: bdd3a468-9ee5-439f-adf9-a2b8c867001533test # 34second # Superblock backups stored on blocks:35test # Allocating group tables: 0/8 done36second # 32768, 98304, 163840, 22937637test # Writing inode tables: 0/8 done38second # 39test # Creating journal (8192 blocks): done40second # Allocating group tables: 0/8 done41test # Writing superblocks and filesystem accounting information: 0/8 done42second # Writing inode tables: 0/8 done43test # 44second # Creating journal (8192 blocks): done45test # Virtualisation disk image created.46second # Writing superblocks and filesystem accounting information: 0/8 done47test # Starting virtiofs daemons...48second # 49test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)50second # Virtualisation disk image created.51test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether52second # Starting virtiofs daemons...53test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...54second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)55test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)56second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether57test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)59test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...60second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether61test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)62second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...63test # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...65test # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...66second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests68second # [2026-10-15T15:14:24Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether69test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests70second # [2026-10-15T15:14:24Z INFO virtiofsd] Waiting for vhost-user socket connection...71test # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests72second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests73second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests74second # [2026-10-15T15:14:24Z INFO virtiofsd] Client connected, servicing requests75test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76test # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179172517277test # [ 0.000000] KASLR enabled78test # [ 0.000000] random: crng init done79test # [ 0.000000] Machine model: linux,dummy-virt80test # [ 0.000000] efi: UEFI not found.81test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT82test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]83test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]84test # [ 0.000000] Zone ranges:85test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]86test # [ 0.000000] DMA32 empty87test # [ 0.000000] Normal empty88second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]89test # [ 0.000000] Device empty90test # [ 0.000000] Movable zone start for each node91test # [ 0.000000] Early memory node ranges92second # [ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179172517293test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]94second # [ 0.000000] KASLR enabled95second # [ 0.000000] random: crng init done96test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]97second # [ 0.000000] Machine model: linux,dummy-virt98test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000099second # [ 0.000000] efi: UEFI not found.100test # [ 0.000000] psci: probing for conduit method from DT.101second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT102test # [ 0.000000] psci: PSCIv1.3 detected in firmware.103second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]104test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs105test # [ 0.000000] psci: Trusted OS migration not required106second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]107second # [ 0.000000] Zone ranges:108test # [ 0.000000] psci: SMC Calling Convention v1.1109second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]110second # [ 0.000000] DMA32 empty111test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)112second # [ 0.000000] Normal empty113second # [ 0.000000] Device empty114test # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296115test # [ 0.000000] Detected PIPT I-cache on CPU0116test # [ 0.000000] CPU features: detected: GICv3 CPU interface117test # [ 0.000000] CPU features: detected: Spectre-v4118test # [ 0.000000] CPU features: detected: Spectre-BHB119test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR120test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)121test # [ 0.000000] CPU features: detected: ARM erratum 1418040122second # [ 0.000000] Movable zone start for each node123second # [ 0.000000] Early memory node ranges124second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]125second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]126second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000127second # [ 0.000000] psci: probing for conduit method from DT.128second # [ 0.000000] psci: PSCIv1.3 detected in firmware.129second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs130test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion131test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing132test # [ 0.000000] alternatives: applying boot alternatives133test # [ 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/azdvna4rsvl2gjx3dfghyhzs5wgiz9xl-nixos-system-test-test/init regInfo=/nix/.ro-store/wr5a7ys7gal7gbhpj2iqy00mp1pnp74l-closure-info/registration console=ttyAMA0,115200n8 console=tty0134second # [ 0.000000] psci: Trusted OS migration not required135second # [ 0.000000] psci: SMC Calling Convention v1.1136second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)137second # [ 0.000000] percpu: Embedded 76 pages/cpu s186904 r8192 d116200 u311296138second # [ 0.000000] Detected PIPT I-cache on CPU0139second # [ 0.000000] CPU features: detected: GICv3 CPU interface140second # [ 0.000000] CPU features: detected: Spectre-v4141second # [ 0.000000] CPU features: detected: Spectre-BHB142second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR143second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)144test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/wr5a7ys7gal7gbhpj2iqy00mp1pnp74l-closure-info/registration", will be passed to user space.145test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes146test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)147test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)148test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB149test # [ 0.000000] software IO TLB: area num 1.150second # [ 0.000000] CPU features: detected: ARM erratum 1418040151second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion152second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing153second # [ 0.000000] alternatives: applying boot alternatives154test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)155test # [ 0.000000] Fallback order for Node 0: 0156second # [ 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/q8s0zh41js3zp5lqqxwmmlfxn7c36mza-nixos-system-second-test/init regInfo=/nix/.ro-store/frwi3j7fvp1jyfj2b977hzsbs9fizhgw-closure-info/registration console=ttyAMA0,115200n8 console=tty0157test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144158second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/frwi3j7fvp1jyfj2b977hzsbs9fizhgw-closure-info/registration", will be passed to user space.159test # [ 0.000000] Policy zone: DMA160test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off161test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1162second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes163test # [ 0.000000] allocated 2097152 bytes of page_ext164test # [ 0.000000] ftrace: allocating 75049 entries in 294 pages165second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)166second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)167second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168second # [ 0.000000] software IO TLB: area num 1.169test # [ 0.000000] ftrace: allocated 294 pages with 4 groups170test # [ 0.000000] rcu: Hierarchical RCU implementation.171test # [ 0.000000] rcu: RCU event tracing is enabled.172test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.173test # [ 0.000000] Trampoline variant of Tasks RCU enabled.174second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)175second # [ 0.000000] Fallback order for Node 0: 0176second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144177second # [ 0.000000] Policy zone: DMA178second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off179second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1180second # [ 0.000000] allocated 2097152 bytes of page_ext181second # [ 0.000000] ftrace: allocating 75049 entries in 294 pages182second # [ 0.000000] ftrace: allocated 294 pages with 4 groups183second # [ 0.000000] rcu: Hierarchical RCU implementation.184test # [ 0.000000] Rude variant of Tasks RCU enabled.185test # [ 0.000000] Tracing variant of Tasks RCU enabled.186test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.187test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1188test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.189test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.190test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.191test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0192second # [ 0.000000] rcu: RCU event tracing is enabled.193second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.194second # [ 0.000000] Trampoline variant of Tasks RCU enabled.195second # [ 0.000000] Rude variant of Tasks RCU enabled.196second # [ 0.000000] Tracing variant of Tasks RCU enabled.197second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.198test # [ 0.000000] GICv3: 256 SPIs implemented199test # [ 0.000000] GICv3: 0 Extended SPIs implemented200test # [ 0.000000] Root IRQ handler: gic_handle_irq201test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI202test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0203test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000204test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]205test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)206second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1207second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)209test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000210second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.211test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000212second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.213test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.214second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0215second # [ 0.000000] GICv3: 256 SPIs implemented216second # [ 0.000000] GICv3: 0 Extended SPIs implemented217test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns218second # [ 0.000000] Root IRQ handler: gic_handle_irq219test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040220second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI221test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).222second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0223second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000224test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns225second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]226test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns227second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)228test # [ 0.000046] arm-pv: using stolen time PV229second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)230test # [ 0.000330] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)231second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000232test # [ 0.000611] Console: colour dummy device 80x25233test # [ 0.000622] printk: legacy console [tty0] enabled234second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000235second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.236test # [ 0.000830] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)237test # [ 0.000835] pid_max: default: 32768 minimum: 301238second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns239test # [ 0.000934] LSM: initializing lsm=capability,landlock,yama,bpf,ima240second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040241test # [ 0.001060] landlock: Up and running.242test # [ 0.001062] Yama: becoming mindful.243second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).244test # [ 0.001395] LSM support for eBPF active245second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns246test # [ 0.001548] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)247test # [ 0.001568] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)248second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns249second # [ 0.000050] arm-pv: using stolen time PV250test # [ 0.004276] rcu: Hierarchical SRCU implementation.251test # [ 0.004282] rcu: Max phase no-delay instances is 1000.252second # [ 0.000379] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)253test # [ 0.005259] fsl-mc MSI: its@8080000 domain created254second # [ 0.000659] Console: colour dummy device 80x25255test # [ 0.005444] EFI services will not be available.256second # [ 0.000671] printk: legacy console [tty0] enabled257test # [ 0.005540] smp: Bringing up secondary CPUs ...258test # [ 0.005571] smp: Brought up 1 node, 1 CPU259second # [ 0.000881] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)260test # [ 0.005574] SMP: Total of 1 processors activated.261second # [ 0.000886] pid_max: default: 32768 minimum: 301262test # [ 0.005576] CPU: All CPU(s) started at EL1263second # [ 0.000961] LSM: initializing lsm=capability,landlock,yama,bpf,ima264test # [ 0.005588] CPU features: detected: 32-bit EL0 Support265second # [ 0.001107] landlock: Up and running.266second # [ 0.001110] Yama: becoming mindful.267test # [ 0.005592] CPU features: detected: Data cache clean to the PoU not required for I/D coherence268second # [ 0.001561] LSM support for eBPF active269test # [ 0.005595] CPU features: detected: Common not Private translations270second # [ 0.001751] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)271test # [ 0.005597] CPU features: detected: CRC32 instructions272second # [ 0.001771] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)273test # [ 0.005599] CPU features: detected: Data cache clean to Point of Persistence274second # [ 0.004489] rcu: Hierarchical SRCU implementation.275test # [ 0.005602] CPU features: detected: Enhanced Virtualization Traps276second # [ 0.004496] rcu: Max phase no-delay instances is 1000.277test # [ 0.005613] CPU features: detected: RCpc load-acquire (LDAPR)278second # [ 0.005476] fsl-mc MSI: its@8080000 domain created279test # [ 0.005615] CPU features: detected: LSE atomic instructions280second # [ 0.005665] EFI services will not be available.281second # [ 0.005763] smp: Bringing up secondary CPUs ...282test # [ 0.005617] CPU features: detected: Privileged Access Never283second # [ 0.005797] smp: Brought up 1 node, 1 CPU284test # [ 0.005619] CPU features: detected: PMUv3285second # [ 0.005799] SMP: Total of 1 processors activated.286test # [ 0.005621] CPU features: detected: RAS Extension Support287second # [ 0.005801] CPU: All CPU(s) started at EL1288test # [ 0.005624] CPU features: detected: Speculative Store Bypassing Safe (SSBS)289second # [ 0.005813] CPU features: detected: 32-bit EL0 Support290test # [ 0.005646] alternatives: applying system-wide alternatives291second # [ 0.005817] CPU features: detected: Data cache clean to the PoU not required for I/D coherence292test # [ 0.007048] CPU features: detected: Hardware dirty bit management on CPU0293second # [ 0.005820] CPU features: detected: Common not Private translations294second # [ 0.005822] CPU features: detected: CRC32 instructions295test # [ 0.007288] Memory: 891192K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116084K reserved, 32768K cma-reserved)296second # [ 0.005824] CPU features: detected: Data cache clean to Point of Persistence297test # [ 0.007781] devtmpfs: initialized298second # [ 0.005827] CPU features: detected: Enhanced Virtualization Traps299test # [ 0.009439] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)300second # [ 0.005837] CPU features: detected: RCpc load-acquire (LDAPR)301second # [ 0.005840] CPU features: detected: LSE atomic instructions302test # [ 0.009462] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).303second # [ 0.005842] CPU features: detected: Privileged Access Never304test # [ 0.009590] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL305second # [ 0.005844] CPU features: detected: PMUv3306test # [ 0.009595] 0 pages in range for non-PLT usage307second # [ 0.005845] CPU features: detected: RAS Extension Support308test # [ 0.009596] 508192 pages in range for PLT usage309second # [ 0.005848] CPU features: detected: Speculative Store Bypassing Safe (SSBS)310test # [ 0.009711] pinctrl core: initialized pinctrl subsystem311test # [ 0.010490] DMI not present or invalid.312second # [ 0.005868] alternatives: applying system-wide alternatives313test # [ 0.013954] NET: Registered PF_NETLINK/PF_ROUTE protocol family314second # [ 0.007333] CPU features: detected: Hardware dirty bit management on CPU0315test # [ 0.016476] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations316test # [ 0.016607] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations317second # [ 0.007567] Memory: 890952K/1048576K available (24512K kernel code, 7102K rwdata, 26572K rodata, 4736K init, 1184K bss, 116088K reserved, 32768K cma-reserved)318second # [ 0.008035] devtmpfs: initialized319test # [ 0.016753] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations320second # [ 0.009561] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)321test # [ 0.016773] audit: initializing netlink subsys (disabled)322test # [ 0.017380] thermal_sys: Registered thermal governor 'fair_share'323second # [ 0.009583] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).324test # [ 0.017383] thermal_sys: Registered thermal governor 'bang_bang'325second # [ 0.009698] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL326second # [ 0.009703] 0 pages in range for non-PLT usage327test # [ 0.017386] thermal_sys: Registered thermal governor 'step_wise'328second # [ 0.009704] 508192 pages in range for PLT usage329test # [ 0.017388] thermal_sys: Registered thermal governor 'user_space'330second # [ 0.009811] pinctrl core: initialized pinctrl subsystem331test # [ 0.017390] thermal_sys: Registered thermal governor 'power_allocator'332second # [ 0.010569] DMI not present or invalid.333second # [ 0.013895] NET: Registered PF_NETLINK/PF_ROUTE protocol family334test # [ 0.017425] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1335test # [ 0.017434] cpuidle: using governor ladder336second # [ 0.016613] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations337test # [ 0.017438] cpuidle: using governor menu338second # [ 0.016747] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations339test # [ 0.017556] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.340second # [ 0.016897] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations341test # [ 0.017578] ASID allocator initialised with 32768 entries342test # [ 0.018227] Serial: AMBA PL011 UART driver343second # [ 0.016917] audit: initializing netlink subsys (disabled)344second # [ 0.017506] thermal_sys: Registered thermal governor 'fair_share'345test # [ 0.021587] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1346second # [ 0.017508] thermal_sys: Registered thermal governor 'bang_bang'347test # [ 0.021729] printk: console [ttyAMA0] enabled348second # [ 0.017511] thermal_sys: Registered thermal governor 'step_wise'349second # [ 0.017513] thermal_sys: Registered thermal governor 'user_space'350second # [ 0.017515] thermal_sys: Registered thermal governor 'power_allocator'351second # [ 0.017548] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352second # [ 0.017557] cpuidle: using governor ladder353second # [ 0.017561] cpuidle: using governor menu354second # [ 0.017688] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.355second # [ 0.017708] ASID allocator initialised with 32768 entries356second # [ 0.018391] Serial: AMBA PL011 UART driver357second # [ 0.021886] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1358second # [ 0.022058] printk: console [ttyAMA0] enabled359test # [ 0.093596] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages360test # [ 0.093612] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page361test # [ 0.093615] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages362test # [ 0.093617] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page363test # [ 0.093619] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364test # [ 0.093621] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page365test # [ 0.093623] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages366test # [ 0.093625] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page367test # [ 0.094864] fbcon: Taking over console368test # [ 0.094875] ACPI: Interpreter disabled.369second # [ 0.094003] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages370test # [ 0.095523] iommu: Default domain type: Translated371second # [ 0.094019] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page372test # [ 0.095527] iommu: DMA domain TLB invalidation policy: strict mode373second # [ 0.094021] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages374test # [ 0.095802] SCSI subsystem initialized375second # [ 0.094023] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page376test # [ 0.097403] usbcore: registered new interface driver usbfs377test # [ 0.097441] usbcore: registered new interface driver hub378second # [ 0.094025] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages379test # [ 0.097448] usbcore: registered new device driver usb380second # [ 0.094027] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page381test # [ 0.097668] pps_core: LinuxPPS API ver. 1 registered382second # [ 0.094029] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages383test # [ 0.097672] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>384second # [ 0.094031] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page385second # [ 0.095122] fbcon: Taking over console386test # [ 0.097676] PTP clock support registered387test # [ 0.097708] EDAC MC: Ver: 3.0.0388second # [ 0.095133] ACPI: Interpreter disabled.389second # [ 0.095785] iommu: Default domain type: Translated390second # [ 0.095789] iommu: DMA domain TLB invalidation policy: strict mode391second # [ 0.096106] SCSI subsystem initialized392second # [ 0.096319] usbcore: registered new interface driver usbfs393second # [ 0.096348] usbcore: registered new interface driver hub394second # [ 0.096362] usbcore: registered new device driver usb395second # [ 0.096569] pps_core: LinuxPPS API ver. 1 registered396second # [ 0.096572] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>397second # [ 0.096576] PTP clock support registered398second # [ 0.096609] EDAC MC: Ver: 3.0.0399test # [ 0.106261] scmi_core: SCMI protocol bus registered400test # [ 0.106638] FPGA manager framework401test # [ 0.110281] vgaarb: loaded402test # [ 0.110512] clocksource: Switched to clocksource arch_sys_counter403second # [ 0.105840] scmi_core: SCMI protocol bus registered404second # [ 0.106228] FPGA manager framework405second # [ 0.109769] vgaarb: loaded406second # [ 0.110835] clocksource: Switched to clocksource arch_sys_counter407test # [ 0.120569] VFS: Disk quotas dquot_6.6.0408test # [ 0.120597] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)409test # [ 0.122083] netfs: FS-Cache loaded410test # [ 0.122202] pnp: PnP ACPI: disabled411test # [ 0.125483] NET: Registered PF_INET protocol family412second # [ 0.120771] VFS: Disk quotas dquot_6.6.0413test # [ 0.125679] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)414second # [ 0.120800] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)415second # [ 0.122326] netfs: FS-Cache loaded416second # [ 0.122449] pnp: PnP ACPI: disabled417second # [ 0.125764] NET: Registered PF_INET protocol family418second # [ 0.125957] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419test # [ 0.150340] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420test # [ 0.150362] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421test # [ 0.150375] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422test # [ 0.150410] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)423test # [ 0.150487] TCP: Hash tables configured (established 8192 bind 8192)424second # [ 0.151535] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)425second # [ 0.151559] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)426second # [ 0.151573] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)427second # [ 0.151609] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)428second # [ 0.151676] TCP: Hash tables configured (established 8192 bind 8192)429second # [ 0.151759] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)430test # [ 0.153518] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)431second # [ 0.151784] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)432test # [ 0.153586] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)433second # [ 0.151847] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)434test # [ 0.153609] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)435second # [ 0.151952] NET: Registered PF_UNIX/PF_LOCAL protocol family436test # [ 0.153737] NET: Registered PF_UNIX/PF_LOCAL protocol family437second # [ 0.151984] NET: Registered PF_XDP protocol family438second # [ 0.151999] PCI: CLS 0 bytes, default 64439test # [ 0.153766] NET: Registered PF_XDP protocol family440test # [ 0.153781] PCI: CLS 0 bytes, default 64441second # [ 0.152315] Trying to unpack rootfs image as initramfs...442test # [ 0.154017] Trying to unpack rootfs image as initramfs...443second # [ 0.161001] kvm [1]: HYP mode not available444test # [ 0.165534] kvm [1]: HYP mode not available445test # [ 0.231118] Initialise system trusted keyrings446test # [ 0.231236] workingset: timestamp_bits=42 max_order=18 bucket_order=0447test # [ 0.231549] squashfs: version 4.0 (2009/01/31) Phillip Lougher448test # [ 0.231585] 9p: Installing v9fs 9p2000 file system support449second # [ 0.235212] Initialise system trusted keyrings450second # [ 0.235329] workingset: timestamp_bits=42 max_order=18 bucket_order=0451second # [ 0.235659] squashfs: version 4.0 (2009/01/31) Phillip Lougher452second # [ 0.235695] 9p: Installing v9fs 9p2000 file system support453test # [ 0.252137] Key type asymmetric registered454test # [ 0.252155] Asymmetric key parser 'x509' registered455test # [ 0.252214] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)456test # [ 0.252313] io scheduler mq-deadline registered457test # [ 0.252317] io scheduler kyber registered458second # [ 0.256186] Key type asymmetric registered459second # [ 0.256204] Asymmetric key parser 'x509' registered460second # [ 0.256259] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)461second # [ 0.256367] io scheduler mq-deadline registered462second # [ 0.256370] io scheduler kyber registered463test # [ 0.262653] pl061_gpio 9030000.pl061: PL061 GPIO chip registered464test # [ 0.263025] ledtrig-cpu: registered to indicate activity on CPUs465test # [ 0.263359] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:466test # [ 0.263375] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000467test # [ 0.263389] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000468test # [ 0.263393] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000469test # [ 0.263425] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits470test # [ 0.263447] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]471test # [ 0.263530] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00472test # [ 0.263546] pci_bus 0000:00: root bus resource [bus 00-ff]473test # [ 0.263549] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]474test # [ 0.263552] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]475test # [ 0.263554] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]476test # [ 0.263632] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint477test # [ 0.264080] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint478test # [ 0.264207] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]479second # [ 0.266975] pl061_gpio 9030000.pl061: PL061 GPIO chip registered480test # [ 0.264216] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]481second # [ 0.267356] ledtrig-cpu: registered to indicate activity on CPUs482test # [ 0.264234] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]483second # [ 0.267691] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:484test # [ 0.264246] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]485second # [ 0.267708] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000486test # [ 0.264554] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint487second # [ 0.267721] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000488test # [ 0.264676] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]489test # [ 0.264685] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]490second # [ 0.267725] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000491test # [ 0.264704] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492second # [ 0.267752] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits493test # [ 0.265013] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint494test # [ 0.265133] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]495second # [ 0.267780] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]496test # [ 0.265142] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]497second # [ 0.267865] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00498test # [ 0.265161] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499second # [ 0.267881] pci_bus 0000:00: root bus resource [bus 00-ff]500second # [ 0.267884] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]501test # [ 0.265458] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint502test # [ 0.265578] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]503second # [ 0.267887] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]504test # [ 0.265587] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]505second # [ 0.267889] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]506test # [ 0.265605] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507second # [ 0.267966] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint508test # [ 0.265613] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]509second # [ 0.268423] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint510test # [ 0.265914] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint511second # [ 0.268548] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]512test # [ 0.266033] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]513second # [ 0.268557] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]514test # [ 0.266050] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]515second # [ 0.268576] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516test # [ 0.266350] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint517second # [ 0.268588] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]518test # [ 0.266482] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]519second # [ 0.268901] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint520test # [ 0.266499] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521second # [ 0.269026] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]522second # [ 0.269035] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]523second # [ 0.269054] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524second # [ 0.269368] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint525second # [ 0.269490] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]526second # [ 0.269499] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]527second # [ 0.269517] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]528second # [ 0.269821] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint529second # [ 0.269943] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]530second # [ 0.269952] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]531second # [ 0.269970] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532second # [ 0.269979] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]533second # [ 0.270303] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint534second # [ 0.270428] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]535second # [ 0.270446] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536second # [ 0.270775] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint537test # [ 0.290906] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint538second # [ 0.289561] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]539test # [ 0.291036] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]540second # [ 0.289585] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]541test # [ 0.291218] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint542second # [ 0.289931] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint543test # [ 0.291343] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]544second # [ 0.290053] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]545test # [ 0.291361] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546second # [ 0.290245] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint547test # [ 0.291672] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint548second # [ 0.290368] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]549test # [ 0.291796] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]550second # [ 0.290387] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551test # [ 0.291814] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]552second # [ 0.290709] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint553test # [ 0.292116] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint554test # [ 0.292246] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]555test # [ 0.292266] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]556test # [ 0.292573] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint557test # [ 0.292821] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]558test # [ 0.292831] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]559test # [ 0.292850] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560test # [ 0.293160] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint561test # [ 0.293282] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]562second # [ 0.294968] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]563test # [ 0.293290] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]564second # [ 0.295002] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]565test # [ 0.293309] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]566second # [ 0.295334] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint567test # [ 0.293722] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned568second # [ 0.295458] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]569test # [ 0.293729] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned570second # [ 0.295476] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]571test # [ 0.293732] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned572second # [ 0.295781] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint573test # [ 0.293765] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned574second # [ 0.295941] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]575second # [ 0.295951] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]576test # [ 0.293796] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned577second # [ 0.295969] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578test # [ 0.293827] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned579second # [ 0.296345] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint580test # [ 0.293859] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned581second # [ 0.296468] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]582test # [ 0.293890] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned583second # [ 0.296477] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]584test # [ 0.293925] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned585second # [ 0.296495] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]586second # [ 0.296932] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned587test # [ 0.293956] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned588second # [ 0.296938] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned589test # [ 0.293986] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned590second # [ 0.296942] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned591test # [ 0.294016] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned592second # [ 0.296976] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned593test # [ 0.294089] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned594test # [ 0.294119] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned595second # [ 0.297007] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned596test # [ 0.294131] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned597second # [ 0.297038] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned598test # [ 0.294143] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned599second # [ 0.297071] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned600test # [ 0.294155] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned601second # [ 0.297102] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned602test # [ 0.294167] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned603second # [ 0.297136] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned604test # [ 0.294180] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned605second # [ 0.297167] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned606test # [ 0.294193] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned607test # [ 0.294206] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned608second # [ 0.297198] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned609test # [ 0.294221] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned610second # [ 0.297228] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned611test # [ 0.294234] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned612second # [ 0.297298] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned613test # [ 0.294245] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned614second # [ 0.297328] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned615test # [ 0.294257] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned616second # [ 0.297341] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned617test # [ 0.294269] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned618second # [ 0.297353] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned619test # [ 0.294281] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned620second # [ 0.297366] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned621test # [ 0.294294] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned622second # [ 0.297377] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned623test # [ 0.294306] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned624test # [ 0.294318] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned625second # [ 0.297389] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned626test # [ 0.294329] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned627second # [ 0.297402] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned628test # [ 0.294346] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]629second # [ 0.297415] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned630test # [ 0.294351] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]631second # [ 0.297430] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned632test # [ 0.294353] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]633second # [ 0.297443] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned634second # [ 0.297455] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned635second # [ 0.297467] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned636second # [ 0.297478] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned637second # [ 0.297490] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned638second # [ 0.297502] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned639second # [ 0.297514] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned640second # [ 0.297526] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned641second # [ 0.297538] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned642second # [ 0.297555] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]643second # [ 0.297559] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]644second # [ 0.297562] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]645second # [ 0.298092] pci 0000:00:07.0: enabling device (0000 -> 0002)646test # [ 0.327007] pci 0000:00:07.0: enabling device (0000 -> 0002)647test # [ 0.341724] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)648second # [ 0.336720] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)649second # [ 0.338296] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)650test # [ 0.343861] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)651test # [ 0.345248] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)652test # [ 0.347580] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)653test # [ 0.348977] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)654second # [ 0.348503] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)655second # [ 0.350253] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)656test # [ 0.358657] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)657test # [ 0.360206] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)658test # [ 0.361772] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)659test # [ 0.364807] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)660second # [ 0.360537] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)661test # [ 0.366102] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)662second # [ 0.362245] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)663second # [ 0.364795] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)664second # [ 0.366451] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)665test # [ 0.375771] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)666second # [ 0.376098] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)667second # [ 0.377779] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)668second # [ 0.380757] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)669test # [ 0.387310] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled670test # [ 0.388302] msm_serial: driver initialized671test # [ 0.388379] SuperH (H)SCI(F) driver initialized672test # [ 0.388417] STM32 USART driver initialized673second # [ 0.392451] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled674second # [ 0.393531] msm_serial: driver initialized675second # [ 0.393604] SuperH (H)SCI(F) driver initialized676second # [ 0.393644] STM32 USART driver initialized677test # [ 0.411684] loop: module loaded678test # [ 0.411829] virtio_blk virtio2: 1/0/0 default/read/poll queues679test # [ 0.412478] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)680test # [ 0.416230] megasas: 07.734.00.00-rc1681test # [ 0.416709] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]682second # [ 0.419027] loop: module loaded683second # [ 0.419165] virtio_blk virtio2: 1/0/0 default/read/poll queues684second # [ 0.419793] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)685second # [ 0.424581] megasas: 07.734.00.00-rc1686test # [ 0.427543] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000687test # [ 0.427578] Intel/Sharp Extended Query Table at 0x0031688second # [ 0.425069] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]689test # [ 0.429255] Using buffer write method690test # [ 0.429320] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]691test # [ 0.433983] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000692test # [ 0.433998] Intel/Sharp Extended Query Table at 0x0031693second # [ 0.428855] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000694second # [ 0.428887] Intel/Sharp Extended Query Table at 0x0031695second # [ 0.430576] Using buffer write method696second # [ 0.430637] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]697test # [ 0.436808] Using buffer write method698test # [ 0.436821] Concatenating MTD devices:699test # [ 0.436823] (0): "0.flash"700test # [ 0.436825] (1): "0.flash"701test # [ 0.436826] into device "0.flash"702second # [ 0.441285] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000703second # [ 0.441308] Intel/Sharp Extended Query Table at 0x0031704second # [ 0.450861] Using buffer write method705second # [ 0.450879] Concatenating MTD devices:706second # [ 0.450881] (0): "0.flash"707second # [ 0.450883] (1): "0.flash"708second # [ 0.450884] into device "0.flash"709test # [ 0.668484] Freeing initrd memory: 26900K710test # [ 0.675375] tun: Universal TUN/TAP device driver, 1.6711test # [ 0.677586] thunder_xcv, ver 1.0712test # [ 0.677601] thunder_bgx, ver 1.0713test # [ 0.677609] nicpf, ver 1.0714test # [ 0.677930] e1000: Intel(R) PRO/1000 Network Driver715test # [ 0.677934] e1000: Copyright (c) 1999-2006 Intel Corporation.716test # [ 0.677948] e1000e: Intel(R) PRO/1000 Network Driver717test # [ 0.677954] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.718test # [ 0.677965] igb: Intel(R) Gigabit Ethernet Network Driver719test # [ 0.677967] igb: Copyright (c) 2007-2014 Intel Corporation.720test # [ 0.677977] igbvf: Intel(R) Gigabit Virtual Function Network Driver721test # [ 0.677979] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.722test # [ 0.678045] sky2: driver version 1.30723test # [ 0.683851] usbcore: registered new interface driver usb-storage724second # [ 0.681276] Freeing initrd memory: 26904K725test # [ 0.683941] usbcore: registered new interface driver usbserial_generic726test # [ 0.683961] usbserial: USB Serial support registered for generic727test # [ 0.684259] hv_vmbus: registering driver hyperv_keyboard728test # [ 0.685340] ehci-pci 0000:00:07.0: EHCI Host Controller729test # [ 0.685365] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1730test # [ 0.685644] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000731test # [ 0.685818] rtc-pl031 9010000.pl031: registered as rtc0732test # [ 0.685888] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:14:26 UTC (1792077266)733test # [ 0.686079] i2c_dev: i2c /dev entries driver734second # [ 0.688471] tun: Universal TUN/TAP device driver, 1.6735second # [ 0.690689] thunder_xcv, ver 1.0736second # [ 0.690705] thunder_bgx, ver 1.0737second # [ 0.690714] nicpf, ver 1.0738second # [ 0.692238] e1000: Intel(R) PRO/1000 Network Driver739second # [ 0.692244] e1000: Copyright (c) 1999-2006 Intel Corporation.740second # [ 0.692257] e1000e: Intel(R) PRO/1000 Network Driver741second # [ 0.692262] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.742second # [ 0.692275] igb: Intel(R) Gigabit Ethernet Network Driver743second # [ 0.692277] igb: Copyright (c) 2007-2014 Intel Corporation.744second # [ 0.692286] igbvf: Intel(R) Gigabit Virtual Function Network Driver745second # [ 0.692289] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.746second # [ 0.692355] sky2: driver version 1.30747second # [ 0.693203] usbcore: registered new interface driver usb-storage748second # [ 0.693293] usbcore: registered new interface driver usbserial_generic749second # [ 0.693311] usbserial: USB Serial support registered for generic750second # [ 0.693624] hv_vmbus: registering driver hyperv_keyboard751second # [ 0.694675] ehci-pci 0000:00:07.0: EHCI Host Controller752second # [ 0.694727] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1753test # [ 0.692389] sdhci: Secure Digital Host Controller Interface driver754test # [ 0.692394] sdhci: Copyright(c) Pierre Ossman755second # [ 0.701421] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000756test # [ 0.692541] Synopsys Designware Multimedia Card Interface Driver757second # [ 0.701623] rtc-pl031 9010000.pl031: registered as rtc0758test # [ 0.692717] sdhci-pltfm: SDHCI platform and OF driver helper759test # [ 0.693583] hid: raw HID events driver (C) Jiri Kosina760second # [ 0.701657] rtc-pl031 9010000.pl031: setting system clock to 2026-10-15T15:14:26 UTC (1792077266)761test # [ 0.693715] usbcore: registered new interface driver usbhid762second # [ 0.701863] i2c_dev: i2c /dev entries driver763test # [ 0.693718] usbhid: USB HID core driver764second # [ 0.703591] sdhci: Secure Digital Host Controller Interface driver765second # [ 0.703597] sdhci: Copyright(c) Pierre Ossman766second # [ 0.703750] Synopsys Designware Multimedia Card Interface Driver767second # [ 0.703933] sdhci-pltfm: SDHCI platform and OF driver helper768test # [ 0.737403] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00769second # [ 0.704827] hid: raw HID events driver (C) Jiri Kosina770test # [ 0.737837] hub 1-0:1.0: USB hub found771test # [ 0.737877] hub 1-0:1.0: 6 ports detected772second # [ 0.704963] usbcore: registered new interface driver usbhid773second # [ 0.704966] usbhid: USB HID core driver774test # [ 0.738164] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available775second # [ 0.730365] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00776second # [ 0.730846] hub 1-0:1.0: USB hub found777second # [ 0.730876] hub 1-0:1.0: 6 ports detected778test # [ 0.740804] drop_monitor: Initializing network drop monitor service779test # [ 0.740925] NET: Registered PF_INET6 protocol family780test # [ 0.741572] Segment Routing with IPv6781test # [ 0.741583] In-situ OAM (IOAM) with IPv6782test # [ 0.741631] NET: Registered PF_PACKET protocol family783test # [ 0.741692] 9pnet: Installing 9P2000 support784test # [ 0.741725] Key type dns_resolver registered785second # [ 0.736796] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available786second # [ 0.737779] drop_monitor: Initializing network drop monitor service787second # [ 0.737900] NET: Registered PF_INET6 protocol family788second # [ 0.738585] Segment Routing with IPv6789second # [ 0.738595] In-situ OAM (IOAM) with IPv6790second # [ 0.738663] NET: Registered PF_PACKET protocol family791second # [ 0.738722] 9pnet: Installing 9P2000 support792second # [ 0.738755] Key type dns_resolver registered793test # [ 0.747221] registered taskstats version 1794test # [ 0.747374] Loading compiled-in X.509 certificates795second # [ 0.745236] registered taskstats version 1796second # [ 0.745406] Loading compiled-in X.509 certificates797test # [ 0.752735] Demotion targets for Node 0: null798test # [ 0.752815] Key type .fscrypt registered799test # [ 0.752818] Key type fscrypt-provisioning registered800test # [ 0.752935] ima: No TPM chip found, activating TPM-bypass!801test # [ 0.752955] ima: Allocated hash algorithm: sha1802test # [ 0.753024] ima: No architecture policies found803test # [ 0.753589] input: gpio-keys as /devices/platform/gpio-keys/input/input0804second # [ 0.751282] Demotion targets for Node 0: null805second # [ 0.751393] Key type .fscrypt registered806second # [ 0.751396] Key type fscrypt-provisioning registered807second # [ 0.751507] ima: No TPM chip found, activating TPM-bypass!808second # [ 0.751517] ima: Allocated hash algorithm: sha1809second # [ 0.751590] ima: No architecture policies found810second # [ 0.752183] input: gpio-keys as /devices/platform/gpio-keys/input/input0811test # [ 0.766567] clk: Disabling unused clocks812test # [ 0.766579] PM: genpd: Disabling unused power domains813second # [ 0.765360] clk: Disabling unused clocks814test # [ 0.768873] Freeing unused kernel memory: 4736K815second # [ 0.765373] PM: genpd: Disabling unused power domains816test # [ 0.768945] Run /init as init process817second # [ 0.768643] Freeing unused kernel memory: 4736K818second # [ 0.768716] Run /init as init process819test # [ 0.789129] systemd[1]: Successfully made /usr/ read-only.820second # [ 0.789528] systemd[1]: Successfully made /usr/ read-only.821second # [ 0.978896] usb 1-1: new high-speed USB device number 2 using ehci-pci822test # [ 0.986562] usb 1-1: new high-speed USB device number 2 using ehci-pci823test # [ 1.123237] 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)824test # [ 1.123267] systemd[1]: Detected virtualization qemu.825test # [ 1.123308] systemd[1]: Detected architecture arm64.826test # [ 1.123317] systemd[1]: Running in initrd.827second # [ 1.123822] 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)828test # [ 1.124109] systemd[1]: Initializing machine ID from random generator.829second # [ 1.123853] systemd[1]: Detected virtualization qemu.830test # [ 1.124386] systemd[1]: Hostname set to <test>.831second # [ 1.123897] systemd[1]: Detected architecture arm64.832second # [ 1.124053] systemd[1]: Running in initrd.833second # [ 1.125457] systemd[1]: Initializing machine ID from random generator.834second # [ 1.125791] systemd[1]: Hostname set to <second>.835test # [ 1.146290] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1836second # [ 1.145592] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1837test # [ 1.230812] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838second # [ 1.231128] 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.270083] systemd[1]: bpf-restrict-fs: LSM BPF program attached840test # [ 1.275780] systemd[1]: bpf-restrict-fs: LSM BPF program attached841second # [ 1.329897] systemd[1]: Queued start job for default target Initrd Default Target.842test # [ 1.338148] systemd[1]: Queued start job for default target Initrd Default Target.843second # [ 1.336526] systemd[1]: Created slice Slice /system/modprobe.844second # [ 1.336697] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.845second # [ 1.336732] systemd[1]: Expecting device /dev/disk/by-label/nixos...846second # [ 1.336754] systemd[1]: Reached target Path Units.847second # [ 1.336766] systemd[1]: Reached target Slice Units.848second # [ 1.336778] systemd[1]: Reached target Swaps.849second # [ 1.336796] systemd[1]: Reached target Timer Units.850test # [ 1.344133] systemd[1]: Created slice Slice /system/modprobe.851second # [ 1.336942] systemd[1]: Listening on D-Bus System Message Bus Socket.852second # [ 1.337068] systemd[1]: Listening on Journal Socket (/dev/log).853test # [ 1.344308] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.854second # [ 1.337198] systemd[1]: Listening on Journal Sockets.855test # [ 1.344343] systemd[1]: Expecting device /dev/disk/by-label/nixos...856second # [ 1.337271] systemd[1]: Listening on udev Control Socket.857test # [ 1.344369] systemd[1]: Reached target Path Units.858test # [ 1.344383] systemd[1]: Reached target Slice Units.859second # [ 1.337317] systemd[1]: Listening on udev Kernel Socket.860test # [ 1.344396] systemd[1]: Reached target Swaps.861second # [ 1.337332] systemd[1]: Reached target Socket Units.862test # [ 1.344416] systemd[1]: Reached target Timer Units.863second # [ 1.338556] systemd[1]: Starting Create List of Static Device Nodes...864test # [ 1.344564] systemd[1]: Listening on D-Bus System Message Bus Socket.865test # [ 1.344687] systemd[1]: Listening on Journal Socket (/dev/log).866second # [ 1.338613] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs867test # [ 1.344771] systemd[1]: Listening on Journal Sockets.868test # [ 1.344836] systemd[1]: Listening on udev Control Socket.869test # [ 1.344927] systemd[1]: Listening on udev Kernel Socket.870test # [ 1.344944] systemd[1]: Reached target Socket Units.871test # [ 1.346199] systemd[1]: Starting Create List of Static Device Nodes...872test # [ 1.346254] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs873test # [ 1.350745] usb 1-2: new high-speed USB device number 3 using ehci-pci874second # [ 1.350906] usb 1-2: new high-speed USB device number 3 using ehci-pci875second # [ 1.354068] systemd[1]: Mounting Kernel Configuration File System...876test # [ 1.359629] systemd[1]: Mounting Kernel Configuration File System...877second # [ 1.360300] systemd[1]: Starting Journal Service...878test # [ 1.366776] systemd[1]: Starting Journal Service...879test # [ 1.375036] systemd[1]: Starting Load Kernel Modules...880test # [ 1.375136] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os881second # [ 1.377842] systemd[1]: Starting Load Kernel Modules...882second # [ 1.377937] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os883second # [ 1.380331] systemd[1]: Starting Coldplug All udev Devices...884test # [ 1.400109] systemd[1]: Starting Coldplug All udev Devices...885test # [ 1.403387] systemd[1]: Finished Create List of Static Device Nodes.886second # [ 1.399168] systemd[1]: Finished Create List of Static Device Nodes.887second # [ 1.415349] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...888second # [ 1.415806] systemd[1]: Mounted Kernel Configuration File System.889second # [ 1.434609] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.890test # [ 1.440321] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.891test # [ 1.442815] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...892second # [ 1.442473] systemd-journald[71]: Collecting audit messages is disabled.893second # [ 1.449872] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev894test # [ 1.455989] systemd-journald[71]: Collecting audit messages is disabled.895test # [ 1.460000] systemd[1]: Mounted Kernel Configuration File System.896test # [ 1.460179] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev897second # [ 1.457062] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0898second # [ 1.457309] [drm] features: -virgl +edid -resource_blob -host_visible899second # [ 1.457311] [drm] features: -context_init900second # [ 1.457807] [drm] number of scanouts: 1901second # [ 1.457816] [drm] number of cap sets: 0902test # [ 1.467111] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.903second # [ 1.464969] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.904test # [ 1.468418] systemd[1]: Starting Create Static Device Nodes in /dev...905test # [ 1.477474] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0906test # [ 1.477827] [drm] features: -virgl +edid -resource_blob -host_visible907second # [ 1.467784] systemd[1]: Starting Create Static Device Nodes in /dev...908test # [ 1.477830] [drm] features: -context_init909test # [ 1.478312] [drm] number of scanouts: 1910test # [ 1.478321] [drm] number of cap sets: 0911second # [ 1.479269] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic912second # [ 1.479281] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0913test # [ 1.500911] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic914test # [ 1.500924] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0915second # [ 1.499641] Console: switching to colour frame buffer device 160x50916test # [ 1.509757] 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/input2917test # [ 1.509863] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0918test # [ 1.518963] Console: switching to colour frame buffer device 160x50919second # [ 1.523630] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device920second # [ 1.525138] systemd[1]: Finished Create Static Device Nodes in /dev.921second # [ 1.525322] systemd[1]: Reached target Preparation for Local File Systems.922second # [ 1.525356] systemd[1]: Reached target Local File Systems.923second # [ 1.527054] 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/input2924second # [ 1.527304] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0925test # [ 1.538733] systemd[1]: Started Journal Service.926second # [ 1.535864] systemd[1]: Starting Rule-based Manager for Device Events and Files...927test # [ 1.544968] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device928second # [ 1.539355] systemd-modules-load[72]: Inserted module 'dm_mod'929second # [ 1.543162] systemd-modules-load[72]: Module 'virtio_balloon' is built in930test # [ 1.539015] systemd-modules-load[72]: Inserted module 'dm_mod'931second # [ 1.547121] systemd[1]: Finished Load Kernel Modules.932second # [ 1.547429] systemd[1]: Started Journal Service.933test # [ 1.548939] systemd-modules-load[72]: Module 'virtio_balloon' is built in934test # [ 1.549999] systemd-modules-load[72]: Module 'virtio_console' is built in935test # [ 1.550710] systemd[1]: Finished Create Static Device Nodes in /dev.936test # [ 1.551393] systemd[1]: Reached target Preparation for Local File Systems.937second # [ 1.543977] systemd-modules-load[72]: Module 'virtio_console' is built in938second # [ 1.552783] systemd-modules-load[72]: Inserted module 'virtio_gpu'939second # [ 1.553664] systemd-modules-load[72]: Module 'virtio_rng' is built in940second # [ 1.554341] systemd[1]: Starting Apply Kernel Variables...941test # [ 1.560313] systemd[1]: Reached target Local File Systems.942test # [ 1.561024] systemd[1]: Starting Create System Files and Directories...943test # [ 1.561661] systemd-modules-load[72]: Inserted module 'virtio_gpu'944test # [ 1.562225] systemd-modules-load[72]: Module 'virtio_rng' is built in945test # [ 1.564144] systemd[1]: Starting Rule-based Manager for Device Events and Files...946test # [ 1.569010] systemd[1]: Finished Load Kernel Modules.947second # [ 1.573939] systemd[1]: Starting Create System Files and Directories...948test # [ 1.584576] systemd[1]: Starting Apply Kernel Variables...949test # [ 1.612968] systemd[1]: Finished Create System Files and Directories.950second # [ 1.611935] systemd[1]: Finished Apply Kernel Variables.951second # [ 1.613161] systemd[1]: Finished Create System Files and Directories.952test # [ 1.625887] systemd[1]: Finished Apply Kernel Variables.953second # [ 1.633298] systemd-udevd[79]: Using default interface naming scheme 'v261'.954test # [ 1.645458] systemd-udevd[79]: Using default interface naming scheme 'v261'.955second # [ 1.658097] systemd[1]: Started Rule-based Manager for Device Events and Files.956test # [ 1.668946] systemd[1]: Started Rule-based Manager for Device Events and Files.957second # [ 1.716990] systemd[1]: Starting Virtual Console Setup...958test # [ 1.731706] systemd[1]: Starting Virtual Console Setup...959second # [ 1.777180] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.960second # [ 1.779216] systemd[1]: Finished Virtual Console Setup.961test # [ 1.784577] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.962test # [ 1.787180] systemd[1]: Finished Virtual Console Setup.963test # [ 1.992563] systemd[1]: Finished Coldplug All udev Devices.964second # [ 1.990041] systemd[1]: Finished Coldplug All udev Devices.965second # [ 1.990649] systemd[1]: Reached target System Initialization.966second # [ 1.991155] systemd[1]: Reached target Basic System.967test # [ 1.996066] systemd[1]: Reached target System Initialization.968test # [ 1.996977] systemd[1]: Reached target Basic System.969test # [ 2.098678] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.970second # [ 2.102897] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.971test # [ 2.127608] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.972second # [ 2.125732] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.973second # [ 2.200480] systemd[1]: Found device /dev/disk/by-label/nixos.974second # [ 2.202718] systemd[1]: Reached target Initrd Root Device.975test # [ 2.208409] systemd[1]: Found device /dev/disk/by-label/nixos.976second # [ 2.205096] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...977test # [ 2.210711] systemd[1]: Reached target Initrd Root Device.978test # [ 2.212425] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...979second # [ 2.243245] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks980test # [ 2.251222] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks981second # [ 2.271208] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.982test # [ 2.275709] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.983second # [ 2.382859] systemd[1]: Mounting /sysroot...984test # [ 2.396529] systemd[1]: Mounting /sysroot...985second # [ 2.417144] EXT4-fs (vda): mounted filesystem bdd3a468-9ee5-439f-adf9-a2b8c8670015 r/w with ordered data mode. Quota mode: none.986second # [ 2.414733] systemd[1]: Mounted /sysroot.987second # [ 2.416533] systemd[1]: Reached target Initrd Root File System.988test # [ 2.428805] EXT4-fs (vda): mounted filesystem 401b84c3-d12b-4087-8e20-e606cb9d1b3f r/w with ordered data mode. Quota mode: none.989second # [ 2.419851] systemd[1]: Starting Mountpoints Configured in the Real Root...990test # [ 2.425166] systemd[1]: Mounted /sysroot.991test # [ 2.426095] systemd[1]: Reached target Initrd Root File System.992test # [ 2.429932] systemd[1]: Mounting /sysroot/nix/.ro-store...993second # [ 2.433965] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.994test # [ 2.441873] systemd[1]: Mounting /sysroot/nix/.rw-store...995second # [ 2.438629] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...996second # [ 2.440087] systemd[1]: Reloading...997test # [ 2.447694] systemd[1]: Mounting /sysroot/run...998test # [ 2.484701] systemd[1]: Mounting /sysroot/tmp/shared...999test # [ 2.498968] systemd[1]: Mounting /sysroot/tmp/xchg...1000test # [ 2.502798] fuse: init (API version 7.45)1001test # [ 2.513785] virtiofs virtio6: discovered new tag: nix-store1002test # [ 2.514277] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1003test # [ 2.512109] systemd[1]: Starting Mountpoints Configured in the Real Root...1004test # [ 2.528111] virtiofs virtio7: discovered new tag: shared1005test # [ 2.528632] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1006test # [ 2.526510] systemd[1]: Mounted /sysroot/nix/.rw-store.1007test # [ 2.534462] virtiofs virtio8: discovered new tag: xchg1008test # [ 2.537259] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1009test # [ 2.561710] systemd[1]: Starting rw-sysroot-nix-store.service...1010test # [ 2.563232] systemd[1]: Mounted /sysroot/nix/.ro-store.1011test # [ 2.564667] systemd-sysroot-fstab-check[124]: /sysroot should be mounted in the initrd, will request daemon-reload.1012test # [ 2.570323] systemd[1]: Mounted /sysroot/tmp/shared.1013test # [ 2.575977] systemd[1]: Mounted /sysroot/run.1014test # [ 2.583336] systemd[1]: Mounted /sysroot/tmp/xchg.1015test # [ 2.592090] systemd[1]: Reload requested from client PID 124 ('systemd-sysroot') (unit initrd-parse-etc.service)...1016test # [ 2.595226] systemd[1]: Reloading...1017second # [ 2.625112] systemd[1]: Reloading finished in 183 ms.1018second # [ 2.653429] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1019second # [ 2.655832] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1020second # [ 2.660888] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1021second # [ 2.662854] systemd[1]: Finished Mountpoints Configured in the Real Root.1022second # [ 2.663796] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1023test # [ 2.779600] systemd[1]: Reloading finished in 185 ms.1024test # [ 2.809253] systemd-sysroot-fstab-check[124]: Requesting initrd-fs.target/start/replace...1025test # [ 2.810856] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1026test # [ 2.812830] systemd[1]: Finished rw-sysroot-nix-store.service.1027test # [ 2.815295] systemd-sysroot-fstab-check[124]: Requesting swap.target/start/replace...1028test # [ 2.819888] systemd[1]: Starting rw-sysroot-nix-store.service...1029test # [ 2.824567] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1030test # [ 2.827640] systemd[1]: Finished Mountpoints Configured in the Real Root.1031test # [ 2.828583] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1032test # [ 2.847217] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1033test # [ 2.848274] systemd[1]: Finished rw-sysroot-nix-store.service.1034second # [ 2.992735] fuse: init (API version 7.45)1035second # [ 3.000870] virtiofs virtio6: discovered new tag: nix-store1036second # [ 3.001365] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1037second # [ 3.011045] (udev-worker)[91]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1038second # [ 3.015174] (udev-worker)[91]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1039second # [ 3.034009] virtiofs virtio7: discovered new tag: shared1040second # [ 3.034528] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1041second # [ 3.041455] virtiofs virtio8: discovered new tag: xchg1042second # [ 3.041954] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1043second # [ 3.040143] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1044second # [ 3.040832] systemd[1]: Stopped Virtual Console Setup.1045second # [ 3.041833] systemd[1]: Stopping Virtual Console Setup...1046second # [ 3.044489] systemd[1]: Starting Virtual Console Setup...1047second # [ 3.061429] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1048second # [ 3.062136] systemd[1]: Stopped Virtual Console Setup.1049second # [ 3.064130] systemd[1]: Starting Virtual Console Setup...1050second # [ 3.077570] systemd-vconsole-setup[139]: Configuration of first virtual console was skipped, ignoring remaining ones.1051second # [ 3.079170] systemd[1]: Finished Virtual Console Setup.1052test # [ 3.117225] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1053test # [ 3.121993] (udev-worker)[99]: 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 directory1054test # [ 3.134597] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1055test # [ 3.136075] systemd[1]: Stopped Virtual Console Setup.1056test # [ 3.137306] systemd[1]: Stopping Virtual Console Setup...1057test # [ 3.139324] systemd[1]: Starting Virtual Console Setup...1058test # [ 3.167112] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones.1059test # [ 3.169596] systemd[1]: Finished Virtual Console Setup.1060second # [ 3.386756] systemd[1]: Mounting /sysroot/nix/.ro-store...1061test # [ 3.397534] systemd[1]: Mounting /sysroot/nix/store...1062second # [ 3.396347] systemd[1]: Mounting /sysroot/nix/.rw-store...1063second # [ 3.402963] systemd[1]: Mounting /sysroot/run...1064second # [ 3.417764] systemd[1]: Mounting /sysroot/tmp/shared...1065test # [ 3.447300] systemd[1]: Mounted /sysroot/nix/store.1066second # [ 3.444300] systemd[1]: Mounting /sysroot/tmp/xchg...1067test # [ 3.449669] systemd[1]: Reached target Initrd File Systems.1068test # [ 3.454592] systemd[1]: Starting Find NixOS closure...1069second # [ 3.453778] systemd[1]: Mounted /sysroot/nix/.ro-store.1070test # [ 3.464570] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1071second # [ 3.465224] systemd[1]: Mounted /sysroot/nix/.rw-store.1072second # [ 3.471222] systemd[1]: Mounted /sysroot/run.1073second # [ 3.484251] systemd[1]: Starting rw-sysroot-nix-store.service...1074second # [ 3.486388] systemd[1]: Mounted /sysroot/tmp/shared.1075second # [ 3.491124] systemd[1]: Mounted /sysroot/tmp/xchg.1076test # [ 3.502934] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1077test # [ 3.509184] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1078second # [ 3.506593] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1079second # [ 3.507693] systemd[1]: Finished rw-sysroot-nix-store.service.1080test # [ 3.521037] systemd[1]: Finished Find NixOS closure.1081test # [ 3.523115] systemd[1]: Reached target Initrd Default Target.1082test # [ 3.524411] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1083test # [ 3.555716] systemd[1]: initrd-cleanup.service: Deactivated successfully.1084test # [ 3.560208] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1085test # [ 3.562103] systemd[1]: Stopped target Initrd Default Target.1086test # [ 3.564197] systemd[1]: Stopped target Basic System.1087test # [ 3.566426] systemd[1]: Stopped target Initrd Root Device.1088test # [ 3.568487] systemd[1]: Stopped target Path Units.1089test # [ 3.572359] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1090test # [ 3.573445] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1091test # [ 3.574552] systemd[1]: Stopped target Slice Units.1092test # [ 3.576866] systemd[1]: Stopped target Socket Units.1093test # [ 3.578941] systemd[1]: Stopped target System Initialization.1094test # [ 3.582817] systemd[1]: Stopped target Swaps.1095test # [ 3.583553] systemd[1]: Stopped target Timer Units.1096test # [ 3.586075] systemd[1]: dbus.socket: Deactivated successfully.1097test # [ 3.592650] systemd[1]: Closed D-Bus System Message Bus Socket.1098test # [ 3.593553] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1099test # [ 3.597925] systemd[1]: Stopped Find NixOS closure.1100test # [ 3.599144] systemd[1]: Starting rw-sysroot-nix-store.service...1101test # [ 3.601415] systemd[1]: systemd-sysctl.service: Deactivated successfully.1102test # [ 3.605419] systemd[1]: Stopped Apply Kernel Variables.1103test # [ 3.606068] systemd[1]: systemd-modules-load.service: Deactivated successfully.1104test # [ 3.607531] systemd[1]: Stopped Load Kernel Modules.1105test # [ 3.612418] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1106test # [ 3.613224] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1107test # [ 3.614040] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1108test # [ 3.614746] systemd[1]: Stopped Create System Files and Directories.1109test # [ 3.615347] systemd[1]: Stopped target Local File Systems.1110test # [ 3.615883] systemd[1]: Stopped target Preparation for Local File Systems.1111test # [ 3.616841] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1112test # [ 3.617517] systemd[1]: Stopped Coldplug All udev Devices.1113test # [ 3.618038] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1114test # [ 3.618708] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1115test # [ 3.619381] systemd[1]: Stopped Virtual Console Setup.1116test # [ 3.619873] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1117test # [ 3.623816] systemd[1]: Finished rw-sysroot-nix-store.service.1118test # [ 3.628171] systemd[1]: systemd-udevd.service: Deactivated successfully.1119test # [ 3.629011] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1120test # [ 3.629660] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1121test # [ 3.630300] systemd[1]: Closed udev Control Socket.1122test # [ 3.630756] systemd[1]: Starting Cleanup udev Database...1123test # [ 3.631249] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1124test # [ 3.631930] systemd[1]: Stopped Create Static Device Nodes in /dev.1125test # [ 3.636369] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1126test # [ 3.637097] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1127test # [ 3.637722] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1128test # [ 3.638318] systemd[1]: Stopped Create List of Static Device Nodes.1129test # [ 3.647346] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1130test # [ 3.651958] systemd[1]: Finished Cleanup udev Database.1131test # [ 3.653536] systemd[1]: Reached target Switch Root.1132test # [ 3.653974] systemd[1]: Starting NixOS Activation...1133test # [ 3.712233] initrd-nixos-activation-start[177]: booting system configuration /nix/store/azdvna4rsvl2gjx3dfghyhzs5wgiz9xl-nixos-system-test-test1134test # [ 3.737039] initrd-nixos-activation-start[177]: running activation script...1135test # [ 3.967839] initrd-nixos-activation-start[200]: setting up /etc...1136test # [ 4.062257] initrd-nixos-activation-start[200]: setting up age secrets...1137test # [ 4.180059] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1138test # [ 4.180763] systemd[1]: Finished NixOS Activation.1139test # [ 4.181671] systemd[1]: Starting Switch Root...1140test # [ 4.201255] systemd[1]: Switching root.1141test # [ 4.366040] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1142second # [ 4.383131] systemd[1]: Mounting /sysroot/nix/store...1143second # [ 4.414575] systemd[1]: Mounted /sysroot/nix/store.1144second # [ 4.420229] systemd[1]: Reached target Initrd File Systems.1145second # [ 4.422610] systemd[1]: Starting Find NixOS closure...1146second # [ 4.431974] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1147second # [ 4.462164] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1148second # [ 4.463534] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1149second # [ 4.473982] systemd[1]: Finished Find NixOS closure.1150second # [ 4.475106] systemd[1]: Reached target Initrd Default Target.1151second # [ 4.476982] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1152second # [ 4.501259] systemd[1]: initrd-cleanup.service: Deactivated successfully.1153second # [ 4.502249] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1154second # [ 4.505046] systemd[1]: Stopped target Initrd Default Target.1155second # [ 4.505711] systemd[1]: Stopped target Basic System.1156second # [ 4.507802] systemd[1]: Stopped target Initrd Root Device.1157second # [ 4.508459] systemd[1]: Stopped target Path Units.1158second # [ 4.508893] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1159second # [ 4.510060] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1160second # [ 4.513745] systemd[1]: Stopped target Slice Units.1161second # [ 4.514917] systemd[1]: Stopped target Socket Units.1162second # [ 4.515998] systemd[1]: Stopped target System Initialization.1163second # [ 4.520885] systemd[1]: Stopped target Swaps.1164second # [ 4.521302] systemd[1]: Stopped target Timer Units.1165second # [ 4.521744] systemd[1]: dbus.socket: Deactivated successfully.1166second # [ 4.522247] systemd[1]: Closed D-Bus System Message Bus Socket.1167second # [ 4.522755] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1168second # [ 4.523407] systemd[1]: Stopped Find NixOS closure.1169second # [ 4.523840] systemd[1]: Starting rw-sysroot-nix-store.service...1170second # [ 4.526617] systemd[1]: systemd-sysctl.service: Deactivated successfully.1171second # [ 4.527239] systemd[1]: Stopped Apply Kernel Variables.1172second # [ 4.527734] systemd[1]: systemd-modules-load.service: Deactivated successfully.1173second # [ 4.528515] systemd[1]: Stopped Load Kernel Modules.1174second # [ 4.528977] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1175second # [ 4.529659] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1176second # [ 4.530337] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1177second # [ 4.530964] systemd[1]: Stopped Create System Files and Directories.1178second # [ 4.531531] systemd[1]: Stopped target Local File Systems.1179second # [ 4.544255] systemd[1]: Stopped target Preparation for Local File Systems.1180second # [ 4.545741] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1181second # [ 4.546599] systemd[1]: Stopped Coldplug All udev Devices.1182second # [ 4.547131] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1183second # [ 4.547821] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1184second # [ 4.549332] systemd[1]: Stopped Virtual Console Setup.1185second # [ 4.549790] systemd[1]: systemd-udevd.service: Deactivated successfully.1186second # [ 4.550327] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1187second # [ 4.550908] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1188second # [ 4.551514] systemd[1]: Closed udev Control Socket.1189second # [ 4.551944] systemd[1]: Starting Cleanup udev Database...1190second # [ 4.557956] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1191second # [ 4.560177] systemd[1]: Stopped Create Static Device Nodes in /dev.1192second # [ 4.564226] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1193second # [ 4.565017] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1194second # [ 4.565622] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1195second # [ 4.566208] systemd[1]: Stopped Create List of Static Device Nodes.1196second # [ 4.566755] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1197second # [ 4.568261] systemd[1]: Finished rw-sysroot-nix-store.service.1198second # [ 4.581678] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1199second # [ 4.583006] systemd[1]: Finished Cleanup udev Database.1200second # [ 4.584146] systemd[1]: Reached target Switch Root.1201second # [ 4.587959] systemd[1]: Starting NixOS Activation...1202second # [ 4.660536] initrd-nixos-activation-start[174]: booting system configuration /nix/store/q8s0zh41js3zp5lqqxwmmlfxn7c36mza-nixos-system-second-test1203second # [ 4.691016] initrd-nixos-activation-start[174]: running activation script...1204second # [ 4.925221] initrd-nixos-activation-start[197]: setting up /etc...1205test # [ 4.926720] 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)1206test # [ 4.926824] systemd[1]: Detected virtualization qemu.1207test # [ 4.926884] systemd[1]: Detected architecture arm64.1208test # [ 4.927017] systemd[1]: Detected first boot.1209test # [ 4.929815] systemd[1]: Initializing machine ID from random generator.1210second # [ 5.025791] initrd-nixos-activation-start[197]: setting up age secrets...1211test # [ 5.077623] systemd[1]: bpf-restrict-fs: LSM BPF program attached1212second # [ 5.119847] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1213second # [ 5.121874] systemd[1]: Finished NixOS Activation.1214second # [ 5.122647] systemd[1]: Starting Switch Root...1215second # [ 5.137402] systemd[1]: Switching root.1216test # [ 5.180366] systemd[1]: Applying preset policy.1217second # [ 5.304106] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1218test # [ 5.356694] systemd[1]: Populated /etc with preset unit settings.1219test # [ 5.581808] systemd[1]: initrd-switch-root.service: Deactivated successfully.1220test # [ 5.582104] systemd[1]: Stopped initrd-switch-root.service.1221test # [ 5.584551] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1222test # [ 5.585257] systemd[1]: Created slice Slice /system/getty.1223test # [ 5.586213] systemd[1]: Created slice User and Session Slice.1224test # [ 5.588831] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1225test # [ 5.589412] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1226test # [ 5.589966] systemd[1]: Expecting device /dev/hvc0...1227test # [ 5.590492] systemd[1]: Expecting device /dev/ttyAMA0...1228test # [ 5.593402] systemd[1]: Reached target Local Encrypted Volumes.1229test # [ 5.593918] systemd[1]: Stopped target initrd-fs.target.1230test # [ 5.594440] systemd[1]: Stopped target initrd-root-fs.target.1231test # [ 5.596688] systemd[1]: Stopped target initrd-switch-root.target.1232test # [ 5.597167] systemd[1]: Reached target Virtual Machines and Containers.1233test # [ 5.597673] systemd[1]: Reached target Path Units.1234test # [ 5.598164] systemd[1]: Reached target Remote File Systems.1235test # [ 5.600787] systemd[1]: Reached target Slice Units.1236test # [ 5.601262] systemd[1]: Reached target Swaps.1237test # [ 5.604110] systemd[1]: Listening on Query the User Interactively for a Password.1238test # [ 5.606058] systemd[1]: Listening on Process Core Dump Socket.1239test # [ 5.608970] systemd[1]: Listening on Credential Encryption/Decryption.1240test # [ 5.611252] systemd[1]: Listening on Factory Reset Management.1241test # [ 5.611802] systemd[1]: Listening on Hostname Service Socket.1242test # [ 5.614393] systemd[1]: Starting Journal Log Access Socket...1243test # [ 5.617035] systemd[1]: Listening on Journal Audit Socket.1244test # [ 5.619687] systemd[1]: Listening on Console Output Muting Service Socket.1245test # [ 5.620359] systemd[1]: Listening on Network Management Metrics Varlink Socket.1246test # [ 5.621050] systemd[1]: Listening on Network Management Varlink Socket.1247test # [ 5.621715] systemd[1]: Listening on Network Management Netlink Socket.1248test # [ 5.622475] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1249test # [ 5.625903] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1250test # [ 5.627671] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1251test # [ 5.632851] systemd[1]: Listening on Disk Repartitioning Service Socket.1252test # [ 5.633394] systemd[1]: Listening on Resolve Monitor Varlink Socket.1253test # [ 5.633918] systemd[1]: Listening on Resolve Service Varlink Socket.1254test # [ 5.634466] systemd[1]: Listening on udev Control Socket.1255test # [ 5.636893] systemd[1]: Listening on udev Varlink Socket.1256test # [ 5.639548] systemd[1]: Mounting Huge Pages File System...1257test # [ 5.641745] systemd[1]: Mounting POSIX Message Queue File System...1258test # [ 5.650740] systemd[1]: Mounting Kernel Debug File System...1259test # [ 5.663729] systemd[1]: Mounting Kernel Trace File System...1260test # [ 5.669079] systemd[1]: Starting Create List of Static Device Nodes...1261test # [ 5.670465] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1262test # [ 5.686638] systemd[1]: Mounting Kernel Configuration File System...1263test # [ 5.687087] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1264test # [ 5.688950] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1265test # [ 5.689594] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1266test # [ 5.717822] systemd[1]: Mounting FUSE Control File System...1267test # [ 5.718213] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671268test # [ 5.744801] systemd[1]: Starting Journal Service...1269test # [ 5.754010] systemd[1]: Starting Load Kernel Modules...1270test # [ 5.774629] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1271test # [ 5.784899] systemd[1]: Starting Remount Root and Kernel File Systems...1272test # [ 5.785559] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1273test # [ 5.799664] systemd-journald[325]: Collecting audit messages is enabled.1274test # [ 5.804975] systemd[1]: Starting Coldplug All udev Devices...1275test # [ 5.810428] systemd[1]: Listening on Journal Log Access Socket.1276test # [ 5.805596] systemd[1]: Queued start job for default target Multi-User System.1277test # [ 5.807737] systemd[1]: systemd-journald.service: Deactivated successfully.1278test # [ 5.811440] systemd[1]: Mounted Huge Pages File System.1279test # [ 5.815056] systemd[1]: Started Journal Service.1280test # [ 5.814860] systemd[1]: Mounted POSIX Message Queue File System.1281test # [ 5.815560] systemd[1]: Mounted Kernel Debug File System.1282test # [ 5.819929] systemd[1]: Mounted Kernel Trace File System.1283test # [ 5.823224] systemd[1]: Finished Create List of Static Device Nodes.1284test # [ 5.825942] systemd[1]: Mounted Kernel Configuration File System.1285test # [ 5.852218] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1286test # [ 5.870869] systemd-modules-load[326]: Module 'atkbd' is built in1287test # [ 5.871486] systemd-modules-load[326]: Module 'ctr' is built in1288test # [ 5.878386] systemd-modules-load[326]: Module 'loop' is built in1289second # [ 5.876043] 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)1290second # [ 5.876121] systemd[1]: Detected virtualization qemu.1291second # [ 5.876172] systemd[1]: Detected architecture arm64.1292second # [ 5.876273] systemd[1]: Detected first boot.1293test # [ 5.886967] EXT4-fs (vda): re-mounted 401b84c3-d12b-4087-8e20-e606cb9d1b3f.1294test # [ 5.885150] systemd[1]: Mounted FUSE Control File System.1295second # [ 5.886955] systemd[1]: Initializing machine ID from random generator.1296test # [ 5.892059] systemd[1]: Finished Load Kernel Modules.1297test # [ 5.897423] systemd[1]: Starting Firewall...1298test # [ 5.900216] systemd[1]: Starting Apply Kernel Variables...1299test # [ 5.900770] systemd[1]: Finished Remount Root and Kernel File Systems.1300test # [ 5.905427] systemd[1]: Listening on Disk Image Download Service Socket.1301test # [ 5.907991] systemd[1]: Starting Flush Journal to Persistent Storage...1302test # [ 5.908622] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1303test # [ 5.921025] systemd[1]: Starting Load/Save OS Random Seed...1304test # [ 5.921681] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1305test # [ 5.931674] systemd-oomd[328]: No swap; memory pressure usage will be degraded1306test # [ 5.941971] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1307test # [ 6.007119] systemd-journald[325]: Received client request to flush runtime journal.1308second # [ 6.027242] systemd[1]: bpf-restrict-fs: LSM BPF program attached1309test # [ 6.056493] systemd[1]: Finished Load/Save OS Random Seed.1310test # [ 6.057101] systemd[1]: Reached target First Boot Complete.1311test # [ 6.060859] systemd[1]: Finished Apply Kernel Variables.1312test # [ 6.069623] systemd[1]: Starting Network Name Resolution...1313test # [ 6.070179] systemd[1]: Finished Flush Journal to Persistent Storage.1314test # [ 6.108975] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1315second # [ 6.117795] systemd[1]: Applying preset policy.1316test # [ 6.121683] systemd[1]: Starting Create Static Device Nodes in /dev...1317test # [ 6.225276] systemd-resolved[353]: Positive Trust Anchors:1318test # [ 6.225875] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1319test # [ 6.226761] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161320test # [ 6.227644] systemd-resolved[353]: 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 test1321second # [ 6.276242] systemd[1]: Populated /etc with preset unit settings.1322test # [ 6.364902] systemd[1]: Finished Coldplug All udev Devices.1323test # [ 6.391729] systemd-resolved[353]: Using system hostname 'test'.1324test # [ 6.394375] systemd[1]: Finished Create Static Device Nodes in /dev.1325test # [ 6.395011] systemd[1]: Reached target Preparation for Local File Systems.1326test # [ 6.399150] systemd[1]: Starting Rule-based Manager for Device Events and Files...1327test # [ 6.399899] systemd[1]: Started Network Name Resolution.1328test # [ 6.452670] systemd-udevd[366]: Using default interface naming scheme 'v261'.1329second # [ 6.482087] systemd[1]: initrd-switch-root.service: Deactivated successfully.1330second # [ 6.482805] systemd[1]: Stopped initrd-switch-root.service.1331second # [ 6.486392] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1332second # [ 6.489288] systemd[1]: Created slice Slice /system/getty.1333second # [ 6.490372] systemd[1]: Created slice User and Session Slice.1334second # [ 6.492479] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1335second # [ 6.493081] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1336second # [ 6.493657] systemd[1]: Expecting device /dev/hvc0...1337second # [ 6.494248] systemd[1]: Expecting device /dev/ttyAMA0...1338second # [ 6.494880] systemd[1]: Reached target Local Encrypted Volumes.1339second # [ 6.498475] systemd[1]: Stopped target initrd-fs.target.1340second # [ 6.500062] systemd[1]: Stopped target initrd-root-fs.target.1341second # [ 6.500560] systemd[1]: Stopped target initrd-switch-root.target.1342second # [ 6.501154] systemd[1]: Reached target Virtual Machines and Containers.1343second # [ 6.501735] systemd[1]: Reached target Path Units.1344second # [ 6.502311] systemd[1]: Reached target Remote File Systems.1345second # [ 6.505903] systemd[1]: Reached target Slice Units.1346second # [ 6.506418] systemd[1]: Reached target Swaps.1347second # [ 6.509873] systemd[1]: Listening on Query the User Interactively for a Password.1348second # [ 6.512735] systemd[1]: Listening on Process Core Dump Socket.1349second # [ 6.514362] systemd[1]: Listening on Credential Encryption/Decryption.1350second # [ 6.517755] systemd[1]: Listening on Factory Reset Management.1351second # [ 6.518347] systemd[1]: Listening on Hostname Service Socket.1352second # [ 6.522599] systemd[1]: Starting Journal Log Access Socket...1353test # [ 6.524117] systemd[1]: Started Rule-based Manager for Device Events and Files.1354second # [ 6.524432] systemd[1]: Listening on Journal Audit Socket.1355second # [ 6.527436] systemd[1]: Listening on Console Output Muting Service Socket.1356second # [ 6.528114] systemd[1]: Listening on Network Management Metrics Varlink Socket.1357second # [ 6.528859] systemd[1]: Listening on Network Management Varlink Socket.1358second # [ 6.529525] systemd[1]: Listening on Network Management Netlink Socket.1359second # [ 6.530257] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1360second # [ 6.530808] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1361second # [ 6.535650] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1362second # [ 6.541054] systemd[1]: Listening on Disk Repartitioning Service Socket.1363second # [ 6.541596] systemd[1]: Listening on Resolve Monitor Varlink Socket.1364second # [ 6.542185] systemd[1]: Listening on Resolve Service Varlink Socket.1365second # [ 6.542779] systemd[1]: Listening on udev Control Socket.1366second # [ 6.545288] systemd[1]: Listening on udev Varlink Socket.1367second # [ 6.548064] systemd[1]: Mounting Huge Pages File System...1368second # [ 6.550275] systemd[1]: Mounting POSIX Message Queue File System...1369second # [ 6.559244] systemd[1]: Mounting Kernel Debug File System...1370second # [ 6.568241] systemd[1]: Mounting Kernel Trace File System...1371second # [ 6.577674] systemd[1]: Starting Create List of Static Device Nodes...1372second # [ 6.578136] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1373test # [ 6.582301] systemd[1]: Mounting /run/wrappers...1374second # [ 6.588907] systemd[1]: Mounting Kernel Configuration File System...1375second # [ 6.589378] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1376second # [ 6.596656] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1377second # [ 6.597122] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1378second # [ 6.607541] systemd[1]: Mounting FUSE Control File System...1379second # [ 6.611428] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671380second # [ 6.636831] systemd[1]: Starting Journal Service...1381test # [ 6.647929] systemd[1]: Mounted /run/wrappers.1382test # [ 6.649229] systemd[1]: Reached target Local File Systems.1383second # [ 6.652399] systemd[1]: Starting Load Kernel Modules...1384test # [ 6.652550] systemd[1]: Listening on Boot Loader Control Service Socket.1385test # [ 6.657858] systemd[1]: Starting register-nix-paths.service...1386test # [ 6.660491] systemd[1]: Starting Create SUID/SGID Wrappers...1387test # [ 6.661052] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1388test # [ 6.665813] systemd[1]: Starting Save Transient machine-id to Disk...1389second # [ 6.669631] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1390second # [ 6.685161] systemd[1]: Starting Remount Root and Kernel File Systems...1391second # [ 6.685558] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1392test # [ 6.686057] systemd[1]: Starting Create System Files and Directories...1393test # [ 6.687305] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1394second # [ 6.691881] systemd[1]: Starting Coldplug All udev Devices...1395second # [ 6.693821] systemd[1]: Listening on Journal Log Access Socket.1396second # [ 6.694244] systemd[1]: Mounted Huge Pages File System.1397second # [ 6.694624] systemd[1]: Mounted POSIX Message Queue File System.1398test # [ 6.693689] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1399second # [ 6.700762] systemd[1]: Mounted Kernel Debug File System.1400second # [ 6.701228] systemd[1]: Mounted Kernel Trace File System.1401second # [ 6.720743] systemd[1]: Finished Create List of Static Device Nodes.1402second # [ 6.741458] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1403second # [ 6.755737] systemd-journald[301]: Collecting audit messages is enabled.1404second # [ 6.762552] systemd[1]: Finished Load Kernel Modules.1405second # [ 6.760955] systemd[1]: Queued start job for default target Multi-User System.1406second # [ 6.761816] systemd[1]: systemd-journald.service: Deactivated successfully.1407second # [ 6.762594] systemd-modules-load[302]: Module 'atkbd' is built in1408second # [ 6.763256] systemd-modules-load[302]: Module 'ctr' is built in1409second # [ 6.763923] systemd-modules-load[302]: Module 'loop' is built in1410second # [ 6.777210] systemd[1]: Starting Firewall...1411second # [ 6.780296] systemd[1]: Starting Apply Kernel Variables...1412second # [ 6.780791] systemd[1]: Started Journal Service.1413second # [ 6.802016] systemd[1]: Mounted Kernel Configuration File System.1414second # [ 6.814230] systemd-oomd[303]: No swap; memory pressure usage will be degraded1415second # [ 6.822639] EXT4-fs (vda): re-mounted bdd3a468-9ee5-439f-adf9-a2b8c8670015.1416second # [ 6.828765] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1417second # [ 6.837396] systemd[1]: Finished Remount Root and Kernel File Systems.1418test # [ 6.851260] systemd[1]: Finished Create System Files and Directories.1419second # [ 6.847409] systemd[1]: Listening on Disk Image Download Service Socket.1420test # [ 6.855479] systemd[1]: Starting Rebuild Journal Catalog...1421second # [ 6.853943] systemd[1]: Starting Flush Journal to Persistent Storage...1422second # [ 6.854607] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1423test # [ 6.864374] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1424second # [ 6.862939] systemd[1]: Starting Load/Save OS Random Seed...1425second # [ 6.863556] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1426second # [ 6.873468] systemd[1]: Mounted FUSE Control File System.1427test # [ 6.902805] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1428test # [ 6.908156] systemd[1]: Finished Save Transient machine-id to Disk.1429test # [ 6.943940] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1430second # [ 6.947560] systemd-journald[301]: Received client request to flush runtime journal.1431second # [ 6.998810] systemd[1]: Finished Load/Save OS Random Seed.1432second # [ 6.999461] systemd[1]: Reached target First Boot Complete.1433second # [ 6.999986] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1434second # [ 7.005680] systemd[1]: Finished Apply Kernel Variables.1435second # [ 7.007868] systemd[1]: Starting Network Name Resolution...1436second # [ 7.009660] systemd[1]: Starting Create Static Device Nodes in /dev...1437second # [ 7.010277] systemd[1]: Finished Flush Journal to Persistent Storage.1438test # [ 7.039967] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1439test # [ 7.050339] systemd[1]: Finished Rebuild Journal Catalog.1440test # [ 7.055128] systemd[1]: Starting Update is Completed...1441test # [ 7.068713] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1442test # [ 7.122817] systemd[1]: Finished Update is Completed.1443second # [ 7.164812] systemd-resolved[332]: Positive Trust Anchors:1444second # [ 7.165459] systemd-resolved[332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1445second # [ 7.166336] systemd-resolved[332]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161446second # [ 7.167203] systemd-resolved[332]: 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 test1447second # [ 7.280594] systemd[1]: Finished Coldplug All udev Devices.1448second # [ 7.297536] systemd[1]: Finished Create Static Device Nodes in /dev.1449second # [ 7.298240] systemd[1]: Reached target Preparation for Local File Systems.1450second # [ 7.300660] systemd[1]: Starting Rule-based Manager for Device Events and Files...1451second # [ 7.326495] systemd-resolved[332]: Using system hostname 'second'.1452second # [ 7.331372] systemd[1]: Started Network Name Resolution.1453second # [ 7.368328] systemd-udevd[344]: Using default interface naming scheme 'v261'.1454test # [ 7.381175] (udev-worker)[398]: Network interface NamePolicy= disabled on kernel command line.1455test # [ 7.392434] (udev-worker)[391]: Network interface NamePolicy= disabled on kernel command line.1456second # [ 7.439415] systemd[1]: Started Rule-based Manager for Device Events and Files.1457second # [ 7.480494] systemd[1]: Mounting /run/wrappers...1458second # [ 7.561378] systemd[1]: Mounted /run/wrappers.1459second # [ 7.562399] systemd[1]: Reached target Local File Systems.1460second # [ 7.566905] systemd[1]: Listening on Boot Loader Control Service Socket.1461second # [ 7.571554] systemd[1]: Starting register-nix-paths.service...1462second # [ 7.575459] systemd[1]: Starting Create SUID/SGID Wrappers...1463second # [ 7.576217] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1464second # [ 7.579989] systemd[1]: Starting Save Transient machine-id to Disk...1465test # [ 7.587619] systemd[1]: Finished register-nix-paths.service.1466test # [ 7.594627] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1467second # [ 7.591192] systemd[1]: Starting Create System Files and Directories...1468second # [ 7.591888] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1469test # [ 7.597394] systemd[1]: Finished Create SUID/SGID Wrappers.1470test # [ 7.599680] systemd[1]: Reached target System Initialization.1471test # [ 7.601690] systemd[1]: Started Discard unused filesystem blocks once a week.1472test # [ 7.602372] systemd[1]: Started Daily Cleanup of Temporary Directories.1473test # [ 7.602990] systemd[1]: Reached target Timer Units.1474test # [ 7.603471] systemd[1]: Listening on D-Bus System Message Bus Socket.1475test # [ 7.609964] systemd[1]: Listening on Nix Daemon Socket.1476test # [ 7.615020] systemd[1]: Listening on Authorization Manager Agent Helper.1477test # [ 7.615710] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1478test # [ 7.618690] systemd[1]: Reached target Socket Units.1479second # [ 7.614062] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1480test # [ 7.620645] systemd[1]: Starting D-Bus System Message Bus...1481test # [ 7.664601] mousedev: PS/2 mouse device common for all mice1482test # [ 7.747756] dbus-broker-launch[520]: Looking up NSS user entry for 'systemd-timesync'...1483test # [ 7.756104] dbus-broker-launch[520]: NSS returned no entry for 'systemd-timesync'1484test # [ 7.756821] dbus-broker-launch[520]: Invalid user-name in /nix/store/1635m3fbdc464bxfy61d5x8mp5003m10-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1485second # [ 7.760899] systemd[1]: Finished Create System Files and Directories.1486second # [ 7.770496] systemd[1]: Starting Rebuild Journal Catalog...1487second # [ 7.780934] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1488second # [ 7.784909] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1489test # [ 7.792209] dbus-broker-launch[520]: 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'1490second # [ 7.789614] systemd[1]: Finished Save Transient machine-id to Disk.1491test # [ 7.796778] dbus-broker-launch[520]: 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'1492test # [ 7.802062] dbus-broker-launch[520]: 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'1493test # [ 7.803857] dbus-broker-launch[520]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1494test # [ 7.814206] systemd[1]: Started D-Bus System Message Bus.1495test # [ 7.814738] systemd[1]: Reached target Basic System.1496test # [ 7.819743] systemd[1]: Starting Network Manager Script Dispatcher Service...1497test # [ 7.823441] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1498test # [ 7.829351] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1499test # [ 7.866661] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1500second # [ 7.868939] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1501test # [ 7.874738] systemd[1]: Started backdoor.service.1502test # [ 7.888660] dbus-broker-launch[520]: Ready1503test # [ 7.920905] systemd[1]: Starting Import lastlog data into lastlog2 database...1504second # [ 7.945332] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1505test # [ 7.949929] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1506test # [ 7.965294] systemd[1]: Starting Post-Boot Actions...1507second # [ 7.982432] systemd[1]: Finished Rebuild Journal Catalog.1508second # [ 7.990803] systemd[1]: Starting Update is Completed...1509second # [ 7.996209] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1510test # [ 8.016248] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1511test # [ 8.027540] systemd[1]: Started Reset console on configuration changes.1512test # [ 8.060136] w20vy84jlc9q97pm5z31jcyz4xpvlr4j-wifi-secrets[541]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-open1513second # [ 8.071697] systemd[1]: Finished Update is Completed.1514test # [ 8.076077] nj6y3zgv8ppidbs8s4xlaglnzsav95yb-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-one1515test # connecting to host...1516test # [ 8.105485] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1517test # [ 8.109154] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1518test # [ 8.115370] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1519test # [ 8.120441] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1520test # [ 8.146048] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1521test # [ 8.152333] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1522test # [ 8.155887] systemd[1]: Finished Post-Boot Actions.1523test: Guest shell says: b'Spawning backdoor root shell...\n'1524test # [ 8.180769] systemd[1]: Finished Firewall.1525test # [ 8.199645] systemd[1]: Reached target Preparation for Network.1526test # [ 8.200618] systemd[1]: Listening on Network Management Resolve Hook Socket.1527test # [ 8.211075] systemd[1]: Starting Network Manager...1528test # [ 8.217323] systemd[1]: Starting Network Management...1529test # [ 8.217819] systemd[1]: Started Name Service Cache Daemon (nsncd).1530test: connected to guest root shell1531test: (connecting took 8.48 seconds)1532test: (finished: waiting for the VM to finish booting, in 8.48 seconds)1533test # [ 8.227315] systemd[1]: Started Network Manager Script Dispatcher Service.1534test # [ 8.227991] systemd[1]: Reached target Host and Network Name Lookups.1535test # [ 8.230102] systemd[1]: Reached target User and Group Name Lookups.1536test # [ 8.256533] systemd[1]: Starting User Login Management...1537test # [ 8.272058] nsncd[547]: Oct 15 15:14:34.090 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1538test # [ 8.292150] systemd[1]: Finished Import lastlog data into lastlog2 database.1539second # [ 8.300886] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.1540second # [ 8.352879] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line.1541second # [ 8.395948] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1542second # [ 8.398694] systemd[1]: Finished Create SUID/SGID Wrappers.1543test # [ 8.457463] systemd-logind[599]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1544test # [ 8.459963] systemd-logind[599]: Watching system buttons on /dev/input/event0 (gpio-keys)1545test # [ 8.464519] systemd-logind[599]: New seat seat0.1546test # [ 8.465004] systemd[1]: Started User Login Management.1547test # [ 8.473582] systemd[1]: Starting linger-users.service...1548second # [ 8.539628] mousedev: PS/2 mouse device common for all mice1549second # [ 8.558527] systemd[1]: Finished register-nix-paths.service.1550second # [ 8.559159] systemd[1]: Reached target System Initialization.1551second # [ 8.559700] systemd[1]: Started Discard unused filesystem blocks once a week.1552second # [ 8.563629] systemd[1]: Started Daily Cleanup of Temporary Directories.1553second # [ 8.566814] systemd[1]: Reached target Timer Units.1554second # [ 8.567313] systemd[1]: Listening on D-Bus System Message Bus Socket.1555test # [ 8.577964] cfg80211: Loading compiled-in X.509 certificates for regulatory database1556second # [ 8.567885] systemd[1]: Listening on Nix Daemon Socket.1557second # [ 8.572254] systemd[1]: Listening on Authorization Manager Agent Helper.1558second # [ 8.572877] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1559second # [ 8.573611] systemd[1]: Reached target Socket Units.1560second # [ 8.578719] systemd[1]: Starting D-Bus System Message Bus...1561test # [ 8.609697] systemd[1]: linger-users.service: Deactivated successfully.1562test # [ 8.612933] systemd[1]: Finished linger-users.service.1563test # [ 8.656363] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1564test # [ 8.656854] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1565test # [ 8.670371] systemd[1]: Starting Hostname Service...1566test # [ 8.703032] systemd-networkd[588]: /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.1567second # [ 8.703444] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'...1568test # [ 8.712104] systemd-networkd[588]: /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.1569test # [ 8.713802] systemd-networkd[588]: lo: Link UP1570test # [ 8.714219] systemd-networkd[588]: lo: Gained carrier1571second # [ 8.711640] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync'1572second # [ 8.713720] dbus-broker-launch[501]: Invalid user-name in /nix/store/1635m3fbdc464bxfy61d5x8mp5003m10-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1573test # [ 8.724608] systemd[1]: Started Network Management.1574test # [ 8.727880] systemd-networkd[588]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1575test # [ 8.737789] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1576test # [ 8.738465] systemd-networkd[588]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1577test # [ 8.739172] systemd-networkd[588]: eth1: Link UP1578test # [ 8.739577] systemd-networkd[588]: eth1: Gained carrier1579test # [ 8.748775] systemd-networkd[588]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1580second # [ 8.747811] dbus-broker-launch[501]: 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'1581second # [ 8.753225] dbus-broker-launch[501]: 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'1582second # [ 8.754987] dbus-broker-launch[501]: 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'1583second # [ 8.764764] dbus-broker-launch[501]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1584second # [ 8.766635] systemd[1]: Started D-Bus System Message Bus.1585second # [ 8.767141] systemd[1]: Reached target Basic System.1586second # [ 8.778589] systemd[1]: Starting Network Manager Script Dispatcher Service...1587second # [ 8.779315] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1588second # [ 8.779930] systemd[1]: Started backdoor.service.1589second # [ 8.805230] systemd[1]: Starting Import lastlog data into lastlog2 database...1590test # [ 8.825560] systemd[1]: Started Hostname Service.1591test # [ 8.839051] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1592second # [ 8.835363] dbus-broker-launch[501]: Ready1593second # [ 8.844612] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1594second # [ 8.852288] systemd[1]: Starting Post-Boot Actions...1595second # [ 8.887254] systemd[1]: Started Reset console on configuration changes.1596test # [ 8.899964] systemd-networkd[588]: eth0: Link UP1597test # [ 8.901858] systemd-networkd[588]: eth0: Gained carrier1598test # [ 8.919840] systemd[1]: Started Network Manager.1599test # [ 8.922351] systemd[1]: Reached target Network.1600test # [ 8.927607] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1601test # [ 8.936219] systemd[1]: Starting Permit User Sessions...1602second # [ 8.935416] f1dh9d5kv7zn55z0q9vm7whcf8ckapll-wifi-secrets[519]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets-wg-test-all1603second # [ 8.972508] systemd[1]: Finished Firewall.1604second # [ 8.978456] systemd[1]: Reached target Preparation for Network.1605second # [ 8.979102] systemd[1]: Listening on Network Management Resolve Hook Socket.1606second # connecting to host...1607second # [ 8.992181] systemd[1]: Starting Network Manager...1608second # [ 9.005689] systemd[1]: Starting Network Management...1609second # [ 9.022917] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1610second # [ 9.030141] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1611second # [ 9.047211] systemd[1]: Started Name Service Cache Daemon (nsncd).1612second # [ 9.053833] nsncd[523]: Oct 15 15:14:34.855 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1613test # [ 9.059379] systemd[1]: Finished Permit User Sessions.1614second # [ 9.062260] systemd[1]: Finished Post-Boot Actions.1615test # [ 9.072892] systemd[1]: Started Getty on tty1.1616test # [ 9.082634] systemd[1]: Reached target Login Prompts.1617test # [ 9.099476] systemd-resolved[353]: eth0: Bus client set default route setting: yes1618test # [ 9.104481] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.31619second # [ 9.110466] systemd[1]: Reached target Host and Network Name Lookups.1620second # [ 9.116682] systemd[1]: Reached target User and Group Name Lookups.1621second # [ 9.135454] systemd[1]: Starting User Login Management...1622second # [ 9.167969] systemd[1]: Started Network Manager Script Dispatcher Service.1623second # [ 9.196788] systemd[1]: Finished Import lastlog data into lastlog2 database.1624test # [ 9.210386] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1625test # [ 9.213564] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1626test # [ 9.223693] systemd[1]: Reached target Multi-User System.1627test # [ 9.228107] systemd[1]: Startup finished in 769ms (kernel) + 3.694s (initrd) + 4.761s (userspace) = 9.225s.1628second # [ 9.281597] systemd-logind[563]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1629second # [ 9.285044] systemd-logind[563]: Watching system buttons on /dev/input/event0 (gpio-keys)1630second # [ 9.286076] systemd-logind[563]: New seat seat0.1631second # [ 9.291489] systemd[1]: Started User Login Management.1632second # [ 9.297152] systemd[1]: Starting linger-users.service...1633test # [ 9.368876] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31634second # [ 9.402106] cfg80211: Loading compiled-in X.509 certificates for regulatory database1635second # [ 9.435456] systemd[1]: linger-users.service: Deactivated successfully.1636second # [ 9.436587] systemd[1]: Finished linger-users.service.1637second # [ 9.479375] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1638second # [ 9.479912] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1639second # [ 9.481339] systemd[1]: Starting Hostname Service...1640test: (finished: waiting for unit NetworkManager.service, in 9.76 seconds)1641test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1642test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds)1643test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open1644second # [ 9.528238] systemd-networkd[541]: /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.1645second # [ 9.536234] systemd-networkd[541]: /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.1646second # [ 9.537925] systemd-networkd[541]: lo: Link UP1647second # [ 9.538326] systemd-networkd[541]: lo: Gained carrier1648test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets-wg-test-open, in 0.02 seconds)1649test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1650second # [ 9.548043] systemd[1]: Started Network Management.1651second # [ 9.552197] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1652second # [ 9.561109] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1653second # [ 9.561932] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1654second # [ 9.562555] systemd-networkd[541]: eth1: Link UP1655second # [ 9.562957] systemd-networkd[541]: eth1: Gained carrier1656second # [ 9.563415] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1657second # [ 9.633005] systemd[1]: Started Hostname Service.1658second # [ 9.648914] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1659test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.12 seconds)1660(finished: run the VM test script, in 10.34 seconds)1661second # [ 9.704743] systemd-networkd[541]: eth0: Link UP1662second # [ 9.706715] systemd-networkd[541]: eth0: Gained carrier1663second # [ 9.726038] systemd[1]: Started Network Manager.1664second # [ 9.727856] systemd[1]: Reached target Network.1665second # [ 9.734097] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1666second # [ 9.739127] systemd[1]: Starting Permit User Sessions...1667test # [ 9.803619] systemd[1]: Starting Virtual Console Setup...1668test # [ 9.836390] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1669test # [ 9.850599] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1670test # [ 9.854249] systemd[1]: Stopped Virtual Console Setup.1671test # [ 9.857722] systemd[1]: Starting Virtual Console Setup...1672test # [ 9.862478] systemd-logind[599]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1673test script finished in 10.54s1674cleanup1675kill QemuMachine (pid 47)1676second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1677second # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1678second # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1679second # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1680test # [ 9.969012] systemd-vconsole-setup[701]: Configuration of first virtual console was skipped, ignoring remaining ones.1681test # [ 9.971952] systemd[1]: Finished Virtual Console Setup.1682kill QemuMachine (pid 48)1683test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1684test # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1685test # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1686test # [2026-10-15T15:14:35Z INFO virtiofsd] Client disconnected, shutting down1687(finished: cleanup, in 0.32 seconds)