vm-test-run-wifi
checks.aarch64-linux.wifi
· build #293
· raw
1build01: building gwqqqgmrjyb2nrg9ikdkp13crwqxsqhy-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.10 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14test # Disk image does not exist, creating the virtualisation disk image...15test # Formatting '/build/vm-state-test/tmp.Pf35Fn1vJa', fmt=raw size=107374182416test # mke2fs 1.47.4 (6-Mar-2025)17test # Discarding device blocks: 0/262144 done18test # Creating filesystem with 262144 4k blocks and 65536 inodes19test # Filesystem UUID: 2bb49cb6-9813-4735-840e-d427251afe9220test # Superblock backups stored on blocks:21test # 32768, 98304, 163840, 22937622test # 23test # Allocating group tables: 0/8 done24test # Writing inode tables: 0/8 done25test # Creating journal (8192 blocks): done26test # Writing superblocks and filesystem accounting information: 0/8 done27test # 28test # Virtualisation disk image created.29test # Starting virtiofs daemons...30test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32test # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...33test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35test # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...36test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)37test # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether38test # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...39test # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests40test # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests41test # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests42test: QEMU running (pid 48)43second: QEMU running (pid 47)44second # Disk image does not exist, creating the virtualisation disk image...45second # Formatting '/build/vm-state-second/tmp.mGUrbNkFAe', fmt=raw size=107374182446second # mke2fs 1.47.4 (6-Mar-2025)47second # Discarding device blocks: 0/262144 done48second # Creating filesystem with 262144 4k blocks and 65536 inodes49second # Filesystem UUID: 6b3de305-291a-48de-bcfe-ede0e3f362bc50second # Superblock backups stored on blocks:51second # 32768, 98304, 163840, 22937652second # 53second # Allocating group tables: 0/8 done54second # Writing inode tables: 0/8 done55second # Creating journal (8192 blocks): done56(finished: start all VMs, in 2.46 seconds)57test: waiting for unit NetworkManager.service58second # Writing superblocks and filesystem accounting information: 0/8 done59second # 60second # Virtualisation disk image created.61second # Starting virtiofs daemons...62second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)63second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether64second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)65second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether66second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-10-22T10:03:19Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...70second # [2026-10-22T10:03:19Z INFO virtiofsd] Waiting for vhost-user socket connection...71second # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests72second # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests73second # [2026-10-22T10:03:19Z INFO virtiofsd] Client connected, servicing requests74test: waiting for the VM to finish booting75test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76test # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @179205830077test # [ 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 empty88test # [ 0.000000] Device empty89test # [ 0.000000] Movable zone start for each node90test # [ 0.000000] Early memory node ranges91test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]92test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]93test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000094test # [ 0.000000] psci: probing for conduit method from DT.95test # [ 0.000000] psci: PSCIv1.3 detected in firmware.96test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs97test # [ 0.000000] psci: Trusted OS migration not required98test # [ 0.000000] psci: SMC Calling Convention v1.199test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100test # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296101test # [ 0.000000] Detected PIPT I-cache on CPU0102test # [ 0.000000] CPU features: detected: GICv3 CPU interface103test # [ 0.000000] CPU features: detected: Spectre-v4104test # [ 0.000000] CPU features: detected: Spectre-BHB105test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR106test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)107test # [ 0.000000] CPU features: detected: ARM erratum 1418040108test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion109test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing110test # [ 0.000000] alternatives: applying boot alternatives111test # [ 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/pkmrsr198f79v866bcannckgyyj06krl-nixos-system-test-test/init regInfo=/nix/.ro-store/2dg5cmz00r2dx01w8ryp0m7lhcabzf5w-closure-info/registration console=ttyAMA0,115200n8 console=tty0112test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/2dg5cmz00r2dx01w8ryp0m7lhcabzf5w-closure-info/registration", will be passed to user space.113test # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes114test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)115test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)116test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB117test # [ 0.000000] software IO TLB: area num 1.118test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)119test # [ 0.000000] Fallback order for Node 0: 0120test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144121test # [ 0.000000] Policy zone: DMA122test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off123test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1124test # [ 0.000000] allocated 2097152 bytes of page_ext125test # [ 0.000000] ftrace: allocating 75301 entries in 296 pages126test # [ 0.000000] ftrace: allocated 296 pages with 3 groups127test # [ 0.000000] rcu: Hierarchical RCU implementation.128test # [ 0.000000] rcu: RCU event tracing is enabled.129test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.130test # [ 0.000000] Trampoline variant of Tasks RCU enabled.131test # [ 0.000000] Rude variant of Tasks RCU enabled.132test # [ 0.000000] Tracing variant of Tasks RCU enabled.133test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.134test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1135test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.136test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.137test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.138test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0139test # [ 0.000000] GICv3: 256 SPIs implemented140test # [ 0.000000] GICv3: 0 Extended SPIs implemented141test # [ 0.000000] Root IRQ handler: gic_handle_irq142test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI143test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0144test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000145test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]146test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)147test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)148test # [ 0.000000] GICv3: using LPI property table @0x0000000045110000149test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000150test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.151test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns152test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040153test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).154test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns155test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns156test # [ 0.000069] arm-pv: using stolen time PV157test # [ 0.000467] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)158test # [ 0.000794] Console: colour dummy device 80x25159test # [ 0.000806] printk: legacy console [tty0] enabled160test # [ 0.001032] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)161test # [ 0.001038] pid_max: default: 32768 minimum: 301162test # [ 0.001133] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima163test # [ 0.001296] landlock: Up and running.164test # [ 0.001298] Yama: becoming mindful.165test # [ 0.001739] LSM support for eBPF active166test # [ 0.002260] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)167test # [ 0.002281] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)168second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]169test # [ 0.005490] rcu: Hierarchical SRCU implementation.170test # [ 0.005500] rcu: Max phase no-delay instances is 1000.171second # [ 0.000000] Linux version 6.18.57 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT @1792058300172second # [ 0.000000] KASLR enabled173test # [ 0.006547] fsl-mc MSI: its@8080000 domain created174second # [ 0.000000] random: crng init done175second # [ 0.000000] Machine model: linux,dummy-virt176test # [ 0.006738] EFI services will not be available.177test # [ 0.006847] smp: Bringing up secondary CPUs ...178test # [ 0.006882] smp: Brought up 1 node, 1 CPU179test # [ 0.006885] SMP: Total of 1 processors activated.180test # [ 0.006887] CPU: All CPU(s) started at EL1181second # [ 0.000000] efi: UEFI not found.182test # [ 0.006903] CPU features: detected: 32-bit EL0 Support183second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT184second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]185second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]186second # [ 0.000000] Zone ranges:187test # [ 0.006909] CPU features: detected: Data cache clean to the PoU not required for I/D coherence188second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]189test # [ 0.006913] CPU features: detected: Common not Private translations190second # [ 0.000000] DMA32 empty191second # [ 0.000000] Normal empty192second # [ 0.000000] Device empty193test # [ 0.006915] CPU features: detected: CRC32 instructions194second # [ 0.000000] Movable zone start for each node195second # [ 0.000000] Early memory node ranges196test # [ 0.006918] CPU features: detected: Data cache clean to Point of Persistence197test # [ 0.006921] CPU features: detected: Enhanced Virtualization Traps198second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]199test # [ 0.006933] CPU features: detected: RCpc load-acquire (LDAPR)200second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]201test # [ 0.006935] CPU features: detected: LSE atomic instructions202test # [ 0.006938] CPU features: detected: Privileged Access Never203second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000204second # [ 0.000000] psci: probing for conduit method from DT.205second # [ 0.000000] psci: PSCIv1.3 detected in firmware.206test # [ 0.006940] CPU features: detected: PMUv3207test # [ 0.006942] CPU features: detected: RAS Extension Support208second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs209second # [ 0.000000] psci: Trusted OS migration not required210test # [ 0.006946] CPU features: detected: Speculative Store Bypassing Safe (SSBS)211second # [ 0.000000] psci: SMC Calling Convention v1.1212test # [ 0.006972] alternatives: applying system-wide alternatives213second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)214test # [ 0.008685] CPU features: detected: Hardware dirty bit management on CPU0215second # [ 0.000000] percpu: Embedded 76 pages/cpu s187672 r8192 d115432 u311296216second # [ 0.000000] Detected PIPT I-cache on CPU0217test # [ 0.009070] Memory: 885596K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 121652K reserved, 32768K cma-reserved)218second # [ 0.000000] CPU features: detected: GICv3 CPU interface219test # [ 0.009669] devtmpfs: initialized220second # [ 0.000000] CPU features: detected: Spectre-v4221test # [ 0.011405] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)222second # [ 0.000000] CPU features: detected: Spectre-BHB223second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR224test # [ 0.011439] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).225second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)226test # [ 0.011600] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL227test # [ 0.011606] 0 pages in range for non-PLT usage228second # [ 0.000000] CPU features: detected: ARM erratum 1418040229test # [ 0.011607] 506368 pages in range for PLT usage230second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion231test # [ 0.011766] pinctrl core: initialized pinctrl subsystem232test # [ 0.013077] DMI not present or invalid.233second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing234second # [ 0.000000] alternatives: applying boot alternatives235test # [ 0.018593] NET: Registered PF_NETLINK/PF_ROUTE protocol family236test # [ 0.022908] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations237test # [ 0.023091] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations238test # [ 0.023238] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations239test # [ 0.023276] audit: initializing netlink subsys (disabled)240second # [ 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/y5jasjl3fkyjjl1kjdgsj4s9354nqi6w-nixos-system-second-test/init regInfo=/nix/.ro-store/4nydfb7mxxbl8bzi13klhyq74bgs1cjs-closure-info/registration console=ttyAMA0,115200n8 console=tty0241test # [ 0.024069] thermal_sys: Registered thermal governor 'fair_share'242test # [ 0.024073] thermal_sys: Registered thermal governor 'bang_bang'243test # [ 0.024076] thermal_sys: Registered thermal governor 'step_wise'244second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/4nydfb7mxxbl8bzi13klhyq74bgs1cjs-closure-info/registration", will be passed to user space.245test # [ 0.024079] thermal_sys: Registered thermal governor 'user_space'246second # [ 0.000000] printk: log buffer data + meta data: 1048576 + 3670016 = 4718592 bytes247test # [ 0.024081] thermal_sys: Registered thermal governor 'power_allocator'248second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)249test # [ 0.024138] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1250test # [ 0.024151] cpuidle: using governor ladder251second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)252test # [ 0.024157] cpuidle: using governor menu253second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB254test # [ 0.024302] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.255second # [ 0.000000] software IO TLB: area num 1.256test # [ 0.024330] ASID allocator initialised with 32768 entries257test # [ 0.025093] Serial: AMBA PL011 UART driver258second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)259second # [ 0.000000] Fallback order for Node 0: 0260test # [ 0.029412] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1261test # [ 0.029628] printk: console [ttyAMA0] enabled262second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144263second # [ 0.000000] Policy zone: DMA264second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off265second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1266second # [ 0.000000] allocated 2097152 bytes of page_ext267second # [ 0.000000] ftrace: allocating 75301 entries in 296 pages268second # [ 0.000000] ftrace: allocated 296 pages with 3 groups269second # [ 0.000000] rcu: Hierarchical RCU implementation.270second # [ 0.000000] rcu: RCU event tracing is enabled.271second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.272second # [ 0.000000] Trampoline variant of Tasks RCU enabled.273second # [ 0.000000] Rude variant of Tasks RCU enabled.274second # [ 0.000000] Tracing variant of Tasks RCU enabled.275second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.276second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1277second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.278second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.279second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.280second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0281second # [ 0.000000] GICv3: 256 SPIs implemented282second # [ 0.000000] GICv3: 0 Extended SPIs implemented283second # [ 0.000000] Root IRQ handler: gic_handle_irq284second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI285second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0286second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000287second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]288second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @450f0000 (indirect, esz 8, psz 64K, shr 1)289second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @45100000 (flat, esz 8, psz 64K, shr 1)290second # [ 0.000000] GICv3: using LPI property table @0x0000000045110000291second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000045120000292second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.293second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns294second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040295second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).296second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns297test # [ 0.103788] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages298second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns299second # [ 0.000064] arm-pv: using stolen time PV300test # [ 0.103796] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page301test # [ 0.103799] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages302second # [ 0.000454] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)303test # [ 0.103801] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page304second # [ 0.000753] Console: colour dummy device 80x25305second # [ 0.000767] printk: legacy console [tty0] enabled306test # [ 0.103803] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages307test # [ 0.103805] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page308second # [ 0.001003] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)309test # [ 0.103807] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages310second # [ 0.001008] pid_max: default: 32768 minimum: 301311test # [ 0.103808] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page312second # [ 0.001096] LSM: initializing lsm=lockdown,capability,landlock,yama,bpf,ima313test # [ 0.105686] fbcon: Taking over console314second # [ 0.001246] landlock: Up and running.315test # [ 0.105697] ACPI: Interpreter disabled.316second # [ 0.001248] Yama: becoming mindful.317second # [ 0.001686] LSM support for eBPF active318second # [ 0.002126] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)319second # [ 0.002151] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)320second # [ 0.005404] rcu: Hierarchical SRCU implementation.321second # [ 0.005418] rcu: Max phase no-delay instances is 1000.322second # [ 0.006565] fsl-mc MSI: its@8080000 domain created323second # [ 0.006775] EFI services will not be available.324second # [ 0.006908] smp: Bringing up secondary CPUs ...325test # [ 0.120226] iommu: Default domain type: Translated326second # [ 0.006946] smp: Brought up 1 node, 1 CPU327test # [ 0.120233] iommu: DMA domain TLB invalidation policy: strict mode328second # [ 0.006949] SMP: Total of 1 processors activated.329test # [ 0.120597] SCSI subsystem initialized330second # [ 0.006951] CPU: All CPU(s) started at EL1331test # [ 0.120854] usbcore: registered new interface driver usbfs332second # [ 0.006968] CPU features: detected: 32-bit EL0 Support333test # [ 0.120890] usbcore: registered new interface driver hub334second # [ 0.006974] CPU features: detected: Data cache clean to the PoU not required for I/D coherence335test # [ 0.120897] usbcore: registered new device driver usb336test # [ 0.121124] pps_core: LinuxPPS API ver. 1 registered337second # [ 0.006978] CPU features: detected: Common not Private translations338second # [ 0.006981] CPU features: detected: CRC32 instructions339test # [ 0.121127] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>340test # [ 0.121131] PTP clock support registered341second # [ 0.006984] CPU features: detected: Data cache clean to Point of Persistence342test # [ 0.121158] EDAC MC: Ver: 3.0.0343test # [ 0.121731] scmi_core: SCMI protocol bus registered344test # [ 0.122012] FPGA manager framework345test # [ 0.122931] vgaarb: loaded346second # [ 0.006987] CPU features: detected: Enhanced Virtualization Traps347test # [ 0.123301] clocksource: Switched to clocksource arch_sys_counter348second # [ 0.007001] CPU features: detected: RCpc load-acquire (LDAPR)349second # [ 0.007003] CPU features: detected: LSE atomic instructions350second # [ 0.007005] CPU features: detected: Privileged Access Never351second # [ 0.007008] CPU features: detected: PMUv3352second # [ 0.007010] CPU features: detected: RAS Extension Support353second # [ 0.007014] CPU features: detected: Speculative Store Bypassing Safe (SSBS)354second # [ 0.007044] alternatives: applying system-wide alternatives355second # [ 0.009038] CPU features: detected: Hardware dirty bit management on CPU0356second # [ 0.009292] Memory: 885632K/1048576K available (24640K kernel code, 10246K rwdata, 26664K rodata, 7808K init, 2086K bss, 121656K reserved, 32768K cma-reserved)357second # [ 0.009931] devtmpfs: initialized358second # [ 0.012386] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)359second # [ 0.012414] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).360second # [ 0.012547] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL361second # [ 0.012552] 0 pages in range for non-PLT usage362second # [ 0.012553] 506368 pages in range for PLT usage363second # [ 0.012681] pinctrl core: initialized pinctrl subsystem364second # [ 0.013611] DMI not present or invalid.365second # [ 0.018995] NET: Registered PF_NETLINK/PF_ROUTE protocol family366second # [ 0.022656] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations367second # [ 0.022816] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations368second # [ 0.022965] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations369test # [ 0.137215] VFS: Disk quotas dquot_6.6.0370second # [ 0.022999] audit: initializing netlink subsys (disabled)371test # [ 0.137246] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)372second # [ 0.023926] thermal_sys: Registered thermal governor 'fair_share'373test # [ 0.137607] netfs: FS-Cache loaded374second # [ 0.023931] thermal_sys: Registered thermal governor 'bang_bang'375test # [ 0.137735] pnp: PnP ACPI: disabled376second # [ 0.023935] thermal_sys: Registered thermal governor 'step_wise'377second # [ 0.023938] thermal_sys: Registered thermal governor 'user_space'378second # [ 0.023940] thermal_sys: Registered thermal governor 'power_allocator'379second # [ 0.023987] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1380second # [ 0.023999] cpuidle: using governor ladder381second # [ 0.024003] cpuidle: using governor menu382test # [ 0.141008] NET: Registered PF_INET protocol family383second # [ 0.024159] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.384test # [ 0.141199] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)385second # [ 0.024186] ASID allocator initialised with 32768 entries386second # [ 0.024940] Serial: AMBA PL011 UART driver387second # [ 0.029371] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1388second # [ 0.029549] printk: console [ttyAMA0] enabled389second # [ 0.107390] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages390second # [ 0.107400] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page391second # [ 0.107402] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages392second # [ 0.107404] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page393second # [ 0.107406] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages394second # [ 0.107408] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page395second # [ 0.107410] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages396second # [ 0.107412] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page397second # [ 0.108432] fbcon: Taking over console398second # [ 0.108442] ACPI: Interpreter disabled.399second # [ 0.109116] iommu: Default domain type: Translated400second # [ 0.109120] iommu: DMA domain TLB invalidation policy: strict mode401second # [ 0.109836] SCSI subsystem initialized402second # [ 0.117320] usbcore: registered new interface driver usbfs403second # [ 0.117364] usbcore: registered new interface driver hub404second # [ 0.117375] usbcore: registered new device driver usb405second # [ 0.117635] pps_core: LinuxPPS API ver. 1 registered406second # [ 0.117638] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>407second # [ 0.117643] PTP clock support registered408second # [ 0.117671] EDAC MC: Ver: 3.0.0409second # [ 0.122676] scmi_core: SCMI protocol bus registered410second # [ 0.123053] FPGA manager framework411test # [ 0.169038] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)412test # [ 0.169065] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)413test # [ 0.169078] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)414test # [ 0.169118] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)415test # [ 0.169185] TCP: Hash tables configured (established 8192 bind 8192)416test # [ 0.169296] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)417test # [ 0.169326] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)418test # [ 0.169349] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)419test # [ 0.169708] NET: Registered PF_UNIX/PF_LOCAL protocol family420test # [ 0.170120] NET: Registered PF_XDP protocol family421test # [ 0.170142] PCI: CLS 0 bytes, default 64422test # [ 0.170381] Trying to unpack rootfs image as initramfs...423second # [ 0.131219] vgaarb: loaded424second # [ 0.131798] clocksource: Switched to clocksource arch_sys_counter425second # [ 0.138045] VFS: Disk quotas dquot_6.6.0426second # [ 0.138075] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)427test # [ 0.183885] kvm [1]: HYP mode not available428second # [ 0.141567] netfs: FS-Cache loaded429second # [ 0.141690] pnp: PnP ACPI: disabled430second # [ 0.143604] NET: Registered PF_INET protocol family431second # [ 0.145204] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)432second # [ 0.174190] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)433second # [ 0.174214] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)434second # [ 0.174226] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)435second # [ 0.174267] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)436second # [ 0.174333] TCP: Hash tables configured (established 8192 bind 8192)437second # [ 0.174449] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)438second # [ 0.174477] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)439second # [ 0.174513] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)440second # [ 0.174632] NET: Registered PF_UNIX/PF_LOCAL protocol family441second # [ 0.174662] NET: Registered PF_XDP protocol family442second # [ 0.174681] PCI: CLS 0 bytes, default 64443second # [ 0.175300] Trying to unpack rootfs image as initramfs...444second # [ 0.187820] kvm [1]: HYP mode not available445test # [ 0.255743] Initialise system trusted keyrings446test # [ 0.255801] Key type blacklist registered447test # [ 0.255908] workingset: timestamp_bits=42 max_order=18 bucket_order=0448test # [ 0.256275] squashfs: version 4.0 (2009/01/31) Phillip Lougher449test # [ 0.256311] 9p: Installing v9fs 9p2000 file system support450test # [ 0.256404] integrity: Platform Keyring initialized451test # [ 0.256410] integrity: Machine keyring initialized452test # [ 0.278217] Key type asymmetric registered453test # [ 0.278236] Asymmetric key parser 'x509' registered454test # [ 0.278309] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)455test # [ 0.278429] io scheduler mq-deadline registered456test # [ 0.278432] io scheduler kyber registered457test # [ 0.291484] pl061_gpio 9030000.pl061: PL061 GPIO chip registered458test # [ 0.291878] ledtrig-cpu: registered to indicate activity on CPUs459test # [ 0.292198] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:460test # [ 0.292215] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000461test # [ 0.292228] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000462test # [ 0.292232] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000463test # [ 0.292261] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits464test # [ 0.292284] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]465test # [ 0.292363] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00466test # [ 0.292380] pci_bus 0000:00: root bus resource [bus 00-ff]467test # [ 0.292383] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]468test # [ 0.292386] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]469test # [ 0.292388] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]470test # [ 0.292489] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint471test # [ 0.292933] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint472test # [ 0.293057] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]473test # [ 0.293066] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]474test # [ 0.293085] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]475test # [ 0.293093] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]476test # [ 0.293392] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint477test # [ 0.293512] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]478test # [ 0.293520] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]479test # [ 0.293539] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]480test # [ 0.293835] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint481test # [ 0.293952] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]482test # [ 0.293960] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]483test # [ 0.293978] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484test # [ 0.294270] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint485test # [ 0.294751] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]486test # [ 0.294760] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]487second # [ 0.256249] Initialise system trusted keyrings488second # [ 0.256311] Key type blacklist registered489test # [ 0.294778] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]490second # [ 0.256549] workingset: timestamp_bits=42 max_order=18 bucket_order=0491test # [ 0.294787] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]492second # [ 0.256923] squashfs: version 4.0 (2009/01/31) Phillip Lougher493test # [ 0.295112] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint494second # [ 0.256958] 9p: Installing v9fs 9p2000 file system support495test # [ 0.295233] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]496second # [ 0.257063] integrity: Platform Keyring initialized497second # [ 0.257070] integrity: Machine keyring initialized498test # [ 0.295250] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499test # [ 0.316326] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint500test # [ 0.316454] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]501test # [ 0.316472] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]502test # [ 0.316875] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint503test # [ 0.316990] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]504test # [ 0.317172] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint505test # [ 0.317292] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]506test # [ 0.317308] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507test # [ 0.317599] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint508test # [ 0.317718] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]509second # [ 0.279245] Key type asymmetric registered510test # [ 0.317735] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511second # [ 0.279259] Asymmetric key parser 'x509' registered512test # [ 0.318026] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint513second # [ 0.279378] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)514test # [ 0.318144] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]515test # [ 0.318161] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516test # [ 0.318454] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint517test # [ 0.318616] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]518test # [ 0.318689] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]519test # [ 0.318707] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520test # [ 0.319009] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint521test # [ 0.319128] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]522test # [ 0.319136] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]523test # [ 0.319153] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524second # [ 0.287877] io scheduler mq-deadline registered525second # [ 0.287888] io scheduler kyber registered526second # [ 0.290283] pl061_gpio 9030000.pl061: PL061 GPIO chip registered527second # [ 0.290655] ledtrig-cpu: registered to indicate activity on CPUs528second # [ 0.290980] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:529second # [ 0.290998] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000530second # [ 0.291011] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000531second # [ 0.291016] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000532second # [ 0.291039] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits533second # [ 0.291058] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]534second # [ 0.291123] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00535second # [ 0.291140] pci_bus 0000:00: root bus resource [bus 00-ff]536test # [ 0.339769] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned537second # [ 0.291143] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]538test # [ 0.339780] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned539second # [ 0.291146] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]540second # [ 0.291149] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]541test # [ 0.339783] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned542test # [ 0.339819] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned543second # [ 0.291238] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint544test # [ 0.339851] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned545second # [ 0.291654] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint546second # [ 0.291774] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]547test # [ 0.339884] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned548second # [ 0.291782] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]549test # [ 0.339914] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned550test # [ 0.339944] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned551test # [ 0.339974] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned552test # [ 0.340004] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned553test # [ 0.340035] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned554test # [ 0.340064] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned555test # [ 0.340130] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned556test # [ 0.340159] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned557test # [ 0.340203] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned558test # [ 0.340215] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned559test # [ 0.340227] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned560test # [ 0.340239] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned561test # [ 0.340257] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned562test # [ 0.340269] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned563test # [ 0.340282] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned564test # [ 0.340294] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned565test # [ 0.340307] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned566test # [ 0.340319] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned567second # [ 0.307852] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568test # [ 0.340331] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned569second # [ 0.307867] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]570test # [ 0.340343] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned571second # [ 0.308204] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint572test # [ 0.340354] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned573second # [ 0.308328] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]574test # [ 0.340366] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned575second # [ 0.308337] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]576test # [ 0.340378] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned577second # [ 0.308355] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578test # [ 0.340389] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned579second # [ 0.308651] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint580test # [ 0.340401] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned581second # [ 0.308769] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]582test # [ 0.340418] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]583second # [ 0.308778] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]584test # [ 0.340422] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]585second # [ 0.308795] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]586test # [ 0.340425] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]587second # [ 0.309100] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint588test # [ 0.340947] pci 0000:00:07.0: enabling device (0000 -> 0002)589second # [ 0.309220] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]590second # [ 0.309228] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]591second # [ 0.309246] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]592second # [ 0.309254] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]593second # [ 0.309549] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint594second # [ 0.309670] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]595second # [ 0.309687] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]596second # [ 0.309989] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint597second # [ 0.310109] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]598second # [ 0.310126] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]599second # [ 0.310398] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint600second # [ 0.310515] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]601second # [ 0.310693] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint602second # [ 0.310813] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]603second # [ 0.310830] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]604second # [ 0.311118] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint605second # [ 0.311236] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]606second # [ 0.311254] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]607second # [ 0.311544] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint608second # [ 0.311663] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]609second # [ 0.311680] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]610second # [ 0.328706] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint611second # [ 0.328909] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]612second # [ 0.328918] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]613second # [ 0.328936] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]614second # [ 0.329228] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint615second # [ 0.329343] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]616second # [ 0.329351] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]617test # [ 0.375626] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)618second # [ 0.329369] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]619second # [ 0.329788] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned620second # [ 0.329795] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned621second # [ 0.329798] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned622second # [ 0.329830] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned623second # [ 0.329859] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned624second # [ 0.329891] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned625second # [ 0.329920] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned626second # [ 0.329948] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned627second # [ 0.329976] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned628second # [ 0.330004] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned629second # [ 0.330032] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned630second # [ 0.330059] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned631second # [ 0.330144] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned632second # [ 0.330192] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned633second # [ 0.330204] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned634second # [ 0.330216] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned635second # [ 0.330227] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned636second # [ 0.330239] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned637second # [ 0.330256] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned638second # [ 0.330267] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned639second # [ 0.330280] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned640second # [ 0.330291] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned641second # [ 0.330303] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned642second # [ 0.330315] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned643second # [ 0.330327] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned644second # [ 0.330339] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned645second # [ 0.330350] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned646second # [ 0.330368] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned647second # [ 0.330380] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned648second # [ 0.330391] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned649second # [ 0.330402] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned650second # [ 0.330419] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]651second # [ 0.330423] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]652second # [ 0.330426] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]653second # [ 0.330921] pci 0000:00:07.0: enabling device (0000 -> 0002)654test # [ 0.387920] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)655test # [ 0.389552] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)656test # [ 0.428598] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)657test # [ 0.430550] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)658test # [ 0.433618] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)659second # [ 0.366037] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)660test # [ 0.460652] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)661test # [ 0.462504] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)662test # [ 0.465762] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)663test # [ 0.475581] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)664test # [ 0.478000] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)665test # [ 0.483065] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled666test # [ 0.484768] msm_serial: driver initialized667test # [ 0.484838] SuperH (H)SCI(F) driver initialized668test # [ 0.484877] STM32 USART driver initialized669second # [ 0.437551] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)670second # [ 0.456368] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)671second # [ 0.458226] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)672test # [ 0.517207] loop: module loaded673test # [ 0.517358] virtio_blk virtio2: 1/0/0 default/read/poll queues674test # [ 0.518052] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)675test # [ 0.521447] megasas: 07.734.00.00-rc1676test # [ 0.521919] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]677second # [ 0.470098] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)678second # [ 0.488598] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)679second # [ 0.490536] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)680test # [ 0.525279] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000681test # [ 0.525301] Intel/Sharp Extended Query Table at 0x0031682test # [ 0.527057] Using buffer write method683test # [ 0.527110] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]684second # [ 0.493395] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)685second # [ 0.495685] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)686test # [ 0.553323] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000687test # [ 0.553346] Intel/Sharp Extended Query Table at 0x0031688second # [ 0.510069] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)689test # [ 0.563336] Using buffer write method690test # [ 0.563353] Concatenating MTD devices:691test # [ 0.563355] (0): "0.flash"692test # [ 0.563357] (1): "0.flash"693test # [ 0.563359] into device "0.flash"694second # [ 0.520364] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)695second # [ 0.532549] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled696second # [ 0.533611] msm_serial: driver initialized697second # [ 0.533692] SuperH (H)SCI(F) driver initialized698second # [ 0.533733] STM32 USART driver initialized699second # [ 0.558225] loop: module loaded700second # [ 0.558417] virtio_blk virtio2: 1/0/0 default/read/poll queues701second # [ 0.559080] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)702second # [ 0.572234] megasas: 07.734.00.00-rc1703second # [ 0.572757] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]704second # [ 0.575062] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000705second # [ 0.575086] Intel/Sharp Extended Query Table at 0x0031706second # [ 0.579035] Using buffer write method707second # [ 0.579069] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]708second # [ 0.583037] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000709second # [ 0.583052] Intel/Sharp Extended Query Table at 0x0031710second # [ 0.612981] Using buffer write method711second # [ 0.613000] Concatenating MTD devices:712second # [ 0.613002] (0): "0.flash"713second # [ 0.613004] (1): "0.flash"714second # [ 0.613006] into device "0.flash"715test # [ 0.783278] Freeing initrd memory: 25172K716test # [ 0.790990] tun: Universal TUN/TAP device driver, 1.6717test # [ 0.794201] thunder_xcv, ver 1.0718test # [ 0.794218] thunder_bgx, ver 1.0719test # [ 0.794229] nicpf, ver 1.0720test # [ 0.794537] e1000: Intel(R) PRO/1000 Network Driver721test # [ 0.794540] e1000: Copyright (c) 1999-2006 Intel Corporation.722test # [ 0.794555] e1000e: Intel(R) PRO/1000 Network Driver723test # [ 0.794559] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.724test # [ 0.794573] igb: Intel(R) Gigabit Ethernet Network Driver725test # [ 0.794575] igb: Copyright (c) 2007-2014 Intel Corporation.726test # [ 0.794585] igbvf: Intel(R) Gigabit Virtual Function Network Driver727test # [ 0.794587] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728test # [ 0.794655] sky2: driver version 1.30729second # [ 0.790866] Freeing initrd memory: 25176K730second # [ 0.798238] tun: Universal TUN/TAP device driver, 1.6731second # [ 0.801017] thunder_xcv, ver 1.0732second # [ 0.801033] thunder_bgx, ver 1.0733second # [ 0.801042] nicpf, ver 1.0734second # [ 0.801352] e1000: Intel(R) PRO/1000 Network Driver735second # [ 0.801356] e1000: Copyright (c) 1999-2006 Intel Corporation.736second # [ 0.801371] e1000e: Intel(R) PRO/1000 Network Driver737second # [ 0.801375] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.738second # [ 0.801387] igb: Intel(R) Gigabit Ethernet Network Driver739second # [ 0.801389] igb: Copyright (c) 2007-2014 Intel Corporation.740second # [ 0.801399] igbvf: Intel(R) Gigabit Virtual Function Network Driver741second # [ 0.801401] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.742second # [ 0.801467] sky2: driver version 1.30743second # [ 0.802321] usbcore: registered new interface driver usb-storage744second # [ 0.802580] usbcore: registered new interface driver usbserial_generic745second # [ 0.802588] usbserial: USB Serial support registered for generic746second # [ 0.802909] hv_vmbus: registering driver hyperv_keyboard747second # [ 0.810589] ehci-pci 0000:00:07.0: EHCI Host Controller748second # [ 0.810627] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1749second # [ 0.810968] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000750second # [ 0.811157] rtc-pl031 9010000.pl031: registered as rtc0751second # [ 0.811185] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T10:03:22 UTC (1792663402)752second # [ 0.811370] i2c_dev: i2c /dev entries driver753test # [ 0.800422] usbcore: registered new interface driver usb-storage754second # [ 0.816075] sdhci: Secure Digital Host Controller Interface driver755test # [ 0.800468] usbcore: registered new interface driver usbserial_generic756second # [ 0.816081] sdhci: Copyright(c) Pierre Ossman757test # [ 0.800475] usbserial: USB Serial support registered for generic758second # [ 0.816240] Synopsys Designware Multimedia Card Interface Driver759test # [ 0.800787] hv_vmbus: registering driver hyperv_keyboard760second # [ 0.816423] sdhci-pltfm: SDHCI platform and OF driver helper761test # [ 0.801976] ehci-pci 0000:00:07.0: EHCI Host Controller762second # [ 0.817387] hid: raw HID events driver (C) Jiri Kosina763test # [ 0.802008] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1764second # [ 0.817532] usbcore: registered new interface driver usbhid765second # [ 0.817536] usbhid: USB HID core driver766test # [ 0.802268] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000767test # [ 0.802453] rtc-pl031 9010000.pl031: registered as rtc0768test # [ 0.802476] rtc-pl031 9010000.pl031: setting system clock to 2026-10-22T10:03:22 UTC (1792663402)769test # [ 0.802639] i2c_dev: i2c /dev entries driver770test # [ 0.804286] sdhci: Secure Digital Host Controller Interface driver771test # [ 0.804291] sdhci: Copyright(c) Pierre Ossman772second # [ 0.841657] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00773test # [ 0.804443] Synopsys Designware Multimedia Card Interface Driver774second # [ 0.842211] hub 1-0:1.0: USB hub found775test # [ 0.804623] sdhci-pltfm: SDHCI platform and OF driver helper776second # [ 0.842230] hub 1-0:1.0: 6 ports detected777test # [ 0.805500] hid: raw HID events driver (C) Jiri Kosina778second # [ 0.842718] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available779test # [ 0.805636] usbcore: registered new interface driver usbhid780second # [ 0.843613] drop_monitor: Initializing network drop monitor service781test # [ 0.805642] usbhid: USB HID core driver782second # [ 0.843742] NET: Registered PF_INET6 protocol family783second # [ 0.847232] Segment Routing with IPv6784second # [ 0.847251] In-situ OAM (IOAM) with IPv6785second # [ 0.847298] NET: Registered PF_PACKET protocol family786second # [ 0.847363] 9pnet: Installing 9P2000 support787second # [ 0.847403] Key type dns_resolver registered788test # [ 0.891350] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00789test # [ 0.891818] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available790test # [ 0.892695] drop_monitor: Initializing network drop monitor service791test # [ 0.893041] NET: Registered PF_INET6 protocol family792test # [ 0.893871] Segment Routing with IPv6793test # [ 0.893888] In-situ OAM (IOAM) with IPv6794test # [ 0.893936] NET: Registered PF_PACKET protocol family795second # [ 0.852637] registered taskstats version 1796test # [ 0.894323] hub 1-0:1.0: USB hub found797second # [ 0.852802] Loading compiled-in X.509 certificates798test # [ 0.894349] hub 1-0:1.0: 6 ports detected799test # [ 0.894646] 9pnet: Installing 9P2000 support800test # [ 0.894687] Key type dns_resolver registered801second # [ 0.859099] Demotion targets for Node 0: null802second # [ 0.859193] Key type .fscrypt registered803second # [ 0.859196] Key type fscrypt-provisioning registered804second # [ 0.859291] ima: No TPM chip found, activating TPM-bypass!805second # [ 0.859306] ima: Allocated hash algorithm: sha1806second # [ 0.859352] ima: No architecture policies found807test # [ 0.902925] registered taskstats version 1808test # [ 0.903386] Loading compiled-in X.509 certificates809second # [ 0.862108] input: gpio-keys as /devices/platform/gpio-keys/input/input0810test # [ 0.909503] Demotion targets for Node 0: null811test # [ 0.909616] Key type .fscrypt registered812test # [ 0.909619] Key type fscrypt-provisioning registered813test # [ 0.909713] ima: No TPM chip found, activating TPM-bypass!814test # [ 0.909728] ima: Allocated hash algorithm: sha1815test # [ 0.909769] ima: No architecture policies found816test # [ 0.910572] input: gpio-keys as /devices/platform/gpio-keys/input/input0817second # [ 0.872852] clk: Disabling unused clocks818second # [ 0.872865] PM: genpd: Disabling unused power domains819second # [ 0.877495] Freeing unused kernel memory: 7808K820second # [ 0.877590] Run /init as init process821test # [ 0.923519] clk: Disabling unused clocks822test # [ 0.923536] PM: genpd: Disabling unused power domains823test # [ 0.928117] Freeing unused kernel memory: 7808K824test # [ 0.928208] Run /init as init process825second # [ 0.898572] systemd[1]: Successfully made /usr/ read-only.826test # [ 0.949829] systemd[1]: Successfully made /usr/ read-only.827second # [ 1.087894] usb 1-1: new high-speed USB device number 2 using ehci-pci828test # [ 1.143379] usb 1-1: new high-speed USB device number 2 using ehci-pci829second # [ 1.232739] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)830second # [ 1.232770] systemd[1]: Detected virtualization qemu.831second # [ 1.232837] systemd[1]: Detected architecture arm64.832second # [ 1.232847] systemd[1]: Running in initrd.833second # [ 1.234693] systemd[1]: Initializing machine ID from random generator.834second # [ 1.234996] systemd[1]: Hostname set to <second>.835test # [ 1.284086] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)836test # [ 1.284116] systemd[1]: Detected virtualization qemu.837test # [ 1.284179] systemd[1]: Detected architecture arm64.838test # [ 1.284192] systemd[1]: Running in initrd.839test # [ 1.285049] systemd[1]: Initializing machine ID from random generator.840test # [ 1.285349] systemd[1]: Hostname set to <test>.841second # [ 1.254499] 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/input1842test # [ 1.327972] 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/input1843second # [ 1.339986] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0844test # [ 1.411470] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0845second # [ 1.375656] systemd[1]: bpf-restrict-fs: LSM BPF program attached846test # [ 1.429251] systemd[1]: bpf-restrict-fs: LSM BPF program attached847second # [ 1.426168] systemd[1]: Queued start job for default target Initrd Default Target.848second # [ 1.429950] systemd[1]: Created slice Slice /system/modprobe.849second # [ 1.430134] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.850second # [ 1.430160] systemd[1]: Expecting device /dev/disk/by-label/nixos...851second # [ 1.430184] systemd[1]: Reached target Path Units.852second # [ 1.430196] systemd[1]: Reached target Slice Units.853second # [ 1.430207] systemd[1]: Reached target Swaps.854second # [ 1.430218] systemd[1]: Reached target Timer Units.855second # [ 1.430378] systemd[1]: Listening on D-Bus System Message Bus Socket.856second # [ 1.430499] systemd[1]: Listening on Journal Socket (/dev/log).857second # [ 1.430610] systemd[1]: Listening on Journal Sockets.858second # [ 1.430666] systemd[1]: Listening on udev Kernel Socket.859second # [ 1.430747] systemd[1]: Listening on udev Varlink Socket.860second # [ 1.430764] systemd[1]: Reached target Socket Units.861test # [ 1.481359] systemd[1]: Queued start job for default target Initrd Default Target.862second # [ 1.437797] systemd[1]: Starting Create List of Static Device Nodes...863second # [ 1.437885] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs864test # [ 1.485392] systemd[1]: Created slice Slice /system/modprobe.865test # [ 1.485540] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.866test # [ 1.485562] systemd[1]: Expecting device /dev/disk/by-label/nixos...867test # [ 1.485586] systemd[1]: Reached target Path Units.868test # [ 1.485599] systemd[1]: Reached target Slice Units.869test # [ 1.485612] systemd[1]: Reached target Swaps.870test # [ 1.485623] systemd[1]: Reached target Timer Units.871test # [ 1.485762] systemd[1]: Listening on D-Bus System Message Bus Socket.872test # [ 1.485869] systemd[1]: Listening on Journal Socket (/dev/log).873test # [ 1.485983] systemd[1]: Listening on Journal Sockets.874test # [ 1.486167] systemd[1]: Listening on udev Kernel Socket.875test # [ 1.486261] systemd[1]: Listening on udev Varlink Socket.876test # [ 1.486279] systemd[1]: Reached target Socket Units.877second # [ 1.443894] systemd[1]: Mounting Kernel Configuration File System...878test # [ 1.493152] systemd[1]: Starting Create List of Static Device Nodes...879test # [ 1.493211] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs880test # [ 1.499404] systemd[1]: Mounting Kernel Configuration File System...881second # [ 1.456594] systemd[1]: Starting Journal Service...882second # [ 1.459852] usb 1-2: new high-speed USB device number 3 using ehci-pci883test # [ 1.512325] systemd[1]: Starting Journal Service...884second # [ 1.464039] systemd[1]: Starting Load Kernel Modules...885second # [ 1.464122] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os886second # [ 1.472163] systemd[1]: Starting Coldplug All udev Devices...887test # [ 1.523451] systemd[1]: Starting Load Kernel Modules...888test # [ 1.523558] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os889second # [ 1.482063] systemd[1]: Finished Create List of Static Device Nodes.890second # [ 1.482781] systemd[1]: Mounted Kernel Configuration File System.891test # [ 1.535326] usb 1-2: new high-speed USB device number 3 using ehci-pci892test # [ 1.540974] systemd[1]: Starting Coldplug All udev Devices...893test # [ 1.542051] systemd[1]: Finished Create List of Static Device Nodes.894second # [ 1.492340] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...895test # [ 1.550910] dm_mod: module verification failed: signature and/or required key missing - tainting kernel896test # [ 1.555401] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...897test # [ 1.555733] systemd[1]: Mounted Kernel Configuration File System.898test # [ 1.565443] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.899test # [ 1.575108] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev900second # [ 1.530241] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.901second # [ 1.531351] systemd[1]: Starting Create Static Device Nodes in /dev...902test # [ 1.584374] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0903test # [ 1.584582] [drm] features: -virgl +edid -resource_blob -host_visible904test # [ 1.584584] [drm] features: -context_init905test # [ 1.585046] [drm] number of scanouts: 1906test # [ 1.585054] [drm] number of cap sets: 0907second # [ 1.542890] systemd-journald[71]: Collecting audit messages is disabled.908second # [ 1.546414] dm_mod: module verification failed: signature and/or required key missing - tainting kernel909second # [ 1.552768] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.910test # [ 1.596715] systemd-journald[71]: Collecting audit messages is disabled.911test # [ 1.599768] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.912test # [ 1.600855] systemd[1]: Starting Create Static Device Nodes in /dev...913second # [ 1.556848] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev914test # [ 1.607545] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic915test # [ 1.607554] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0916second # [ 1.574456] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0917second # [ 1.574656] [drm] features: -virgl +edid -resource_blob -host_visible918second # [ 1.574657] [drm] features: -context_init919second # [ 1.575174] [drm] number of scanouts: 1920second # [ 1.575183] [drm] number of cap sets: 0921second # [ 1.579972] systemd[1]: Finished Create Static Device Nodes in /dev.922second # [ 1.580217] systemd[1]: Reached target Preparation for Local File Systems.923second # [ 1.580239] systemd[1]: Reached target Local File Systems.924test # [ 1.627688] systemd[1]: Finished Create Static Device Nodes in /dev.925test # [ 1.627829] systemd[1]: Reached target Preparation for Local File Systems.926test # [ 1.627849] systemd[1]: Reached target Local File Systems.927test # [ 1.628962] systemd[1]: Starting Rule-based Manager for Device Events and Files...928second # [ 1.585580] systemd[1]: Starting Rule-based Manager for Device Events and Files...929second # [ 1.592114] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic930test # [ 1.635751] Console: switching to colour frame buffer device 160x50931second # [ 1.592123] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0932second # [ 1.610920] Console: switching to colour frame buffer device 160x50933test # [ 1.655927] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device934test # [ 1.664856] systemd[1]: Finished Load Kernel Modules.935test # [ 1.665999] systemd[1]: Starting Apply Kernel Variables...936second # [ 1.629593] 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/input2937second # [ 1.629673] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0938second # [ 1.632949] systemd-modules-load[72]: Inserted module 'dm_mod'939second # [ 1.641945] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device940test # [ 1.687292] 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/input2941test # [ 1.687544] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0942second # [ 1.638589] systemd-modules-load[72]: Module 'virtio_balloon' is built in943second # [ 1.645201] systemd[1]: Started Journal Service.944second # [ 1.644318] systemd-modules-load[72]: Module 'virtio_console' is built in945test # [ 1.697053] systemd[1]: Started Journal Service.946second # [ 1.646285] systemd-modules-load[72]: Inserted module 'virtio_gpu'947second # [ 1.651570] systemd-modules-load[72]: Module 'virtio_rng' is built in948test # [ 1.696228] systemd-modules-load[72]: Inserted module 'dm_mod'949second # [ 1.656270] systemd[1]: Finished Load Kernel Modules.950second # [ 1.656867] systemd[1]: Starting Apply Kernel Variables...951test # [ 1.700242] systemd-modules-load[72]: Module 'virtio_balloon' is built in952test # [ 1.701002] systemd-modules-load[72]: Module 'virtio_console' is built in953test # [ 1.701705] systemd-modules-load[72]: Inserted module 'virtio_gpu'954test # [ 1.702372] systemd-modules-load[72]: Module 'virtio_rng' is built in955test # [ 1.703160] systemd[1]: Starting Create System Files and Directories...956second # [ 1.669024] systemd[1]: Starting Create System Files and Directories...957test # [ 1.714256] systemd[1]: Finished Apply Kernel Variables.958second # [ 1.677264] systemd-udevd[79]: Using default interface naming scheme 'v261'.959test # [ 1.725935] systemd-udevd[79]: Using default interface naming scheme 'v261'.960test # [ 1.736771] systemd[1]: Finished Create System Files and Directories.961second # [ 1.708762] systemd[1]: Finished Apply Kernel Variables.962test # [ 1.753326] systemd[1]: Started Rule-based Manager for Device Events and Files.963second # [ 1.712190] systemd[1]: Finished Create System Files and Directories.964second # [ 1.719313] systemd[1]: Started Rule-based Manager for Device Events and Files.965test # [ 1.819328] systemd[1]: Starting Virtual Console Setup...966second # [ 1.799421] systemd[1]: Starting Virtual Console Setup...967test # [ 1.864469] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.968test # [ 1.866873] systemd[1]: Finished Virtual Console Setup.969second # [ 1.832409] systemd-vconsole-setup[102]: Configuration of first virtual console was skipped, ignoring remaining ones.970second # [ 1.836152] systemd[1]: Finished Virtual Console Setup.971second # [ 2.053450] systemd[1]: Finished Coldplug All udev Devices.972second # [ 2.054105] systemd[1]: Reached target System Initialization.973second # [ 2.054704] systemd[1]: Reached target Basic System.974test # [ 2.099727] systemd[1]: Finished Coldplug All udev Devices.975test # [ 2.100476] systemd[1]: Reached target System Initialization.976test # [ 2.101024] systemd[1]: Reached target Basic System.977second # [ 2.159326] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.978test # [ 2.207888] (udev-worker)[90]: Network interface NamePolicy= disabled on kernel command line.979second # [ 2.176970] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.980test # [ 2.230994] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.981test # [ 2.279519] systemd[1]: Found device /dev/disk/by-label/nixos.982test # [ 2.281451] systemd[1]: Reached target Initrd Root Device.983test # [ 2.283772] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...984second # [ 2.245928] systemd[1]: Found device /dev/disk/by-label/nixos.985second # [ 2.248446] systemd[1]: Reached target Initrd Root Device.986second # [ 2.250967] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...987test # [ 2.326665] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks988second # [ 2.289763] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks989test # [ 2.337466] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.990second # [ 2.297694] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.991second # [ 2.475478] systemd[1]: Mounting /sysroot...992test # [ 2.532798] systemd[1]: Mounting /sysroot...993second # [ 2.511853] EXT4-fs (vda): mounted filesystem 6b3de305-291a-48de-bcfe-ede0e3f362bc r/w with ordered data mode. Quota mode: none.994second # [ 2.510153] systemd[1]: Mounted /sysroot.995second # [ 2.512279] systemd[1]: Reached target Initrd Root File System.996second # [ 2.514244] systemd[1]: Starting Mountpoints Configured in the Real Root...997test # [ 2.573018] EXT4-fs (vda): mounted filesystem 2bb49cb6-9813-4735-840e-d427251afe92 r/w with ordered data mode. Quota mode: none.998test # [ 2.565432] systemd[1]: Mounted /sysroot.999test # [ 2.566477] systemd[1]: Reached target Initrd Root File System.1000test # [ 2.569601] systemd[1]: Starting Mountpoints Configured in the Real Root...1001second # [ 2.534477] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1002second # [ 2.540308] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1003second # [ 2.543720] systemd[1]: Reloading...1004test # [ 2.591215] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.1005test # [ 2.597591] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...1006test # [ 2.600789] systemd[1]: Reloading...1007second # [ 2.691375] systemd[1]: Reloading finished in 148 ms.1008test # [ 2.748538] systemd[1]: Reloading finished in 148 ms.1009second # [ 2.719400] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1010second # [ 2.721875] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1011second # [ 2.724607] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1012second # [ 2.726660] systemd[1]: Finished Mountpoints Configured in the Real Root.1013second # [ 2.728816] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1014test # [ 2.776343] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1015test # [ 2.779495] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1016test # [ 2.781957] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1017test # [ 2.783882] systemd[1]: Finished Mountpoints Configured in the Real Root.1018test # [ 2.785484] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1019second # [ 3.009935] fuse: init (API version 7.45)1020test # [ 3.070638] fuse: init (API version 7.45)1021second # [ 3.044613] virtiofs virtio6: discovered new tag: nix-store1022second # [ 3.045156] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1023second # [ 3.049362] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/115gmjp006v2rh6wbyxqxlhng9bqa6q4-systemd-262/lib/udev/mtd_probe": No such file or directory1024second # [ 3.052684] (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 directory1025test # [ 3.108686] virtiofs virtio6: discovered new tag: nix-store1026test # [ 3.109210] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1027second # [ 3.068322] virtiofs virtio7: discovered new tag: shared1028second # [ 3.068855] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1029test # [ 3.105571] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/115gmjp006v2rh6wbyxqxlhng9bqa6q4-systemd-262/lib/udev/mtd_probe": No such file or directory1030test # [ 3.108991] (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 directory1031second # [ 3.077841] virtiofs virtio8: discovered new tag: xchg1032second # [ 3.078367] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1033second # [ 3.076497] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1034second # [ 3.080156] systemd[1]: Stopped Virtual Console Setup.1035second # [ 3.080692] systemd[1]: Stopping Virtual Console Setup...1036second # [ 3.081947] systemd[1]: Starting Virtual Console Setup...1037test # [ 3.136337] virtiofs virtio7: discovered new tag: shared1038test # [ 3.137062] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1039test # [ 3.145610] virtiofs virtio8: discovered new tag: xchg1040test # [ 3.146170] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1041test # [ 3.141384] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1042test # [ 3.142933] systemd[1]: Stopped Virtual Console Setup.1043test # [ 3.147505] systemd[1]: Stopping Virtual Console Setup...1044test # [ 3.151478] systemd[1]: Starting Virtual Console Setup...1045second # [ 3.109568] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1046second # [ 3.110616] systemd[1]: Finished Virtual Console Setup.1047test # [ 3.172809] systemd-vconsole-setup[138]: Configuration of first virtual console was skipped, ignoring remaining ones.1048test # [ 3.174496] systemd[1]: Finished Virtual Console Setup.1049second # [ 3.479364] systemd[1]: Mounting /sysroot/nix/.ro-store...1050second # [ 3.490227] systemd[1]: Mounting /sysroot/nix/.rw-store...1051test # [ 3.535051] systemd[1]: Mounting /sysroot/nix/.ro-store...1052second # [ 3.503643] systemd[1]: Mounting /sysroot/run...1053test # [ 3.546131] systemd[1]: Mounting /sysroot/nix/.rw-store...1054test # [ 3.559725] systemd[1]: Mounting /sysroot/run...1055second # [ 3.518764] systemd[1]: Mounting /sysroot/tmp/shared...1056second # [ 3.536314] systemd[1]: Mounting /sysroot/tmp/xchg...1057test # [ 3.580579] systemd[1]: Mounting /sysroot/tmp/shared...1058test # [ 3.592455] systemd[1]: Mounting /sysroot/tmp/xchg...1059second # [ 3.554497] systemd[1]: Mounted /sysroot/nix/.rw-store.1060second # [ 3.564298] systemd[1]: Starting rw-sysroot-nix-store.service...1061second # [ 3.566168] systemd[1]: Mounted /sysroot/nix/.ro-store.1062test # [ 3.615448] systemd[1]: Mounted /sysroot/nix/.ro-store.1063test # [ 3.617835] systemd[1]: Mounted /sysroot/nix/.rw-store.1064second # [ 3.577376] systemd[1]: Mounted /sysroot/run.1065test # [ 3.620655] systemd[1]: Mounted /sysroot/run.1066second # [ 3.580251] systemd[1]: Mounted /sysroot/tmp/shared.1067test # [ 3.632873] systemd[1]: Starting rw-sysroot-nix-store.service...1068second # [ 3.591453] systemd[1]: Mounted /sysroot/tmp/xchg.1069test # [ 3.634793] systemd[1]: Mounted /sysroot/tmp/shared.1070second # [ 3.593684] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1071second # [ 3.595322] systemd[1]: Finished rw-sysroot-nix-store.service.1072test # [ 3.641004] systemd[1]: Mounted /sysroot/tmp/xchg.1073test # [ 3.654636] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1074test # [ 3.655654] systemd[1]: Finished rw-sysroot-nix-store.service.1075second # [ 4.475508] systemd[1]: Mounting /sysroot/nix/store...1076test # [ 4.532917] systemd[1]: Mounting /sysroot/nix/store...1077second # [ 4.509253] systemd[1]: Mounted /sysroot/nix/store.1078second # [ 4.511466] systemd[1]: Reached target Initrd File Systems.1079second # [ 4.516185] systemd[1]: Starting Find NixOS closure...1080second # [ 4.517777] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1081test # [ 4.571339] systemd[1]: Mounted /sysroot/nix/store.1082test # [ 4.572608] systemd[1]: Reached target Initrd File Systems.1083test # [ 4.574999] systemd[1]: Starting Find NixOS closure...1084test # [ 4.581283] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1085second # [ 4.539990] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1086second # [ 4.544134] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1087second # [ 4.553535] systemd[1]: Finished Find NixOS closure.1088second # [ 4.555065] systemd[1]: Reached target Initrd Default Target.1089second # [ 4.557314] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1090test # [ 4.614696] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1091test # [ 4.616200] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1092second # [ 4.577839] systemd[1]: initrd-cleanup.service: Deactivated successfully.1093second # [ 4.579157] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1094second # [ 4.582027] systemd[1]: Stopped target Initrd Default Target.1095second # [ 4.582650] systemd[1]: Stopped target Basic System.1096second # [ 4.583176] systemd[1]: Stopped target Initrd Root Device.1097second # [ 4.583742] systemd[1]: Stopped target Path Units.1098test # [ 4.626186] systemd[1]: Finished Find NixOS closure.1099test # [ 4.627358] systemd[1]: Reached target Initrd Default Target.1100second # [ 4.586044] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1101test # [ 4.629460] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1102second # [ 4.590426] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1103second # [ 4.596737] systemd[1]: Stopped target Slice Units.1104second # [ 4.597249] systemd[1]: Stopped target Socket Units.1105second # [ 4.597724] systemd[1]: Stopped target System Initialization.1106second # [ 4.598251] systemd[1]: Stopped target Swaps.1107second # [ 4.598715] systemd[1]: Stopped target Timer Units.1108second # [ 4.599209] systemd[1]: dbus.socket: Deactivated successfully.1109second # [ 4.599727] systemd[1]: Closed D-Bus System Message Bus Socket.1110second # [ 4.603214] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1111second # [ 4.603916] systemd[1]: Stopped Find NixOS closure.1112second # [ 4.604634] systemd[1]: Starting rw-sysroot-nix-store.service...1113second # [ 4.605199] systemd[1]: systemd-sysctl.service: Deactivated successfully.1114second # [ 4.605809] systemd[1]: Stopped Apply Kernel Variables.1115second # [ 4.606301] systemd[1]: systemd-modules-load.service: Deactivated successfully.1116second # [ 4.607176] systemd[1]: Stopped Load Kernel Modules.1117test # [ 4.649867] systemd[1]: Stopped target Initrd Default Target.1118test # [ 4.651053] systemd[1]: Stopped target Basic System.1119test # [ 4.651832] systemd[1]: Stopped target Initrd Root Device.1120test # [ 4.652926] systemd[1]: Stopped target Path Units.1121second # [ 4.611960] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1122test # [ 4.654054] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1123test # [ 4.656235] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1124test # [ 4.657734] systemd[1]: Stopped target Slice Units.1125test # [ 4.658438] systemd[1]: Stopped target Socket Units.1126test # [ 4.660128] systemd[1]: Stopped target System Initialization.1127test # [ 4.660732] systemd[1]: Stopped target Swaps.1128test # [ 4.661780] systemd[1]: Stopped target Timer Units.1129second # [ 4.616138] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1130second # [ 4.617520] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1131second # [ 4.618969] systemd[1]: Stopped Create System Files and Directories.1132second # [ 4.620202] systemd[1]: Stopped target Local File Systems.1133second # [ 4.621333] systemd[1]: Stopped target Preparation for Local File Systems.1134test # [ 4.664116] systemd[1]: dbus.socket: Deactivated successfully.1135test # [ 4.664730] systemd[1]: Closed D-Bus System Message Bus Socket.1136test # [ 4.665323] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1137second # [ 4.624371] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1138second # [ 4.625047] systemd[1]: Stopped Coldplug All udev Devices.1139second # [ 4.625561] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1140test # [ 4.667565] systemd[1]: Stopped Find NixOS closure.1141second # [ 4.627082] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1142second # [ 4.627975] systemd[1]: Stopped Virtual Console Setup.1143second # [ 4.628634] systemd[1]: systemd-udevd.service: Deactivated successfully.1144test # [ 4.670318] systemd[1]: Starting rw-sysroot-nix-store.service...1145second # [ 4.629227] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1146test # [ 4.670905] systemd[1]: systemd-sysctl.service: Deactivated successfully.1147test # [ 4.671535] systemd[1]: Stopped Apply Kernel Variables.1148second # [ 4.629863] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1149second # [ 4.630477] systemd[1]: Closed udev Varlink Socket.1150second # [ 4.630930] systemd[1]: Starting Cleanup udev Database...1151second # [ 4.631427] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1152test # [ 4.676539] systemd[1]: systemd-modules-load.service: Deactivated successfully.1153second # [ 4.635105] systemd[1]: Stopped Create Static Device Nodes in /dev.1154test # [ 4.677483] systemd[1]: Stopped Load Kernel Modules.1155test # [ 4.678114] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1156test # [ 4.678843] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1157test # [ 4.679569] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1158second # [ 4.640130] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1159second # [ 4.641124] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1160second # [ 4.641747] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1161second # [ 4.642342] systemd[1]: Stopped Create List of Static Device Nodes.1162second # [ 4.642893] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1163second # [ 4.643520] systemd[1]: Finished rw-sysroot-nix-store.service.1164test # [ 4.691757] systemd[1]: Stopped Create System Files and Directories.1165test # [ 4.692837] systemd[1]: Stopped target Local File Systems.1166test # [ 4.695038] systemd[1]: Stopped target Preparation for Local File Systems.1167test # [ 4.696310] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1168second # [ 4.655021] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1169second # [ 4.656891] systemd[1]: Finished Cleanup udev Database.1170test # [ 4.699142] systemd[1]: Stopped Coldplug All udev Devices.1171test # [ 4.699692] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1172test # [ 4.701189] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1173second # [ 4.661022] systemd[1]: Reached target Switch Root.1174second # [ 4.661517] systemd[1]: Starting NixOS Activation...1175test # [ 4.704297] systemd[1]: Stopped Virtual Console Setup.1176test # [ 4.704820] systemd[1]: initrd-cleanup.service: Deactivated successfully.1177test # [ 4.705419] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1178test # [ 4.706004] systemd[1]: systemd-udevd.service: Deactivated successfully.1179test # [ 4.706589] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1180test # [ 4.707220] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1181test # [ 4.707852] systemd[1]: Finished rw-sysroot-nix-store.service.1182test # [ 4.711193] systemd[1]: systemd-udevd-varlink.socket: Deactivated successfully.1183test # [ 4.711877] systemd[1]: Closed udev Varlink Socket.1184test # [ 4.712525] systemd[1]: Starting Cleanup udev Database...1185test # [ 4.713039] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1186test # [ 4.713734] systemd[1]: Stopped Create Static Device Nodes in /dev.1187test # [ 4.714306] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1188test # [ 4.715037] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1189test # [ 4.715697] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1190test # [ 4.719675] systemd[1]: Stopped Create List of Static Device Nodes.1191test # [ 4.736865] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1192test # [ 4.737705] systemd[1]: Finished Cleanup udev Database.1193test # [ 4.739239] systemd[1]: Reached target Switch Root.1194test # [ 4.744130] systemd[1]: Starting NixOS Activation...1195second # [ 4.745476] initrd-nixos-activation-start[172]: booting system configuration /nix/store/y5jasjl3fkyjjl1kjdgsj4s9354nqi6w-nixos-system-second-test1196second # [ 4.778272] initrd-nixos-activation-start[172]: running activation script...1197test # [ 4.826894] initrd-nixos-activation-start[173]: booting system configuration /nix/store/pkmrsr198f79v866bcannckgyyj06krl-nixos-system-test-test1198test # [ 4.856392] initrd-nixos-activation-start[173]: running activation script...1199second # [ 5.001450] initrd-nixos-activation-start[195]: setting up /etc...1200test # [ 5.092247] initrd-nixos-activation-start[196]: setting up /etc...1201second # [ 5.086754] initrd-nixos-activation-start[195]: setting up age secrets...1202test # [ 5.191600] initrd-nixos-activation-start[196]: setting up age secrets...1203second # [ 5.174464] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1204second # [ 5.176328] systemd[1]: Finished NixOS Activation.1205second # [ 5.177894] systemd[1]: Starting Switch Root...1206second # [ 5.197749] systemd[1]: Switching root.1207test # [ 5.318568] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1208test # [ 5.320302] systemd[1]: Finished NixOS Activation.1209test # [ 5.321642] systemd[1]: Starting Switch Root...1210test # [ 5.343102] systemd[1]: Switching root.1211second # [ 5.357961] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1212test # [ 5.517573] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1213second # [ 5.831853] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1214second # [ 5.831916] systemd[1]: Detected virtualization qemu.1215second # [ 5.831965] systemd[1]: Detected architecture arm64.1216second # [ 5.832049] systemd[1]: Detected first boot.1217second # [ 5.833744] systemd[1]: Initializing machine ID from random generator.1218second # [ 5.944538] systemd[1]: bpf-restrict-fs: LSM BPF program attached1219test # [ 6.014549] systemd[1]: systemd 262 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1220test # [ 6.014631] systemd[1]: Detected virtualization qemu.1221test # [ 6.014686] systemd[1]: Detected architecture arm64.1222test # [ 6.014773] systemd[1]: Detected first boot.1223test # [ 6.022375] systemd[1]: Initializing machine ID from random generator.1224second # [ 6.036520] systemd[1]: Applying preset policy.1225test # [ 6.118245] systemd[1]: bpf-restrict-fs: LSM BPF program attached1226test # [ 6.212360] systemd[1]: Applying preset policy.1227second # [ 6.197217] systemd[1]: Populated /etc with preset unit settings.1228test # [ 6.397216] systemd[1]: Populated /etc with preset unit settings.1229second # [ 6.374706] systemd[1]: initrd-switch-root.service: Deactivated successfully.1230second # [ 6.374994] systemd[1]: Stopped initrd-switch-root.service.1231second # [ 6.378361] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1232second # [ 6.379556] systemd[1]: Created slice Slice /system/getty.1233second # [ 6.381831] systemd[1]: Created slice User and Session Slice.1234second # [ 6.382358] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1235second # [ 6.382714] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1236second # [ 6.383004] systemd[1]: Expecting device /dev/hvc0...1237second # [ 6.383267] systemd[1]: Expecting device /dev/ttyAMA0...1238second # [ 6.383529] systemd[1]: Reached target Local Encrypted Volumes.1239second # [ 6.383793] systemd[1]: Stopped target initrd-fs.target.1240second # [ 6.387707] systemd[1]: Stopped target initrd-root-fs.target.1241second # [ 6.388703] systemd[1]: Stopped target initrd-switch-root.target.1242second # [ 6.389187] systemd[1]: Reached target Virtual Machines and Containers.1243second # [ 6.389675] systemd[1]: Reached target Path Units.1244second # [ 6.390156] systemd[1]: Reached target Remote File Systems.1245second # [ 6.390643] systemd[1]: Reached target Slice Units.1246second # [ 6.391134] systemd[1]: Reached target Swaps.1247second # [ 6.395440] systemd[1]: Listening on Query the User Interactively for a Password.1248second # [ 6.398411] systemd[1]: Listening on Process Core Dump Socket.1249second # [ 6.400413] systemd[1]: Listening on Credential Encryption/Decryption.1250second # [ 6.401903] systemd[1]: Listening on Factory Reset Management.1251second # [ 6.402485] systemd[1]: Listening on Hostname Service Socket.1252second # [ 6.407177] systemd[1]: Starting Journal Log Access Socket...1253second # [ 6.409742] systemd[1]: Listening on Journal Audit Socket.1254second # [ 6.412308] systemd[1]: Listening on Console Output Muting Service Socket.1255second # [ 6.412963] systemd[1]: Listening on Network Management Metrics Varlink Socket.1256second # [ 6.413648] systemd[1]: Listening on Network Management Varlink Socket.1257second # [ 6.414271] systemd[1]: Listening on Network Management Netlink Socket.1258second # [ 6.414951] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1259second # [ 6.415458] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1260second # [ 6.420492] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1261second # [ 6.425477] systemd[1]: Listening on Disk Repartitioning Service Socket.1262second # [ 6.426006] systemd[1]: Listening on Resolve Monitor Varlink Socket.1263second # [ 6.426609] systemd[1]: Listening on Resolve Service Varlink Socket.1264second # [ 6.427184] systemd[1]: Listening on udev Varlink Socket.1265second # [ 6.431443] systemd[1]: Mounting Huge Pages File System...1266second # [ 6.436294] systemd[1]: Mounting POSIX Message Queue File System...1267second # [ 6.444373] systemd[1]: Mounting Kernel Debug File System...1268second # [ 6.454490] systemd[1]: Mounting Kernel Trace File System...1269second # [ 6.464941] systemd[1]: Starting Create List of Static Device Nodes...1270second # [ 6.465887] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1271second # [ 6.478900] systemd[1]: Mounting Kernel Configuration File System...1272second # [ 6.479355] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1273second # [ 6.485586] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1274second # [ 6.489971] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1275second # [ 6.506484] systemd[1]: Mounting FUSE Control File System...1276second # [ 6.506872] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671277test # [ 6.578625] systemd[1]: initrd-switch-root.service: Deactivated successfully.1278second # [ 6.531964] systemd[1]: Starting Journal Service...1279test # [ 6.580461] systemd[1]: Stopped initrd-switch-root.service.1280test # [ 6.582469] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1281test # [ 6.585387] systemd[1]: Created slice Slice /system/getty.1282test # [ 6.586446] systemd[1]: Created slice User and Session Slice.1283second # [ 6.542407] systemd[1]: Starting Load Kernel Modules...1284test # [ 6.587079] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1285test # [ 6.589733] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1286test # [ 6.590274] systemd[1]: Expecting device /dev/hvc0...1287test # [ 6.590805] systemd[1]: Expecting device /dev/ttyAMA0...1288test # [ 6.593162] systemd[1]: Reached target Local Encrypted Volumes.1289test # [ 6.593712] systemd[1]: Stopped target initrd-fs.target.1290test # [ 6.594263] systemd[1]: Stopped target initrd-root-fs.target.1291test # [ 6.594811] systemd[1]: Stopped target initrd-switch-root.target.1292test # [ 6.597642] systemd[1]: Reached target Virtual Machines and Containers.1293test # [ 6.598182] systemd[1]: Reached target Path Units.1294test # [ 6.598728] systemd[1]: Reached target Remote File Systems.1295test # [ 6.599268] systemd[1]: Reached target Slice Units.1296test # [ 6.602486] systemd[1]: Reached target Swaps.1297test # [ 6.605043] systemd[1]: Listening on Query the User Interactively for a Password.1298test # [ 6.606857] systemd[1]: Listening on Process Core Dump Socket.1299second # [ 6.563398] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1300test # [ 6.610007] systemd[1]: Listening on Credential Encryption/Decryption.1301test # [ 6.611977] systemd[1]: Listening on Factory Reset Management.1302test # [ 6.612454] systemd[1]: Listening on Hostname Service Socket.1303test # [ 6.614679] systemd[1]: Starting Journal Log Access Socket...1304test # [ 6.618017] systemd[1]: Listening on Journal Audit Socket.1305test # [ 6.620860] systemd[1]: Listening on Console Output Muting Service Socket.1306test # [ 6.621574] systemd[1]: Listening on Network Management Metrics Varlink Socket.1307test # [ 6.622386] systemd[1]: Listening on Network Management Varlink Socket.1308test # [ 6.623085] systemd[1]: Listening on Network Management Netlink Socket.1309second # [ 6.580126] systemd[1]: Starting Remount Root and Kernel File Systems...1310second # [ 6.580578] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1311test # [ 6.627470] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1312test # [ 6.628927] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1313test # [ 6.629366] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1314second # [ 6.586386] systemd[1]: Starting Coldplug All udev Devices...1315test # [ 6.634612] systemd[1]: Listening on Disk Repartitioning Service Socket.1316test # [ 6.635187] systemd[1]: Listening on Resolve Monitor Varlink Socket.1317test # [ 6.637041] systemd[1]: Listening on Resolve Service Varlink Socket.1318test # [ 6.637720] systemd[1]: Listening on udev Varlink Socket.1319second # [ 6.592659] systemd[1]: Listening on Journal Log Access Socket.1320second # [ 6.593191] systemd[1]: Mounted Huge Pages File System.1321second # [ 6.593579] systemd[1]: Mounted POSIX Message Queue File System.1322second # [ 6.593937] systemd[1]: Mounted Kernel Debug File System.1323second # [ 6.594311] systemd[1]: Mounted Kernel Trace File System.1324test # [ 6.640958] systemd[1]: Mounting Huge Pages File System...1325test # [ 6.643024] systemd[1]: Mounting POSIX Message Queue File System...1326second # [ 6.609878] systemd[1]: Finished Create List of Static Device Nodes.1327test # [ 6.655607] systemd[1]: Mounting Kernel Debug File System...1328test # [ 6.662071] systemd[1]: Mounting Kernel Trace File System...1329second # [ 6.618518] systemd-journald[300]: Collecting audit messages is enabled.1330test # [ 6.668535] systemd[1]: Starting Create List of Static Device Nodes...1331second # [ 6.619533] systemd[1]: Queued start job for default target Multi-User System.1332test # [ 6.671994] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1333second # [ 6.625300] systemd[1]: systemd-journald.service: Deactivated successfully.1334second # [ 6.626242] systemd-modules-load[301]: Module 'atkbd' is built in1335second # [ 6.626936] systemd-modules-load[301]: Module 'ctr' is built in1336second # [ 6.627611] systemd-modules-load[301]: Module 'loop' is built in1337second # [ 6.637726] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1338second # [ 6.638343] systemd[1]: Started Journal Service.1339test # [ 6.683533] systemd[1]: Mounting Kernel Configuration File System...1340test # [ 6.683981] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1341second # [ 6.641080] systemd[1]: Finished Load Kernel Modules.1342test # [ 6.691704] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1343test # [ 6.692148] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1344test # [ 6.702311] systemd[1]: Mounting FUSE Control File System...1345test # [ 6.702773] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671346second # [ 6.681152] systemd[1]: Starting Firewall...1347test # [ 6.731787] systemd[1]: Starting Journal Service...1348test # [ 6.753793] systemd[1]: Starting Load Kernel Modules...1349second # [ 6.706640] systemd[1]: Starting Apply Kernel Variables...1350second # [ 6.707274] systemd[1]: Mounted Kernel Configuration File System.1351second # [ 6.707833] systemd[1]: Mounted FUSE Control File System.1352test # [ 6.773014] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1353second # [ 6.734953] systemd-oomd[302]: No swap; memory pressure usage will be degraded1354test # [ 6.788763] systemd[1]: Starting Remount Root and Kernel File Systems...1355test # [ 6.789205] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1356second # [ 6.747034] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1357second # [ 6.761064] EXT4-fs (vda): re-mounted 6b3de305-291a-48de-bcfe-ede0e3f362bc.1358test # [ 6.811196] systemd[1]: Starting Coldplug All udev Devices...1359test # [ 6.815220] systemd[1]: Listening on Journal Log Access Socket.1360second # [ 6.768335] systemd[1]: Finished Remount Root and Kernel File Systems.1361second # [ 6.769073] systemd[1]: Listening on Disk Image Download Service Socket.1362test # [ 6.820102] systemd-journald[322]: Collecting audit messages is enabled.1363second # [ 6.777531] systemd[1]: Starting Flush Journal to Persistent Storage...1364second # [ 6.778234] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1365test # [ 6.824644] systemd[1]: Queued start job for default target Multi-User System.1366test # [ 6.825605] systemd[1]: systemd-journald.service: Deactivated successfully.1367test # [ 6.838587] systemd[1]: Started Journal Service.1368second # [ 6.788105] systemd[1]: Starting Load/Save OS Random Seed...1369second # [ 6.788717] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1370test # [ 6.844658] systemd[1]: Mounted Huge Pages File System.1371test # [ 6.848208] systemd-modules-load[323]: Module 'atkbd' is built in1372test # [ 6.851922] systemd-modules-load[323]: Module 'ctr' is built in1373test # [ 6.856553] systemd-modules-load[323]: Module 'loop' is built in1374test # [ 6.857294] systemd[1]: Mounted POSIX Message Queue File System.1375test # [ 6.857910] systemd[1]: Mounted Kernel Debug File System.1376test # [ 6.858450] systemd[1]: Mounted Kernel Trace File System.1377test # [ 6.858980] systemd[1]: Finished Create List of Static Device Nodes.1378test # [ 6.877551] systemd[1]: Finished Load Kernel Modules.1379test # [ 6.878225] systemd[1]: Mounted Kernel Configuration File System.1380test # [ 6.890288] systemd[1]: Starting Firewall...1381test # [ 6.892206] systemd[1]: Starting Apply Kernel Variables...1382second # [ 6.866105] systemd-journald[300]: Received client request to flush runtime journal.1383test # [ 6.909654] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1384test # [ 6.910499] systemd[1]: Mounted FUSE Control File System.1385test # [ 6.951700] EXT4-fs (vda): re-mounted 2bb49cb6-9813-4735-840e-d427251afe92.1386test # [ 6.955571] systemd[1]: Finished Remount Root and Kernel File Systems.1387test # [ 6.958251] systemd[1]: Listening on Disk Image Download Service Socket.1388test # [ 6.961401] systemd-oomd[325]: No swap; memory pressure usage will be degraded1389test # [ 6.971690] systemd[1]: Starting Flush Journal to Persistent Storage...1390test # [ 6.975842] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1391test # [ 6.979431] systemd[1]: Starting Load/Save OS Random Seed...1392test # [ 6.984249] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1393test # [ 6.985166] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1394second # [ 6.959405] systemd[1]: Finished Apply Kernel Variables.1395second # [ 6.960353] systemd[1]: Starting Network Name Resolution...1396second # [ 6.961041] systemd[1]: Finished Load/Save OS Random Seed.1397second # [ 6.961648] systemd[1]: Reached target First Boot Complete.1398second # [ 6.962177] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1399second # [ 6.962847] systemd[1]: Starting Create Static Device Nodes in /dev...1400second # [ 6.984175] systemd[1]: Finished Flush Journal to Persistent Storage.1401test # [ 7.075576] systemd-journald[322]: Received client request to flush runtime journal.1402second # [ 7.033927] systemd-resolved[326]: Positive Trust Anchors:1403second # [ 7.034552] systemd-resolved[326]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1404second # [ 7.035426] systemd-resolved[326]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161405second # [ 7.045101] systemd-resolved[326]: 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 test1406second # [ 7.064604] systemd-resolved[326]: Using system hostname 'second'.1407second # [ 7.067731] systemd[1]: Started Network Name Resolution.1408test # [ 7.121454] systemd[1]: Finished Apply Kernel Variables.1409test # [ 7.124134] systemd[1]: Starting Network Name Resolution...1410test # [ 7.124774] systemd[1]: Finished Load/Save OS Random Seed.1411test # [ 7.125325] systemd[1]: Reached target First Boot Complete.1412test # [ 7.136281] systemd[1]: Finished Flush Journal to Persistent Storage.1413test # [ 7.165751] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1414test # [ 7.176324] systemd[1]: Starting Create Static Device Nodes in /dev...1415second # [ 7.138132] systemd[1]: Finished Create Static Device Nodes in /dev.1416second # [ 7.138850] systemd[1]: Reached target Preparation for Local File Systems.1417second # [ 7.141228] systemd[1]: Starting Rule-based Manager for Device Events and Files...1418test # [ 7.247798] systemd-resolved[349]: Positive Trust Anchors:1419test # [ 7.249326] systemd-resolved[349]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1420test # [ 7.250310] systemd-resolved[349]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161421test # [ 7.251252] systemd-resolved[349]: 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 test1422test # [ 7.277223] systemd-resolved[349]: Using system hostname 'test'.1423second # [ 7.236091] systemd[1]: Finished Coldplug All udev Devices.1424test # [ 7.282540] systemd[1]: Started Network Name Resolution.1425second # [ 7.259088] systemd-udevd[341]: Using default interface naming scheme 'v261'.1426second # [ 7.328707] systemd[1]: Started Rule-based Manager for Device Events and Files.1427test # [ 7.410189] systemd[1]: Finished Create Static Device Nodes in /dev.1428test # [ 7.410914] systemd[1]: Reached target Preparation for Local File Systems.1429second # [ 7.374130] systemd[1]: Mounting /run/wrappers...1430test # [ 7.417241] systemd[1]: Starting Rule-based Manager for Device Events and Files...1431test # [ 7.447268] systemd[1]: Finished Coldplug All udev Devices.1432test # [ 7.491187] systemd-udevd[362]: Using default interface naming scheme 'v261'.1433second # [ 7.454404] systemd[1]: Mounted /run/wrappers.1434second # [ 7.454979] systemd[1]: Reached target Local File Systems.1435second # [ 7.459963] systemd[1]: Listening on Boot Loader Control Service Socket.1436second # [ 7.463562] systemd[1]: Starting register-nix-paths.service...1437second # [ 7.468085] systemd[1]: Starting Create SUID/SGID Wrappers...1438second # [ 7.468735] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1439second # [ 7.474546] systemd[1]: Starting Save Transient machine-id to Disk...1440second # [ 7.479248] systemd[1]: Starting Create System Files and Directories...1441second # [ 7.479977] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1442second # [ 7.524488] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1443test # [ 7.571789] systemd[1]: Mounting /run/wrappers...1444test # [ 7.588411] systemd[1]: Started Rule-based Manager for Device Events and Files.1445test # [ 7.668849] systemd[1]: Mounted /run/wrappers.1446test # [ 7.669448] systemd[1]: Reached target Local File Systems.1447test # [ 7.683902] systemd[1]: Listening on Boot Loader Control Service Socket.1448test # [ 7.684845] systemd[1]: Starting register-nix-paths.service...1449test # [ 7.692170] systemd[1]: Starting Create SUID/SGID Wrappers...1450test # [ 7.692911] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1451test # [ 7.695901] systemd[1]: Starting Save Transient machine-id to Disk...1452test # [ 7.706130] systemd[1]: Starting Create System Files and Directories...1453second # [ 7.672336] systemd[1]: Finished Create System Files and Directories.1454second # [ 7.683005] systemd[1]: Starting Rebuild Journal Catalog...1455second # [ 7.689399] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1456second # [ 7.757123] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1457test # [ 7.799231] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1458second # [ 7.766275] systemd[1]: Finished Save Transient machine-id to Disk.1459test # [ 7.808804] systemd[1]: Finished Save Transient machine-id to Disk.1460second # [ 7.794617] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1461test # [ 7.865170] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1462test # [ 7.880456] systemd[1]: Finished Create System Files and Directories.1463test # [ 7.887055] systemd[1]: Starting Rebuild Journal Catalog...1464test # [ 7.893323] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1465second # [ 7.856370] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1466second # [ 7.868152] systemd[1]: Finished Rebuild Journal Catalog.1467second # [ 7.874569] systemd[1]: Starting Update is Completed...1468test # [ 7.938656] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1469second # [ 7.944389] systemd[1]: Finished Update is Completed.1470second # [ 7.979188] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1471test # [ 8.065116] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1472test # [ 8.079643] systemd[1]: Finished Rebuild Journal Catalog.1473test # [ 8.089743] systemd[1]: Starting Update is Completed...1474test # [ 8.161081] systemd[1]: Finished Update is Completed.1475test # [ 8.219830] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1476test # [ 8.289974] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1477second # [ 8.275109] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line.1478second # [ 8.280517] (udev-worker)[366]: Network interface NamePolicy= disabled on kernel command line.1479second # [ 8.333614] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1480second # [ 8.335694] systemd[1]: Finished Create SUID/SGID Wrappers.1481test # [ 8.528613] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1482test # [ 8.535438] (udev-worker)[401]: Network interface NamePolicy= disabled on kernel command line.1483second # [ 8.539610] systemd[1]: Finished register-nix-paths.service.1484second # [ 8.540717] systemd[1]: Reached target System Initialization.1485second # [ 8.541321] systemd[1]: Started Discard unused filesystem blocks once a week.1486second # [ 8.542006] systemd[1]: Started Daily Cleanup of Temporary Directories.1487second # [ 8.542668] systemd[1]: Reached target Timer Units.1488second # [ 8.547532] systemd[1]: Listening on D-Bus System Message Bus Socket.1489second # [ 8.551330] systemd[1]: Listening on Nix Daemon Socket.1490second # [ 8.556435] systemd[1]: Listening on Authorization Manager Agent Helper.1491second # [ 8.557313] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1492second # [ 8.558159] systemd[1]: Reached target Socket Units.1493second # [ 8.562651] systemd[1]: Starting D-Bus System Message Bus...1494second # [ 8.568419] mousedev: PS/2 mouse device common for all mice1495test # [ 8.608228] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1496test # [ 8.609171] systemd[1]: Finished Create SUID/SGID Wrappers.1497second # [ 8.709934] dbus-broker-launch[505]: Looking up NSS user entry for 'systemd-timesync'...1498second # [ 8.715754] dbus-broker-launch[505]: NSS returned no entry for 'systemd-timesync'1499test # [ 8.758955] systemd[1]: Finished register-nix-paths.service.1500test # [ 8.759646] systemd[1]: Reached target System Initialization.1501second # [ 8.717487] dbus-broker-launch[505]: Invalid user-name in /nix/store/pck2w3smbwrh948q75yfark74z6v1l71-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1502test # [ 8.762003] systemd[1]: Started Discard unused filesystem blocks once a week.1503test # [ 8.762716] systemd[1]: Started Daily Cleanup of Temporary Directories.1504test # [ 8.763353] systemd[1]: Reached target Timer Units.1505test # [ 8.763865] systemd[1]: Listening on D-Bus System Message Bus Socket.1506test # [ 8.772152] systemd[1]: Listening on Nix Daemon Socket.1507test # [ 8.776121] systemd[1]: Listening on Authorization Manager Agent Helper.1508test # [ 8.779897] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1509test # [ 8.783013] systemd[1]: Reached target Socket Units.1510test # [ 8.785737] systemd[1]: Starting D-Bus System Message Bus...1511second # [ 8.755200] dbus-broker-launch[505]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1512second # [ 8.760573] dbus-broker-launch[505]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1513second # [ 8.762627] dbus-broker-launch[505]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/35a9madwirlwb9wnblmwzbdv54cw49pj-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1514second # [ 8.777931] dbus-broker-launch[505]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/xsp02xw40qnj0xybs6i40055kkc9mxyl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1515second # [ 8.789676] systemd[1]: Started D-Bus System Message Bus.1516second # [ 8.790424] systemd[1]: Reached target Basic System.1517second # [ 8.790990] systemd[1]: Starting Network Manager Script Dispatcher Service...1518second # [ 8.791679] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1519second # [ 8.805432] systemd[1]: Started backdoor.service.1520second # [ 8.822884] systemd[1]: Starting Import lastlog data into lastlog2 database...1521second # [ 8.840417] dbus-broker-launch[505]: Ready1522second # [ 8.843829] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1523second # [ 8.859167] systemd[1]: Starting Post-Boot Actions...1524test # [ 8.922245] dbus-broker-launch[521]: Looking up NSS user entry for 'systemd-timesync'...1525test # [ 8.926826] dbus-broker-launch[521]: NSS returned no entry for 'systemd-timesync'1526test # [ 8.927593] dbus-broker-launch[521]: Invalid user-name in /nix/store/pck2w3smbwrh948q75yfark74z6v1l71-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1527second # [ 8.899381] systemd[1]: Started Reset console on configuration changes.1528second # [ 8.904163] systemd[1]: Finished Firewall.1529second # [ 8.913982] systemd[1]: Reached target Preparation for Network.1530second # [ 8.916476] systemd[1]: Listening on Network Management Resolve Hook Socket.1531test # [ 8.973863] mousedev: PS/2 mouse device common for all mice1532test # [ 8.992750] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1533test # [ 8.996454] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/mn4lmv01d4rl5y8l37k6hjlpdh2xpdfg-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1534second # [ 8.958622] systemd[1]: Starting Network Manager...1535test # [ 9.004542] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/35a9madwirlwb9wnblmwzbdv54cw49pj-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1536test # [ 9.008283] dbus-broker-launch[521]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/xsp02xw40qnj0xybs6i40055kkc9mxyl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1537test # [ 9.016435] systemd[1]: Started D-Bus System Message Bus.1538test # [ 9.017063] systemd[1]: Reached target Basic System.1539test # [ 9.022677] systemd[1]: Starting Network Manager Script Dispatcher Service...1540second # [ 8.986379] systemd[1]: Starting Network Management...1541test # [ 9.030794] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1542test # [ 9.036106] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1543second # [ 8.998584] wxrshyqifd3a301c2q4frbrsgvl9225i-wifi-secrets[524]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1544test # [ 9.047299] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545test # [ 9.080444] systemd[1]: Started backdoor.service.1546second # connecting to host...1547test # [ 9.083613] dbus-broker-launch[521]: Ready1548second # [ 9.048793] systemd[1]: Finished Post-Boot Actions.1549second # [ 9.049391] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1550second # [ 9.071293] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1551second # [ 9.074532] systemd[1]: Started Name Service Cache Daemon (nsncd).1552test # [ 9.126996] systemd[1]: Starting Import lastlog data into lastlog2 database...1553test # [ 9.162698] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1554second # [ 9.118819] nsncd[527]: Oct 22 10:03:30.813 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1555second # [ 9.145831] systemd[1]: Reached target Host and Network Name Lookups.1556second # [ 9.146525] systemd[1]: Reached target User and Group Name Lookups.1557second # [ 9.160496] systemd[1]: Starting User Login Management...1558test # [ 9.211574] i7s7ishricp8ddyw1z8fynkr3bhavn7n-wifi-secrets[540]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1559test # [ 9.224586] systemd[1]: Starting Post-Boot Actions...1560test # [ 9.225192] systemd[1]: Started Reset console on configuration changes.1561second # [ 9.204933] systemd[1]: Finished Import lastlog data into lastlog2 database.1562second # [ 9.213832] systemd[1]: Started Network Manager Script Dispatcher Service.1563test # [ 9.258745] wxrshyqifd3a301c2q4frbrsgvl9225i-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1564test # [ 9.309866] zirzjmjcpcp2h9bmxad7ld6caiwshd23-wifi-secrets[541]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1565test # [ 9.318578] systemd[1]: Finished Firewall.1566test # [ 9.319249] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1567test # [ 9.327781] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1568test # [ 9.335182] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1569test # connecting to host...1570test # [ 9.343641] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1571test # [ 9.348205] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1572test # [ 9.354924] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573test # [ 9.359933] systemd[1]: Finished Post-Boot Actions.1574test # [ 9.383255] systemd[1]: Started Name Service Cache Daemon (nsncd).1575second # [ 9.343750] systemd-logind[559]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1576second # [ 9.349792] systemd-logind[559]: Watching system buttons on /dev/input/event0 (gpio-keys)1577second # [ 9.357117] systemd-logind[559]: New seat seat0.1578second # [ 9.357624] systemd[1]: Started User Login Management.1579second # [ 9.362292] systemd[1]: Starting linger-users.service...1580test # [ 9.416632] systemd[1]: Reached target Preparation for Network.1581test # [ 9.417290] systemd[1]: Reached target Host and Network Name Lookups.1582test # [ 9.417862] systemd[1]: Reached target User and Group Name Lookups.1583test # [ 9.418406] systemd[1]: Listening on Network Management Resolve Hook Socket.1584test: Guest shell says: b'Spawning backdoor root shell...\n'1585test # [ 9.430666] systemd[1]: Starting Network Manager...1586test # [ 9.434726] nsncd[547]: Oct 22 10:03:31.141 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1587test # [ 9.443231] systemd[1]: Starting User Login Management...1588test # [ 9.454682] systemd[1]: Starting Network Management...1589test: connected to guest root shell1590test: (connecting took 9.69 seconds)1591test: (finished: waiting for the VM to finish booting, in 9.69 seconds)1592second # [ 9.449120] cfg80211: Loading compiled-in X.509 certificates for regulatory database1593test # [ 9.487088] systemd[1]: Finished Import lastlog data into lastlog2 database.1594second # [ 9.461035] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1595second # [ 9.461552] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1596test # [ 9.527041] systemd[1]: Started Network Manager Script Dispatcher Service.1597second # [ 9.487077] systemd[1]: Starting Hostname Service...1598second # [ 9.497581] systemd[1]: linger-users.service: Deactivated successfully.1599second # [ 9.499678] systemd[1]: Finished linger-users.service.1600second # [ 9.521472] systemd-networkd[536]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1601second # [ 9.535252] systemd-networkd[536]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1602second # [ 9.540771] systemd-networkd[536]: lo: Link UP1603second # [ 9.541267] systemd-networkd[536]: lo: Gained carrier1604second # [ 9.548540] systemd[1]: Started Network Management.1605second # [ 9.549082] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1606second # [ 9.550434] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1607second # [ 9.568798] systemd-networkd[536]: eth1: Link UP1608second # [ 9.569319] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1609second # [ 9.569974] systemd-networkd[536]: eth1: Gained carrier1610second # [ 9.570494] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1611test # [ 9.676251] systemd-logind[589]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1612second # [ 9.635749] systemd[1]: Started Hostname Service.1613test # [ 9.680422] systemd-logind[589]: Watching system buttons on /dev/input/event0 (gpio-keys)1614test # [ 9.685239] systemd-logind[589]: New seat seat0.1615test # [ 9.685780] systemd[1]: Started User Login Management.1616second # [ 9.653100] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1617test # [ 9.696550] systemd[1]: Starting linger-users.service...1618second # [ 9.716591] systemd-networkd[536]: eth0: Link UP1619second # [ 9.717110] systemd-networkd[536]: eth0: Gained carrier1620second # [ 9.744991] systemd[1]: Started Network Manager.1621second # [ 9.747881] systemd[1]: Reached target Network.1622second # [ 9.758181] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1623second # [ 9.767750] systemd[1]: Starting Permit User Sessions...1624test # [ 9.834121] cfg80211: Loading compiled-in X.509 certificates for regulatory database1625test # [ 9.835663] systemd[1]: linger-users.service: Deactivated successfully.1626test # [ 9.837114] systemd[1]: Finished linger-users.service.1627test # [ 9.850233] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1628test # [ 9.850840] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1629test # [ 9.906170] systemd-networkd[590]: /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.1630test # [ 9.916223] systemd-networkd[590]: /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.1631test # [ 9.918114] systemd-networkd[590]: lo: Link UP1632test # [ 9.918577] systemd-networkd[590]: lo: Gained carrier1633test # [ 9.929755] systemd[1]: Starting Hostname Service...1634test # [ 9.935027] systemd-networkd[590]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1635test # [ 9.944716] systemd-networkd[590]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1636test # [ 9.945474] systemd[1]: Started Network Management.1637test # [ 9.945944] systemd-networkd[590]: eth1: Link UP1638test # [ 9.946387] systemd-networkd[590]: eth1: Gained carrier1639test # [ 9.946885] systemd-networkd[590]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1640second # [ 9.908184] systemd[1]: Finished Permit User Sessions.1641test # [ 9.961190] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1642second # [ 9.930427] systemd[1]: Started Getty on tty1.1643second # [ 9.933398] systemd[1]: Reached target Login Prompts.1644second # [ 9.959263] systemd-resolved[326]: eth0: Bus client set default route setting: yes1645second # [ 9.968569] systemd-resolved[326]: eth0: Bus client set DNS server list to: 10.0.2.31646test # [ 10.063657] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1647test # [ 10.103047] systemd[1]: Started Hostname Service.1648second # [ 10.067314] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1649second # [ 10.069735] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1650test # [ 10.187073] systemd-networkd[590]: eth0: Link UP1651test # [ 10.187603] systemd-networkd[590]: eth0: Gained carrier1652test # [ 10.212338] systemd[1]: Started Network Manager.1653test # [ 10.215021] systemd[1]: Reached target Network.1654test # [ 10.220578] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1655test # [ 10.230325] systemd[1]: Starting Permit User Sessions...1656second # [ 10.246712] systemd[1]: Reached target Multi-User System.1657second # [ 10.251061] systemd[1]: Startup finished in 875ms (kernel) + 4.503s (initrd) + 4.870s (userspace) = 10.249s.1658second # [ 10.298046] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31659test # [ 10.362840] systemd[1]: Finished Permit User Sessions.1660test # [ 10.384132] systemd[1]: Started Getty on tty1.1661test # [ 10.386781] systemd[1]: Reached target Login Prompts.1662test # [ 10.408896] systemd-resolved[349]: eth0: Bus client set default route setting: yes1663test # [ 10.413763] systemd-resolved[349]: eth0: Bus client set DNS server list to: 10.0.2.31664test # [ 10.448853] NetworkManager[588]: <warn> [1792663412.1562] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1665test # [ 10.457887] NetworkManager[588]: <warn> [1792663412.1602] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1666test # [ 10.505536] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1667test # [ 10.508209] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1668test # [ 10.543230] systemd[1]: Reached target Multi-User System.1669test # [ 10.547300] systemd[1]: Startup finished in 923ms (kernel) + 4.614s (initrd) + 5.006s (userspace) = 10.544s.1670second # [ 10.626579] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1671second # [ 10.642255] systemd[1]: Starting Virtual Console Setup...1672second # [ 10.662396] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1673second # [ 10.664759] systemd[1]: Stopped Virtual Console Setup.1674second # [ 10.671549] systemd[1]: Starting Virtual Console Setup...1675test: (finished: waiting for unit NetworkManager.service, in 10.94 seconds)1676test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1677second # [ 10.693796] systemd-logind[559]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1678test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds)1679cleanup1680kill QemuMachine (pid 47)1681second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)1682second # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1683second # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1684second # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1685test # [ 10.868422] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31686kill QemuMachine (pid 48)1687test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lq2fsnmg0q3y0vlxn91w8s8wvj1m6fld-python3-3.14.8/bin/python3.14)1688test # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1689test # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1690test # [2026-10-22T10:03:32Z INFO virtiofsd] Client disconnected, shutting down1691(finished: cleanup, in 0.31 seconds)1692Traceback (most recent call last):1693 File "/nix/store/4sclkna8930rvd8i24i3fdmfp9l6l1r7-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1694 sys.exit(main())1695 ~~~~^^1696 File "/nix/store/4sclkna8930rvd8i24i3fdmfp9l6l1r7-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1697 driver.run_tests()1698 ~~~~~~~~~~~~~~~~^^1699 File "/nix/store/4sclkna8930rvd8i24i3fdmfp9l6l1r7-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests1700 self.test_script()1701 ~~~~~~~~~~~~~~~~^^1702 File "/nix/store/4sclkna8930rvd8i24i3fdmfp9l6l1r7-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script1703 exec(self.tests, symbols, None)1704 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1705 File "<string>", line 4, in <module>1706AssertionError: Expected mock password in connection file:1707[connection]1708autoconnect=true1709id=1710type=wifi17111712[wifi]1713mode=infrastructure1714ssid=17151716[wifi-security]1717key-mgmt=wpa-psk1718psk=1719