vm-test-run-wifi
checks.aarch64-linux.wifi
· build #220
· raw
1build01: building rgsm7k52sdhh8a51hbswrznjcwfmmdjq-vm-test-run-wifi.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 second, test,9 vlan1,10 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh11start all VMs12second: starting vm13test: starting vm14test # Disk image does not exist, creating the virtualisation disk image...15test: QEMU running (pid 48)16test # Formatting '/build/vm-state-test/tmp.uYD1pkKIMc', fmt=raw size=107374182417test # mke2fs 1.47.4 (6-Mar-2025)18test # Discarding device blocks: 0/262144 done19test # Creating filesystem with 262144 4k blocks and 65536 inodes20test # Filesystem UUID: f57741b7-2a0a-45db-96ac-6d3265aa528221test # Superblock backups stored on blocks:22test # 32768, 98304, 163840, 22937623test # 24test # Allocating group tables: 0/8 done25test # Writing inode tables: 0/8 done26test # Creating journal (8192 blocks): done27test # Writing superblocks and filesystem accounting information: 0/8 done28second # Disk image does not exist, creating the virtualisation disk image...29test # 30second # Formatting '/build/vm-state-second/tmp.u3Sax3nYk1', fmt=raw size=107374182431second: QEMU running (pid 47)32second # mke2fs 1.47.4 (6-Mar-2025)33test # Virtualisation disk image created.34second # Discarding device blocks: 0/262144 done35test # Starting virtiofs daemons...36second # Creating filesystem with 262144 4k blocks and 65536 inodes37test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)38second # Filesystem UUID: d4728724-7795-49c7-8616-25a8305a52be39test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether40second # Superblock backups stored on blocks:41test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)42second # 32768, 98304, 163840, 22937643test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether44second # 45test # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...46second # Allocating group tables: 0/8 done47(finished: start all VMs, in 0.39 seconds)48second # Writing inode tables: 0/8 done49test: waiting for unit NetworkManager.service50second # Creating journal (8192 blocks): done51test: waiting for the VM to finish booting52second # Writing superblocks and filesystem accounting information: 0/8 done53test # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...54second # 55test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)56second # Virtualisation disk image created.57test # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether58second # Starting virtiofs daemons...59test # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...60second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)61test # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests62second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether63test # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests64second # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...65test # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests66second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)67second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether68second # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...69second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)70second # [2026-10-04T20:14:26Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether71second # [2026-10-04T20:14:26Z INFO virtiofsd] Waiting for vhost-user socket connection...72second # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests73second # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests74second # [2026-10-04T20:14:26Z INFO virtiofsd] Client connected, servicing requests75test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]76test # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 202677test # [ 0.000000] KASLR enabled78test # [ 0.000000] random: crng init done79test # [ 0.000000] Machine model: linux,dummy-virt80test # [ 0.000000] efi: UEFI not found.81test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT82test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]83test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]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 s186776 r8192 d116328 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 alternatives111second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]112second # [ 0.000000] Linux version 6.18.54 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Sep 25 14:35:54 UTC 2026113second # [ 0.000000] KASLR enabled114second # [ 0.000000] random: crng init done115test # [ 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/5xlw8xlb2i17jsicszzylcm6hq4s3znn-nixos-system-test-test/init regInfo=/nix/.ro-store/mh3y8ji9r9bkak2nnszqwx7askwcz40k-closure-info/registration console=ttyAMA0,115200n8 console=tty0116second # [ 0.000000] Machine model: linux,dummy-virt117second # [ 0.000000] efi: UEFI not found.118second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT119test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/mh3y8ji9r9bkak2nnszqwx7askwcz40k-closure-info/registration", will be passed to user space.120second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]121test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes122second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc08700-0x7fc0be7f]123test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)124second # [ 0.000000] Zone ranges:125second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]126test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)127second # [ 0.000000] DMA32 empty128second # [ 0.000000] Normal empty129test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB130second # [ 0.000000] Device empty131test # [ 0.000000] software IO TLB: area num 1.132second # [ 0.000000] Movable zone start for each node133second # [ 0.000000] Early memory node ranges134test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)135second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]136test # [ 0.000000] Fallback order for Node 0: 0137test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144138second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]139test # [ 0.000000] Policy zone: DMA140second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000141test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off142second # [ 0.000000] psci: probing for conduit method from DT.143test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1144second # [ 0.000000] psci: PSCIv1.3 detected in firmware.145test # [ 0.000000] allocated 2097152 bytes of page_ext146second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs147second # [ 0.000000] psci: Trusted OS migration not required148test # [ 0.000000] ftrace: allocating 75026 entries in 294 pages149second # [ 0.000000] psci: SMC Calling Convention v1.1150test # [ 0.000000] ftrace: allocated 294 pages with 4 groups151test # [ 0.000000] rcu: Hierarchical RCU implementation.152second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)153test # [ 0.000000] rcu: RCU event tracing is enabled.154second # [ 0.000000] percpu: Embedded 76 pages/cpu s186776 r8192 d116328 u311296155test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.156second # [ 0.000000] Detected PIPT I-cache on CPU0157test # [ 0.000000] Trampoline variant of Tasks RCU enabled.158second # [ 0.000000] CPU features: detected: GICv3 CPU interface159second # [ 0.000000] CPU features: detected: Spectre-v4160test # [ 0.000000] Rude variant of Tasks RCU enabled.161second # [ 0.000000] CPU features: detected: Spectre-BHB162test # [ 0.000000] Tracing variant of Tasks RCU enabled.163second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR164test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.165second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)166test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1167second # [ 0.000000] CPU features: detected: ARM erratum 1418040168test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion170second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing171test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172second # [ 0.000000] alternatives: applying boot alternatives173test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0175test # [ 0.000000] GICv3: 256 SPIs implemented176test # [ 0.000000] GICv3: 0 Extended SPIs implemented177test # [ 0.000000] Root IRQ handler: gic_handle_irq178test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI179test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0180second # [ 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/255cn07si1s5z1lxy17xrnr4i6k6zl3v-nixos-system-second-test/init regInfo=/nix/.ro-store/kc3a6w339y7ncsbnzsd4pb4qfr4pzz25-closure-info/registration console=ttyAMA0,115200n8 console=tty0181test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000182test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]183second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/.ro-store/kc3a6w339y7ncsbnzsd4pb4qfr4pzz25-closure-info/registration", will be passed to user space.184test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)185second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes186second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)187test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)188test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000189second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)190second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB191test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000192second # [ 0.000000] software IO TLB: area num 1.193test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.194second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)195second # [ 0.000000] Fallback order for Node 0: 0196test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns197second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144198test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040199second # [ 0.000000] Policy zone: DMA200test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).201second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off202second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1203test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns204second # [ 0.000000] allocated 2097152 bytes of page_ext205test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns206second # [ 0.000000] ftrace: allocating 75026 entries in 294 pages207test # [ 0.000043] arm-pv: using stolen time PV208second # [ 0.000000] ftrace: allocated 294 pages with 4 groups209second # [ 0.000000] rcu: Hierarchical RCU implementation.210test # [ 0.000355] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)211second # [ 0.000000] rcu: RCU event tracing is enabled.212test # [ 0.000621] Console: colour dummy device 80x25213second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.214test # [ 0.000632] printk: legacy console [tty0] enabled215second # [ 0.000000] Trampoline variant of Tasks RCU enabled.216second # [ 0.000000] Rude variant of Tasks RCU enabled.217test # [ 0.000855] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)218second # [ 0.000000] Tracing variant of Tasks RCU enabled.219test # [ 0.000860] pid_max: default: 32768 minimum: 301220second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.221test # [ 0.000938] LSM: initializing lsm=capability,landlock,yama,bpf,ima222test # [ 0.001065] landlock: Up and running.223second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1224test # [ 0.001067] Yama: becoming mindful.225test # [ 0.001447] LSM support for eBPF active226second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.227test # [ 0.001622] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)228second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.229test # [ 0.001644] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)230test # [ 0.004290] rcu: Hierarchical SRCU implementation.231second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.232test # [ 0.004296] rcu: Max phase no-delay instances is 1000.233second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0234test # [ 0.005254] fsl-mc MSI: its@8080000 domain created235second # [ 0.000000] GICv3: 256 SPIs implemented236test # [ 0.005443] EFI services will not be available.237second # [ 0.000000] GICv3: 0 Extended SPIs implemented238test # [ 0.005591] smp: Bringing up secondary CPUs ...239second # [ 0.000000] Root IRQ handler: gic_handle_irq240test # [ 0.005635] smp: Brought up 1 node, 1 CPU241second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI242test # [ 0.005639] SMP: Total of 1 processors activated.243second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0244test # [ 0.005641] CPU: All CPU(s) started at EL1245second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000246test # [ 0.005654] CPU features: detected: 32-bit EL0 Support247second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]248test # [ 0.005656] CPU features: detected: Data cache clean to the PoU not required for I/D coherence249second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)250test # [ 0.005659] CPU features: detected: Common not Private translations251second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)252test # [ 0.005661] CPU features: detected: CRC32 instructions253test # [ 0.005663] CPU features: detected: Data cache clean to Point of Persistence254second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000255test # [ 0.005666] CPU features: detected: Enhanced Virtualization Traps256second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000257test # [ 0.005676] CPU features: detected: RCpc load-acquire (LDAPR)258second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.259test # [ 0.005678] CPU features: detected: LSE atomic instructions260test # [ 0.005680] CPU features: detected: Privileged Access Never261second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns262test # [ 0.005682] CPU features: detected: PMUv3263second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040264test # [ 0.005683] CPU features: detected: RAS Extension Support265second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).266test # [ 0.005687] CPU features: detected: Speculative Store Bypassing Safe (SSBS)267test # [ 0.005707] alternatives: applying system-wide alternatives268second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns269test # [ 0.007215] CPU features: detected: Hardware dirty bit management on CPU0270second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns271second # [ 0.000044] arm-pv: using stolen time PV272test # [ 0.007439] Memory: 891216K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116040K reserved, 32768K cma-reserved)273second # [ 0.000339] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)274test # [ 0.007954] devtmpfs: initialized275second # [ 0.000623] Console: colour dummy device 80x25276second # [ 0.000634] printk: legacy console [tty0] enabled277test # [ 0.009437] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)278second # [ 0.000849] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)279test # [ 0.009463] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).280second # [ 0.000855] pid_max: default: 32768 minimum: 301281test # [ 0.009598] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL282second # [ 0.000949] LSM: initializing lsm=capability,landlock,yama,bpf,ima283test # [ 0.009601] 0 pages in range for non-PLT usage284second # [ 0.001075] landlock: Up and running.285test # [ 0.009602] 508208 pages in range for PLT usage286second # [ 0.001078] Yama: becoming mindful.287test # [ 0.009717] pinctrl core: initialized pinctrl subsystem288second # [ 0.001461] LSM support for eBPF active289test # [ 0.010451] DMI not present or invalid.290second # [ 0.001631] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)291test # [ 0.013717] NET: Registered PF_NETLINK/PF_ROUTE protocol family292second # [ 0.001649] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)293test # [ 0.016299] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations294second # [ 0.004205] rcu: Hierarchical SRCU implementation.295test # [ 0.016429] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations296second # [ 0.004211] rcu: Max phase no-delay instances is 1000.297second # [ 0.005196] fsl-mc MSI: its@8080000 domain created298test # [ 0.016575] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations299second # [ 0.005388] EFI services will not be available.300test # [ 0.016592] audit: initializing netlink subsys (disabled)301second # [ 0.005475] smp: Bringing up secondary CPUs ...302test # [ 0.017171] thermal_sys: Registered thermal governor 'fair_share'303second # [ 0.005515] smp: Brought up 1 node, 1 CPU304second # [ 0.005519] SMP: Total of 1 processors activated.305test # [ 0.017173] thermal_sys: Registered thermal governor 'bang_bang'306second # [ 0.005521] CPU: All CPU(s) started at EL1307test # [ 0.017176] thermal_sys: Registered thermal governor 'step_wise'308second # [ 0.005532] CPU features: detected: 32-bit EL0 Support309test # [ 0.017178] thermal_sys: Registered thermal governor 'user_space'310second # [ 0.005535] CPU features: detected: Data cache clean to the PoU not required for I/D coherence311test # [ 0.017180] thermal_sys: Registered thermal governor 'power_allocator'312second # [ 0.005538] CPU features: detected: Common not Private translations313test # [ 0.017222] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1314second # [ 0.005540] CPU features: detected: CRC32 instructions315test # [ 0.017230] cpuidle: using governor ladder316test # [ 0.017235] cpuidle: using governor menu317second # [ 0.005542] CPU features: detected: Data cache clean to Point of Persistence318test # [ 0.017358] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.319second # [ 0.005544] CPU features: detected: Enhanced Virtualization Traps320test # [ 0.017378] ASID allocator initialised with 32768 entries321second # [ 0.005554] CPU features: detected: RCpc load-acquire (LDAPR)322test # [ 0.018357] Serial: AMBA PL011 UART driver323second # [ 0.005557] CPU features: detected: LSE atomic instructions324second # [ 0.005559] CPU features: detected: Privileged Access Never325test # [ 0.021765] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1326second # [ 0.005561] CPU features: detected: PMUv3327test # [ 0.021900] printk: console [ttyAMA0] enabled328second # [ 0.005562] CPU features: detected: RAS Extension Support329second # [ 0.005566] CPU features: detected: Speculative Store Bypassing Safe (SSBS)330second # [ 0.005586] alternatives: applying system-wide alternatives331second # [ 0.007031] CPU features: detected: Hardware dirty bit management on CPU0332second # [ 0.007227] Memory: 891192K/1048576K available (24512K kernel code, 7094K rwdata, 26568K rodata, 4736K init, 1106K bss, 116068K reserved, 32768K cma-reserved)333second # [ 0.007712] devtmpfs: initialized334second # [ 0.009297] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)335second # [ 0.009321] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).336second # [ 0.009450] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL337second # [ 0.009453] 0 pages in range for non-PLT usage338second # [ 0.009454] 508208 pages in range for PLT usage339second # [ 0.009573] pinctrl core: initialized pinctrl subsystem340second # [ 0.010312] DMI not present or invalid.341second # [ 0.013665] NET: Registered PF_NETLINK/PF_ROUTE protocol family342second # [ 0.016237] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations343second # [ 0.016388] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations344second # [ 0.016536] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations345second # [ 0.016555] audit: initializing netlink subsys (disabled)346second # [ 0.017168] thermal_sys: Registered thermal governor 'fair_share'347second # [ 0.017170] thermal_sys: Registered thermal governor 'bang_bang'348second # [ 0.017174] thermal_sys: Registered thermal governor 'step_wise'349second # [ 0.017176] thermal_sys: Registered thermal governor 'user_space'350second # [ 0.017178] thermal_sys: Registered thermal governor 'power_allocator'351second # [ 0.017213] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1352test # [ 0.097882] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages353second # [ 0.017221] cpuidle: using governor ladder354second # [ 0.017226] cpuidle: using governor menu355test # [ 0.097896] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page356second # [ 0.017346] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.357test # [ 0.097899] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages358second # [ 0.017366] ASID allocator initialised with 32768 entries359test # [ 0.097903] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page360second # [ 0.018021] Serial: AMBA PL011 UART driver361test # [ 0.097905] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages362second # [ 0.021511] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1363test # [ 0.097907] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page364second # [ 0.021691] printk: console [ttyAMA0] enabled365test # [ 0.097909] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages366test # [ 0.097911] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page367test # [ 0.098867] fbcon: Taking over console368test # [ 0.098884] ACPI: Interpreter disabled.369test # [ 0.099545] iommu: Default domain type: Translated370test # [ 0.099549] iommu: DMA domain TLB invalidation policy: strict mode371test # [ 0.099866] SCSI subsystem initialized372test # [ 0.101710] usbcore: registered new interface driver usbfs373test # [ 0.101751] usbcore: registered new interface driver hub374test # [ 0.101767] usbcore: registered new device driver usb375test # [ 0.101969] pps_core: LinuxPPS API ver. 1 registered376test # [ 0.101972] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>377test # [ 0.101977] PTP clock support registered378test # [ 0.102010] EDAC MC: Ver: 3.0.0379test # [ 0.110747] scmi_core: SCMI protocol bus registered380test # [ 0.111093] FPGA manager framework381second # [ 0.093965] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages382second # [ 0.093979] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page383second # [ 0.093982] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages384second # [ 0.093986] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page385second # [ 0.093989] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages386second # [ 0.093990] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page387test # [ 0.114783] vgaarb: loaded388second # [ 0.093993] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages389test # [ 0.115032] clocksource: Switched to clocksource arch_sys_counter390second # [ 0.093994] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page391second # [ 0.095028] fbcon: Taking over console392second # [ 0.095045] ACPI: Interpreter disabled.393second # [ 0.097624] iommu: Default domain type: Translated394second # [ 0.097629] iommu: DMA domain TLB invalidation policy: strict mode395second # [ 0.097946] SCSI subsystem initialized396second # [ 0.103946] usbcore: registered new interface driver usbfs397second # [ 0.103979] usbcore: registered new interface driver hub398second # [ 0.103997] usbcore: registered new device driver usb399second # [ 0.104232] pps_core: LinuxPPS API ver. 1 registered400second # [ 0.104234] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>401second # [ 0.104239] PTP clock support registered402second # [ 0.104271] EDAC MC: Ver: 3.0.0403second # [ 0.104452] scmi_core: SCMI protocol bus registered404second # [ 0.104757] FPGA manager framework405test # [ 0.125253] VFS: Disk quotas dquot_6.6.0406second # [ 0.109564] vgaarb: loaded407test # [ 0.125280] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)408second # [ 0.109844] clocksource: Switched to clocksource arch_sys_counter409test # [ 0.126839] netfs: FS-Cache loaded410test # [ 0.126957] pnp: PnP ACPI: disabled411test # [ 0.130244] NET: Registered PF_INET protocol family412test # [ 0.130430] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)413second # [ 0.123457] VFS: Disk quotas dquot_6.6.0414second # [ 0.123485] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)415second # [ 0.125018] netfs: FS-Cache loaded416second # [ 0.125129] pnp: PnP ACPI: disabled417second # [ 0.128456] NET: Registered PF_INET protocol family418second # [ 0.128629] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)419test # [ 0.154842] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)420test # [ 0.154868] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)421test # [ 0.154881] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)422test # [ 0.154919] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)423test # [ 0.154985] TCP: Hash tables configured (established 8192 bind 8192)424test # [ 0.158081] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)425test # [ 0.158151] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)426test # [ 0.158204] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)427test # [ 0.158322] NET: Registered PF_UNIX/PF_LOCAL protocol family428test # [ 0.158353] NET: Registered PF_XDP protocol family429test # [ 0.158373] PCI: CLS 0 bytes, default 64430test # [ 0.158596] Trying to unpack rootfs image as initramfs...431second # [ 0.153279] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)432second # [ 0.153303] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)433second # [ 0.153315] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)434second # [ 0.153351] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)435second # [ 0.153418] TCP: Hash tables configured (established 8192 bind 8192)436second # [ 0.153516] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)437second # [ 0.153547] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)438second # [ 0.153603] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)439second # [ 0.153724] NET: Registered PF_UNIX/PF_LOCAL protocol family440second # [ 0.153753] NET: Registered PF_XDP protocol family441second # [ 0.153774] PCI: CLS 0 bytes, default 64442test # [ 0.170215] kvm [1]: HYP mode not available443second # [ 0.159695] Trying to unpack rootfs image as initramfs...444second # [ 0.162670] kvm [1]: HYP mode not available445test # [ 0.231470] Initialise system trusted keyrings446test # [ 0.231589] workingset: timestamp_bits=42 max_order=18 bucket_order=0447test # [ 0.231907] squashfs: version 4.0 (2009/01/31) Phillip Lougher448test # [ 0.231939] 9p: Installing v9fs 9p2000 file system support449test # [ 0.250798] Key type asymmetric registered450test # [ 0.250816] Asymmetric key parser 'x509' registered451test # [ 0.250885] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)452second # [ 0.234242] Initialise system trusted keyrings453second # [ 0.234389] workingset: timestamp_bits=42 max_order=18 bucket_order=0454second # [ 0.234709] squashfs: version 4.0 (2009/01/31) Phillip Lougher455second # [ 0.234753] 9p: Installing v9fs 9p2000 file system support456test # [ 0.259089] io scheduler mq-deadline registered457test # [ 0.259100] io scheduler kyber registered458test # [ 0.261484] pl061_gpio 9030000.pl061: PL061 GPIO chip registered459test # [ 0.261811] ledtrig-cpu: registered to indicate activity on CPUs460test # [ 0.262111] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:461test # [ 0.262127] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000462test # [ 0.262147] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000463test # [ 0.262153] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000464test # [ 0.262184] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits465test # [ 0.262204] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]466test # [ 0.262269] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00467test # [ 0.262284] pci_bus 0000:00: root bus resource [bus 00-ff]468test # [ 0.262288] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]469test # [ 0.262290] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]470test # [ 0.262293] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]471second # [ 0.253548] Key type asymmetric registered472test # [ 0.262365] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint473second # [ 0.253566] Asymmetric key parser 'x509' registered474test # [ 0.262808] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint475second # [ 0.253636] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)476test # [ 0.262932] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]477test # [ 0.262941] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]478test # [ 0.262960] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479test # [ 0.262969] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]480second # [ 0.261905] io scheduler mq-deadline registered481test # [ 0.279389] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint482second # [ 0.261916] io scheduler kyber registered483test # [ 0.279523] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]484second # [ 0.264420] pl061_gpio 9030000.pl061: PL061 GPIO chip registered485test # [ 0.279533] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]486second # [ 0.264784] ledtrig-cpu: registered to indicate activity on CPUs487test # [ 0.279552] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]488second # [ 0.265083] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:489test # [ 0.279867] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint490second # [ 0.265098] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000491test # [ 0.279989] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]492test # [ 0.279997] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]493second # [ 0.265112] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000494test # [ 0.280015] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]495second # [ 0.265116] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000496test # [ 0.280319] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint497second # [ 0.265151] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits498test # [ 0.280443] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]499test # [ 0.280451] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]500second # [ 0.265171] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]501test # [ 0.280469] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]502second # [ 0.265236] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00503test # [ 0.280478] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]504second # [ 0.265255] pci_bus 0000:00: root bus resource [bus 00-ff]505second # [ 0.265258] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]506test # [ 0.280792] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint507second # [ 0.265260] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]508test # [ 0.280916] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]509second # [ 0.265263] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]510test # [ 0.280934] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511test # [ 0.281243] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint512second # [ 0.265379] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint513test # [ 0.281369] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]514second # [ 0.265826] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint515test # [ 0.281387] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516test # [ 0.281666] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint517test # [ 0.281786] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]518test # [ 0.281996] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint519test # [ 0.282121] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]520test # [ 0.282139] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521test # [ 0.282437] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint522test # [ 0.282565] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]523test # [ 0.282583] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524test # [ 0.282889] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint525test # [ 0.283014] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]526test # [ 0.298185] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527test # [ 0.298590] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint528test # [ 0.298757] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]529test # [ 0.298766] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]530test # [ 0.298824] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]531second # [ 0.282038] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]532test # [ 0.301754] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint533second # [ 0.282058] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]534test # [ 0.301878] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]535second # [ 0.282077] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536test # [ 0.301887] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]537second # [ 0.282087] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]538test # [ 0.301906] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]539test # [ 0.302354] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned540second # [ 0.282467] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint541second # [ 0.282595] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]542test # [ 0.302361] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned543second # [ 0.282604] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]544test # [ 0.302364] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned545second # [ 0.282623] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546test # [ 0.302397] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned547second # [ 0.282947] pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint548test # [ 0.302428] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned549second # [ 0.283075] pci 0000:00:03.0: BAR 0 [io 0x0000-0x007f]550second # [ 0.283084] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]551test # [ 0.302459] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned552second # [ 0.283102] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]553test # [ 0.302491] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned554second # [ 0.283415] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint555test # [ 0.302520] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned556second # [ 0.283541] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]557test # [ 0.302551] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned558second # [ 0.283551] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]559test # [ 0.302582] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned560second # [ 0.283570] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]561second # [ 0.283579] pci 0000:00:04.0: ROM [mem 0x00000000-0x0003ffff pref]562test # [ 0.302614] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned563second # [ 0.283900] pci 0000:00:05.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint564test # [ 0.302645] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned565second # [ 0.284028] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]566test # [ 0.302704] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned567second # [ 0.284046] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568test # [ 0.302732] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned569second # [ 0.284410] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint570test # [ 0.302747] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned571second # [ 0.284537] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]572test # [ 0.302759] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned573second # [ 0.284555] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]574test # [ 0.302887] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned575test # [ 0.302901] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned576second # [ 0.284826] pci 0000:00:07.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint577second # [ 0.284949] pci 0000:00:07.0: BAR 0 [mem 0x00000000-0x00000fff]578test # [ 0.302914] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned579test # [ 0.302926] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned580second # [ 0.285165] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint581test # [ 0.302939] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned582second # [ 0.285291] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]583test # [ 0.302952] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned584second # [ 0.285309] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585test # [ 0.302964] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned586second # [ 0.285617] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint587test # [ 0.302975] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned588second # [ 0.285745] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]589test # [ 0.302987] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned590second # [ 0.285763] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]591test # [ 0.302999] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned592test # [ 0.303011] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned593test # [ 0.303022] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned594second # [ 0.302012] pci 0000:00:0a.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint595second # [ 0.302152] pci 0000:00:0a.0: BAR 1 [mem 0x00000000-0x00000fff]596second # [ 0.302170] pci 0000:00:0a.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]597second # [ 0.302495] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint598second # [ 0.302658] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]599second # [ 0.302667] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]600second # [ 0.302685] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]601second # [ 0.303080] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint602second # [ 0.303204] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]603second # [ 0.303212] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]604second # [ 0.303230] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]605second # [ 0.303669] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned606second # [ 0.303676] pci 0000:00:04.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned607second # [ 0.303679] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned608second # [ 0.303713] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned609test # [ 0.327107] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned610second # [ 0.303746] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned611test # [ 0.327131] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned612second # [ 0.303777] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned613test # [ 0.327147] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned614test # [ 0.327175] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]615second # [ 0.303808] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned616test # [ 0.327179] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]617second # [ 0.303838] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned618test # [ 0.327185] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]619second # [ 0.303868] pci 0000:00:08.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned620test # [ 0.327758] pci 0000:00:07.0: enabling device (0000 -> 0002)621second # [ 0.303897] pci 0000:00:09.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned622second # [ 0.303927] pci 0000:00:0a.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned623second # [ 0.303957] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned624second # [ 0.304045] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned625second # [ 0.304096] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned626second # [ 0.304108] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned627second # [ 0.304119] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned628second # [ 0.304131] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned629second # [ 0.304143] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned630second # [ 0.304155] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned631second # [ 0.304167] pci 0000:00:07.0: BAR 0 [mem 0x10086000-0x10086fff]: assigned632second # [ 0.304179] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned633second # [ 0.304191] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned634second # [ 0.304203] pci 0000:00:0a.0: BAR 1 [mem 0x10089000-0x10089fff]: assigned635second # [ 0.304215] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned636second # [ 0.304227] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned637second # [ 0.304239] pci 0000:00:03.0: BAR 0 [io 0x1000-0x107f]: assigned638test # [ 0.342443] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)639second # [ 0.304251] pci 0000:00:0b.0: BAR 0 [io 0x1080-0x10bf]: assigned640second # [ 0.304263] pci 0000:00:01.0: BAR 0 [io 0x10c0-0x10df]: assigned641second # [ 0.304277] pci 0000:00:02.0: BAR 0 [io 0x10e0-0x10ff]: assigned642second # [ 0.304291] pci 0000:00:04.0: BAR 0 [io 0x1100-0x111f]: assigned643second # [ 0.304303] pci 0000:00:0c.0: BAR 0 [io 0x1120-0x113f]: assigned644second # [ 0.304320] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]645second # [ 0.304324] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]646second # [ 0.304332] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]647second # [ 0.304873] pci 0000:00:07.0: enabling device (0000 -> 0002)648test # [ 0.344585] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)649test # [ 0.345998] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)650test # [ 0.355238] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)651test # [ 0.357052] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)652test # [ 0.358823] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)653second # [ 0.343993] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)654second # [ 0.345593] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)655test # [ 0.368391] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)656test # [ 0.370212] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)657second # [ 0.355387] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)658test # [ 0.372797] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)659test # [ 0.374294] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)660second # [ 0.357157] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)661test # [ 0.384256] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)662second # [ 0.368163] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)663test # [ 0.388503] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled664second # [ 0.370705] virtio-pci 0000:00:06.0: enabling device (0000 -> 0002)665test # [ 0.389432] msm_serial: driver initialized666test # [ 0.389526] SuperH (H)SCI(F) driver initialized667second # [ 0.372197] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)668test # [ 0.389588] STM32 USART driver initialized669second # [ 0.373634] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)670second # [ 0.383022] virtio-pci 0000:00:0a.0: enabling device (0000 -> 0002)671second # [ 0.384683] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)672second # [ 0.387741] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)673test # [ 0.414629] loop: module loaded674test # [ 0.414755] virtio_blk virtio2: 1/0/0 default/read/poll queues675second # [ 0.399410] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled676test # [ 0.416072] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)677second # [ 0.400441] msm_serial: driver initialized678second # [ 0.400533] SuperH (H)SCI(F) driver initialized679second # [ 0.400574] STM32 USART driver initialized680test # [ 0.423517] megasas: 07.734.00.00-rc1681test # [ 0.423992] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]682test # [ 0.426046] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000683test # [ 0.426069] Intel/Sharp Extended Query Table at 0x0031684test # [ 0.429930] Using buffer write method685test # [ 0.429989] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]686test # [ 0.439698] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000687test # [ 0.439717] Intel/Sharp Extended Query Table at 0x0031688test # [ 0.441521] Using buffer write method689test # [ 0.441535] Concatenating MTD devices:690test # [ 0.441537] (0): "0.flash"691test # [ 0.441539] (1): "0.flash"692test # [ 0.441540] into device "0.flash"693second # [ 0.426322] loop: module loaded694second # [ 0.426465] virtio_blk virtio2: 1/0/0 default/read/poll queues695second # [ 0.427134] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)696second # [ 0.431706] megasas: 07.734.00.00-rc1697second # [ 0.432168] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]698second # [ 0.446781] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000699second # [ 0.446807] Intel/Sharp Extended Query Table at 0x0031700second # [ 0.448954] Using buffer write method701second # [ 0.449025] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]702second # [ 0.452704] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000703second # [ 0.452717] Intel/Sharp Extended Query Table at 0x0031704second # [ 0.462442] Using buffer write method705second # [ 0.462456] Concatenating MTD devices:706second # [ 0.462458] (0): "0.flash"707second # [ 0.462460] (1): "0.flash"708second # [ 0.462462] into device "0.flash"709test # [ 0.678424] Freeing initrd memory: 26920K710test # [ 0.685456] tun: Universal TUN/TAP device driver, 1.6711test # [ 0.688186] thunder_xcv, ver 1.0712test # [ 0.688202] thunder_bgx, ver 1.0713test # [ 0.688211] nicpf, ver 1.0714test # [ 0.688560] e1000: Intel(R) PRO/1000 Network Driver715test # [ 0.688565] e1000: Copyright (c) 1999-2006 Intel Corporation.716test # [ 0.688579] e1000e: Intel(R) PRO/1000 Network Driver717test # [ 0.688584] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.718test # [ 0.688595] igb: Intel(R) Gigabit Ethernet Network Driver719test # [ 0.688597] igb: Copyright (c) 2007-2014 Intel Corporation.720test # [ 0.688607] igbvf: Intel(R) Gigabit Virtual Function Network Driver721test # [ 0.688611] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.722test # [ 0.688678] sky2: driver version 1.30723test # [ 0.689499] usbcore: registered new interface driver usb-storage724test # [ 0.689619] usbcore: registered new interface driver usbserial_generic725test # [ 0.689638] usbserial: USB Serial support registered for generic726test # [ 0.689945] hv_vmbus: registering driver hyperv_keyboard727test # [ 0.690940] ehci-pci 0000:00:07.0: EHCI Host Controller728test # [ 0.690998] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1729test # [ 0.698681] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000730test # [ 0.698911] rtc-pl031 9010000.pl031: registered as rtc0731test # [ 0.698939] rtc-pl031 9010000.pl031: setting system clock to 2026-10-04T20:14:27 UTC (1791144867)732test # [ 0.699177] i2c_dev: i2c /dev entries driver733test # [ 0.702989] sdhci: Secure Digital Host Controller Interface driver734test # [ 0.702996] sdhci: Copyright(c) Pierre Ossman735second # [ 0.687625] Freeing initrd memory: 26948K736second # [ 0.695110] tun: Universal TUN/TAP device driver, 1.6737second # [ 0.697471] thunder_xcv, ver 1.0738second # [ 0.697488] thunder_bgx, ver 1.0739second # [ 0.697500] nicpf, ver 1.0740second # [ 0.697834] e1000: Intel(R) PRO/1000 Network Driver741second # [ 0.697838] e1000: Copyright (c) 1999-2006 Intel Corporation.742second # [ 0.699909] e1000e: Intel(R) PRO/1000 Network Driver743second # [ 0.699917] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.744second # [ 0.699930] igb: Intel(R) Gigabit Ethernet Network Driver745second # [ 0.699932] igb: Copyright (c) 2007-2014 Intel Corporation.746second # [ 0.699942] igbvf: Intel(R) Gigabit Virtual Function Network Driver747second # [ 0.699948] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.748second # [ 0.700036] sky2: driver version 1.30749second # [ 0.700957] usbcore: registered new interface driver usb-storage750second # [ 0.701075] usbcore: registered new interface driver usbserial_generic751second # [ 0.701093] usbserial: USB Serial support registered for generic752second # [ 0.701429] hv_vmbus: registering driver hyperv_keyboard753second # [ 0.707411] ehci-pci 0000:00:07.0: EHCI Host Controller754second # [ 0.707443] ehci-pci 0000:00:07.0: new USB bus registered, assigned bus number 1755second # [ 0.707660] ehci-pci 0000:00:07.0: irq 16, io mem 0x10086000756second # [ 0.707904] rtc-pl031 9010000.pl031: registered as rtc0757second # [ 0.707934] rtc-pl031 9010000.pl031: setting system clock to 2026-10-04T20:14:28 UTC (1791144868)758second # [ 0.708158] i2c_dev: i2c /dev entries driver759second # [ 0.709832] sdhci: Secure Digital Host Controller Interface driver760second # [ 0.709837] sdhci: Copyright(c) Pierre Ossman761second # [ 0.713746] Synopsys Designware Multimedia Card Interface Driver762second # [ 0.714462] sdhci-pltfm: SDHCI platform and OF driver helper763test # [ 0.704064] Synopsys Designware Multimedia Card Interface Driver764second # [ 0.715493] hid: raw HID events driver (C) Jiri Kosina765test # [ 0.704249] sdhci-pltfm: SDHCI platform and OF driver helper766test # [ 0.705222] hid: raw HID events driver (C) Jiri Kosina767second # [ 0.715622] usbcore: registered new interface driver usbhid768second # [ 0.715625] usbhid: USB HID core driver769test # [ 0.705362] usbcore: registered new interface driver usbhid770test # [ 0.705365] usbhid: USB HID core driver771test # [ 0.743767] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00772second # [ 0.726106] ehci-pci 0000:00:07.0: USB 2.0 started, EHCI 1.00773test # [ 0.744196] hub 1-0:1.0: USB hub found774second # [ 0.726506] hub 1-0:1.0: USB hub found775test # [ 0.744219] hub 1-0:1.0: 6 ports detected776second # [ 0.726533] hub 1-0:1.0: 6 ports detected777test # [ 0.744518] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available778second # [ 0.726834] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available779test # [ 0.745406] drop_monitor: Initializing network drop monitor service780second # [ 0.727750] drop_monitor: Initializing network drop monitor service781test # [ 0.745534] NET: Registered PF_INET6 protocol family782second # [ 0.727871] NET: Registered PF_INET6 protocol family783test # [ 0.746239] Segment Routing with IPv6784second # [ 0.728556] Segment Routing with IPv6785test # [ 0.746266] In-situ OAM (IOAM) with IPv6786second # [ 0.728576] In-situ OAM (IOAM) with IPv6787test # [ 0.746314] NET: Registered PF_PACKET protocol family788second # [ 0.728630] NET: Registered PF_PACKET protocol family789test # [ 0.746372] 9pnet: Installing 9P2000 support790second # [ 0.728699] 9pnet: Installing 9P2000 support791test # [ 0.746405] Key type dns_resolver registered792second # [ 0.728731] Key type dns_resolver registered793test # [ 0.753548] registered taskstats version 1794second # [ 0.735950] registered taskstats version 1795test # [ 0.753726] Loading compiled-in X.509 certificates796second # [ 0.736109] Loading compiled-in X.509 certificates797second # [ 0.741638] Demotion targets for Node 0: null798second # [ 0.741719] Key type .fscrypt registered799second # [ 0.741724] Key type fscrypt-provisioning registered800second # [ 0.741857] ima: No TPM chip found, activating TPM-bypass!801second # [ 0.741868] ima: Allocated hash algorithm: sha1802second # [ 0.741928] ima: No architecture policies found803test # [ 0.759070] Demotion targets for Node 0: null804second # [ 0.744698] input: gpio-keys as /devices/platform/gpio-keys/input/input0805test # [ 0.759163] Key type .fscrypt registered806test # [ 0.759169] Key type fscrypt-provisioning registered807test # [ 0.759273] ima: No TPM chip found, activating TPM-bypass!808test # [ 0.759283] ima: Allocated hash algorithm: sha1809test # [ 0.759344] ima: No architecture policies found810test # [ 0.759891] input: gpio-keys as /devices/platform/gpio-keys/input/input0811test # [ 0.772647] clk: Disabling unused clocks812second # [ 0.755427] clk: Disabling unused clocks813test # [ 0.772660] PM: genpd: Disabling unused power domains814second # [ 0.755441] PM: genpd: Disabling unused power domains815test # [ 0.775936] Freeing unused kernel memory: 4736K816second # [ 0.758765] Freeing unused kernel memory: 4736K817test # [ 0.776039] Run /init as init process818second # [ 0.758870] Run /init as init process819test # [ 0.798181] systemd[1]: Successfully made /usr/ read-only.820second # [ 0.781035] systemd[1]: Successfully made /usr/ read-only.821test # [ 0.995106] usb 1-1: new high-speed USB device number 2 using ehci-pci822second # [ 0.977905] usb 1-1: new high-speed USB device number 2 using ehci-pci823second # [ 1.115247] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)824test # [ 1.132559] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)825second # [ 1.115292] systemd[1]: Detected virtualization qemu.826test # [ 1.132591] systemd[1]: Detected virtualization qemu.827second # [ 1.115345] systemd[1]: Detected architecture arm64.828test # [ 1.132641] systemd[1]: Detected architecture arm64.829second # [ 1.115356] systemd[1]: Running in initrd.830test # [ 1.132651] systemd[1]: Running in initrd.831second # [ 1.116170] systemd[1]: Initializing machine ID from random generator.832test # [ 1.133508] systemd[1]: Initializing machine ID from random generator.833second # [ 1.116499] systemd[1]: Hostname set to <second>.834test # [ 1.133895] systemd[1]: Hostname set to <test>.835test # [ 1.155789] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1836second # [ 1.138611] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:07.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1837test # [ 1.243234] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0838second # [ 1.230078] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:07.0-1/input0839test # [ 1.278034] systemd[1]: bpf-restrict-fs: LSM BPF program attached840second # [ 1.262231] systemd[1]: bpf-restrict-fs: LSM BPF program attached841test # [ 1.329983] systemd[1]: Queued start job for default target Initrd Default Target.842test # [ 1.333095] systemd[1]: Created slice Slice /system/modprobe.843test # [ 1.333229] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.844test # [ 1.333259] systemd[1]: Expecting device /dev/disk/by-label/nixos...845test # [ 1.333279] systemd[1]: Reached target Path Units.846test # [ 1.333292] systemd[1]: Reached target Slice Units.847test # [ 1.333301] systemd[1]: Reached target Swaps.848test # [ 1.333312] systemd[1]: Reached target Timer Units.849test # [ 1.333437] systemd[1]: Listening on D-Bus System Message Bus Socket.850test # [ 1.333548] systemd[1]: Listening on Journal Socket (/dev/log).851test # [ 1.333702] systemd[1]: Listening on Journal Sockets.852second # [ 1.319007] systemd[1]: Queued start job for default target Initrd Default Target.853test # [ 1.333773] systemd[1]: Listening on udev Control Socket.854test # [ 1.333818] systemd[1]: Listening on udev Kernel Socket.855test # [ 1.333833] systemd[1]: Reached target Socket Units.856test # [ 1.334878] systemd[1]: Starting Create List of Static Device Nodes...857test # [ 1.334927] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs858test # [ 1.343854] systemd[1]: Mounting Kernel Configuration File System...859second # [ 1.327308] systemd[1]: Created slice Slice /system/modprobe.860second # [ 1.327455] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.861second # [ 1.327486] systemd[1]: Expecting device /dev/disk/by-label/nixos...862second # [ 1.327508] systemd[1]: Reached target Path Units.863second # [ 1.327521] systemd[1]: Reached target Slice Units.864second # [ 1.327532] systemd[1]: Reached target Swaps.865second # [ 1.327543] systemd[1]: Reached target Timer Units.866second # [ 1.327676] systemd[1]: Listening on D-Bus System Message Bus Socket.867second # [ 1.327793] systemd[1]: Listening on Journal Socket (/dev/log).868second # [ 1.327910] systemd[1]: Listening on Journal Sockets.869second # [ 1.327981] systemd[1]: Listening on udev Control Socket.870second # [ 1.328024] systemd[1]: Listening on udev Kernel Socket.871second # [ 1.328038] systemd[1]: Reached target Socket Units.872second # [ 1.329095] systemd[1]: Starting Create List of Static Device Nodes...873second # [ 1.329144] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs874test # [ 1.351265] systemd[1]: Starting Journal Service...875test # [ 1.363083] usb 1-2: new high-speed USB device number 3 using ehci-pci876second # [ 1.345985] systemd[1]: Mounting Kernel Configuration File System...877second # [ 1.349899] usb 1-2: new high-speed USB device number 3 using ehci-pci878test # [ 1.368435] systemd[1]: Starting Load Kernel Modules...879test # [ 1.368507] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os880second # [ 1.353998] systemd[1]: Starting Journal Service...881second # [ 1.354879] systemd[1]: Starting Load Kernel Modules...882second # [ 1.354939] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os883second # [ 1.363461] systemd[1]: Starting Coldplug All udev Devices...884test # [ 1.379216] systemd[1]: Starting Coldplug All udev Devices...885test # [ 1.386761] systemd-journald[71]: Collecting audit messages is disabled.886test # [ 1.392101] systemd[1]: Finished Create List of Static Device Nodes.887test # [ 1.392764] systemd[1]: Mounted Kernel Configuration File System.888second # [ 1.374086] systemd[1]: Finished Create List of Static Device Nodes.889test # [ 1.403473] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...890second # [ 1.415846] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...891second # [ 1.417300] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.892test # [ 1.439543] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.893test # [ 1.440567] systemd[1]: Starting Create Static Device Nodes in /dev...894second # [ 1.426386] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.895second # [ 1.426758] systemd[1]: Starting Create Static Device Nodes in /dev...896second # [ 1.426928] systemd[1]: Mounted Kernel Configuration File System.897second # [ 1.431471] systemd-journald[71]: Collecting audit messages is disabled.898test # [ 1.452322] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.899second # [ 1.441942] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev900test # [ 1.462946] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev901second # [ 1.451383] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0902second # [ 1.451587] [drm] features: -virgl +edid -resource_blob -host_visible903second # [ 1.451589] [drm] features: -context_init904second # [ 1.452058] [drm] number of scanouts: 1905second # [ 1.452067] [drm] number of cap sets: 0906test # [ 1.469353] [drm] pci: virtio-gpu-pci detected at 0000:00:05.0907test # [ 1.469579] [drm] features: -virgl +edid -resource_blob -host_visible908test # [ 1.469581] [drm] features: -context_init909test # [ 1.470041] [drm] number of scanouts: 1910test # [ 1.470049] [drm] number of cap sets: 0911test # [ 1.476520] systemd-modules-load[73]: Inserted module 'dm_mod'912test # [ 1.478808] systemd-modules-load[73]: Module 'virtio_balloon' is built in913test # [ 1.479572] systemd-modules-load[73]: Module 'virtio_console' is built in[ 1.484323] systemd[1]: Started Journal Service.914test # 915second # [ 1.470488] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic916second # [ 1.470500] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0917test # [ 1.486236] systemd[1]: Finished Create Static Device Nodes in /dev.918test # [ 1.487124] systemd[1]: Reached target Preparation for Local File Systems.919test # [ 1.494970] virtio-pci 0000:00:05.0: [drm] Registered 1 planes with drm panic920test # [ 1.494981] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:05.0 on minor 0921second # [ 1.482326] systemd[1]: Finished Create Static Device Nodes in /dev.922second # [ 1.482464] systemd[1]: Reached target Preparation for Local File Systems.923second # [ 1.482486] systemd[1]: Reached target Local File Systems.924second # [ 1.483590] systemd[1]: Starting Rule-based Manager for Device Events and Files...925test # [ 1.499510] systemd[1]: Reached target Local File Systems.926test # [ 1.500428] systemd[1]: Starting Create System Files and Directories...927second # [ 1.491548] Console: switching to colour frame buffer device 160x50928test # [ 1.515431] Console: switching to colour frame buffer device 160x50929test # [ 1.521652] systemd[1]: Starting Rule-based Manager for Device Events and Files...930second # [ 1.510120] 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/input2931second # [ 1.510211] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0932second # [ 1.510973] systemd-modules-load[72]: Inserted module 'dm_mod'933second # [ 1.511747] systemd-modules-load[72]: Module 'virtio_balloon' is built in[ 1.518579] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device934test # [ 1.536985] 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/input2935second # [ 1.518804] systemd[1]: Started Journal Service.936test # [ 1.537067] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:07.0-2/input0937test # [ 1.541492] virtio-pci 0000:00:05.0: [drm] fb0: virtio_gpudrmfb frame buffer device938second # 939test # [ 1.544144] systemd[1]: Finished Create System Files and Directories.940second # [ 1.524608] systemd-modules-load[72]: Module 'virtio_console' is built in941test # [ 1.544924] systemd-modules-load[73]: Inserted module 'virtio_gpu'942second # [ 1.525333] systemd-modules-load[72]: Inserted module 'virtio_gpu'943test # [ 1.545468] systemd-modules-load[73]: Module 'virtio_rng' is built in944test # [ 1.546023] systemd[1]: Finished Load Kernel Modules.945second # [ 1.526024] systemd-modules-load[72]: Module 'virtio_rng' is built in946test # [ 1.546578] systemd[1]: Starting Apply Kernel Variables...947second # [ 1.526775] systemd[1]: Starting Create System Files and Directories...948second # [ 1.536054] systemd[1]: Finished Load Kernel Modules.949test # [ 1.579790] systemd[1]: Finished Apply Kernel Variables.950second # [ 1.562473] systemd[1]: Starting Apply Kernel Variables...951second # [ 1.563810] systemd[1]: Finished Create System Files and Directories.952test # [ 1.586426] systemd-udevd[84]: Using default interface naming scheme 'v261'.953second # [ 1.571797] systemd-udevd[79]: Using default interface naming scheme 'v261'.954second # [ 1.580272] systemd[1]: Finished Apply Kernel Variables.955test # [ 1.608081] systemd[1]: Started Rule-based Manager for Device Events and Files.956second # [ 1.593721] systemd[1]: Started Rule-based Manager for Device Events and Files.957test # [ 1.660082] systemd[1]: Starting Virtual Console Setup...958second # [ 1.644083] systemd[1]: Starting Virtual Console Setup...959test # [ 1.700757] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.960test # [ 1.703116] systemd[1]: Finished Virtual Console Setup.961second # [ 1.689776] systemd-vconsole-setup[101]: Configuration of first virtual console was skipped, ignoring remaining ones.962second # [ 1.692225] systemd[1]: Finished Virtual Console Setup.963test # [ 1.912596] systemd[1]: Finished Coldplug All udev Devices.964test # [ 1.913202] systemd[1]: Reached target System Initialization.965test # [ 1.913735] systemd[1]: Reached target Basic System.966second # [ 1.913321] systemd[1]: Finished Coldplug All udev Devices.967second # [ 1.913973] systemd[1]: Reached target System Initialization.968second # [ 1.914943] systemd[1]: Reached target Basic System.969test # [ 2.006281] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.970second # [ 2.003649] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.971test # [ 2.025683] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.972second # [ 2.029247] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.973second # [ 2.083398] systemd[1]: Found device /dev/disk/by-label/nixos.974test # [ 2.103598] systemd[1]: Found device /dev/disk/by-label/nixos.975test # [ 2.104650] systemd[1]: Reached target Initrd Root Device.976test # [ 2.107095] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...977second # [ 2.088757] systemd[1]: Reached target Initrd Root Device.978second # [ 2.091850] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...979test # [ 2.140622] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks980second # [ 2.139298] systemd-fsck[110]: nixos: clean, 12/65536 files, 13019/262144 blocks981test # [ 2.267025] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.982second # [ 2.247620] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.983second # [ 2.361649] systemd[1]: Mounting /sysroot...984test # [ 2.385524] systemd[1]: Mounting /sysroot...985second # [ 2.396672] EXT4-fs (vda): mounted filesystem d4728724-7795-49c7-8616-25a8305a52be r/w with ordered data mode. Quota mode: none.986second # [ 2.392495] systemd[1]: Mounted /sysroot.987second # [ 2.393775] systemd[1]: Reached target Initrd Root File System.988second # [ 2.394639] systemd[1]: Starting Mountpoints Configured in the Real Root...989test # [ 2.415909] systemd[1]: Mounted /sysroot.990test # [ 2.420934] EXT4-fs (vda): mounted filesystem f57741b7-2a0a-45db-96ac-6d3265aa5282 r/w with ordered data mode. Quota mode: none.991test # [ 2.420103] systemd[1]: Reached target Initrd Root File System.992test # [ 2.426696] systemd[1]: Starting Mountpoints Configured in the Real Root...993second # [ 2.414226] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.994second # [ 2.419605] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...995second # [ 2.423036] systemd[1]: Reloading...996test # [ 2.442686] systemd-sysroot-fstab-check[118]: /sysroot should be mounted in the initrd, will request daemon-reload.997test # [ 2.448214] systemd[1]: Reload requested from client PID 118 ('systemd-sysroot') (unit initrd-parse-etc.service)...998test # [ 2.451494] systemd[1]: Reloading...999second # [ 2.594647] systemd[1]: Reloading finished in 173 ms.1000test # [ 2.621342] systemd[1]: Reloading finished in 171 ms.1001test # [ 2.640301] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1002test # [ 2.642828] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1003second # [ 2.622733] systemd-sysroot-fstab-check[118]: Requesting initrd-fs.target/start/replace...1004test # [ 2.644962] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1005second # [ 2.625039] systemd-sysroot-fstab-check[118]: Requesting swap.target/start/replace...1006test # [ 2.646612] systemd[1]: Finished Mountpoints Configured in the Real Root.1007test # [ 2.647702] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1008second # [ 2.629796] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1009second # [ 2.631216] systemd[1]: Finished Mountpoints Configured in the Real Root.1010second # [ 2.632682] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1011test # [ 2.824569] fuse: init (API version 7.45)1012second # [ 2.835642] fuse: init (API version 7.45)1013test # [ 2.865242] virtiofs virtio6: discovered new tag: nix-store1014test # [ 2.865774] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1015test # [ 2.872665] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1016test # [ 2.876573] (udev-worker)[95]: 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 directory1017test # [ 2.893445] virtiofs virtio7: discovered new tag: shared1018test # [ 2.893979] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1019second # [ 2.878392] virtiofs virtio6: discovered new tag: nix-store1020second # [ 2.878921] virtiofs virtio6: virtio_fs_setup_dax: No cache capability1021test # [ 2.893956] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1022test # [ 2.895650] systemd[1]: Stopped Virtual Console Setup.1023test # [ 2.900627] systemd[1]: Stopping Virtual Console Setup...1024test # [ 2.901159] systemd[1]: Starting Virtual Console Setup...1025test # [ 2.908982] virtiofs virtio8: discovered new tag: xchg1026test # [ 2.909498] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1027second # [ 2.886536] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/8w0qabyr98chcmr4y9hh6h6l26albldx-systemd-261.3/lib/udev/mtd_probe": No such file or directory1028second # [ 2.890451] (udev-worker)[95]: mtd0ro: /etc/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1029second # [ 2.907657] virtiofs virtio7: discovered new tag: shared1030second # [ 2.908163] virtiofs virtio7: virtio_fs_setup_dax: No cache capability1031test # [ 2.927101] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1032second # [ 2.915277] virtiofs virtio8: discovered new tag: xchg1033test # [ 2.928849] systemd[1]: Finished Virtual Console Setup.1034second # [ 2.915896] virtiofs virtio8: virtio_fs_setup_dax: No cache capability1035second # [ 2.910129] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1036second # [ 2.912422] systemd[1]: Stopped Virtual Console Setup.1037second # [ 2.916308] systemd[1]: Stopping Virtual Console Setup...1038second # [ 2.917397] systemd[1]: Starting Virtual Console Setup...1039second # [ 2.939147] systemd-vconsole-setup[137]: Configuration of first virtual console was skipped, ignoring remaining ones.1040second # [ 2.941283] systemd[1]: Finished Virtual Console Setup.1041second # [ 3.364439] systemd[1]: Mounting /sysroot/nix/.ro-store...1042test # [ 3.389919] systemd[1]: Mounting /sysroot/nix/.ro-store...1043second # [ 3.376320] systemd[1]: Mounting /sysroot/nix/.rw-store...1044second # [ 3.379671] systemd[1]: Mounting /sysroot/run...1045test # [ 3.401700] systemd[1]: Mounting /sysroot/nix/.rw-store...1046test # [ 3.404214] systemd[1]: Mounting /sysroot/run...1047second # [ 3.392238] systemd[1]: Mounting /sysroot/tmp/shared...1048test # [ 3.414392] systemd[1]: Mounting /sysroot/tmp/shared...1049test # [ 3.436453] systemd[1]: Mounting /sysroot/tmp/xchg...1050second # [ 3.416325] systemd[1]: Mounting /sysroot/tmp/xchg...1051second # [ 3.439653] systemd[1]: Mounted /sysroot/nix/.rw-store.1052test # [ 3.461423] systemd[1]: Mounted /sysroot/nix/.ro-store.1053test # [ 3.465289] systemd[1]: Mounted /sysroot/nix/.rw-store.1054test # [ 3.466931] systemd[1]: Mounted /sysroot/run.1055test # [ 3.468163] systemd[1]: Mounted /sysroot/tmp/shared.1056second # [ 3.456264] systemd[1]: Starting rw-sysroot-nix-store.service...1057test # [ 3.476640] systemd[1]: Starting rw-sysroot-nix-store.service...1058test # [ 3.478639] systemd[1]: Mounted /sysroot/tmp/xchg.1059second # [ 3.462410] systemd[1]: Mounted /sysroot/nix/.ro-store.1060second # [ 3.463422] systemd[1]: Mounted /sysroot/run.1061second # [ 3.464657] systemd[1]: Mounted /sysroot/tmp/shared.1062second # [ 3.467997] systemd[1]: Mounted /sysroot/tmp/xchg.1063test # [ 3.494635] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1064test # [ 3.495758] systemd[1]: Finished rw-sysroot-nix-store.service.1065second # [ 3.478423] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1066second # [ 3.479519] systemd[1]: Finished rw-sysroot-nix-store.service.1067second # [ 4.363162] systemd[1]: Mounting /sysroot/nix/store...1068test # [ 4.387169] systemd[1]: Mounting /sysroot/nix/store...1069second # [ 4.411159] systemd[1]: Mounted /sysroot/nix/store.1070second # [ 4.414122] systemd[1]: Reached target Initrd File Systems.1071test # [ 4.434408] systemd[1]: Mounted /sysroot/nix/store.1072second # [ 4.416207] systemd[1]: Starting Find NixOS closure...1073test # [ 4.437133] systemd[1]: Reached target Initrd File Systems.1074test # [ 4.440209] systemd[1]: Starting Find NixOS closure...1075second # [ 4.421522] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1076test # [ 4.448230] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1077second # [ 4.458543] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1078second # [ 4.459927] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1079test # [ 4.484310] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1080test # [ 4.488455] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1081second # [ 4.475167] systemd[1]: Finished Find NixOS closure.1082second # [ 4.477447] systemd[1]: Reached target Initrd Default Target.1083second # [ 4.480223] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1084test # [ 4.504149] systemd[1]: Finished Find NixOS closure.1085test # [ 4.506580] systemd[1]: Reached target Initrd Default Target.1086test # [ 4.512356] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1087second # [ 4.508669] systemd[1]: Stopped target Initrd Default Target.1088second # [ 4.510107] systemd[1]: Stopped target Basic System.1089second # [ 4.511420] systemd[1]: Stopped target Initrd Root Device.1090second # [ 4.516078] systemd[1]: Stopped target Path Units.1091second # [ 4.517072] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1092second # [ 4.518180] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1093second # [ 4.519911] systemd[1]: Stopped target Slice Units.1094test # [ 4.540982] systemd[1]: initrd-cleanup.service: Deactivated successfully.1095test # [ 4.542557] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1096second # [ 4.524218] systemd[1]: Stopped target Socket Units.1097second # [ 4.524984] systemd[1]: Stopped target System Initialization.1098second # [ 4.525813] systemd[1]: Stopped target Swaps.1099test # [ 4.546107] systemd[1]: Stopped target Initrd Default Target.1100second # [ 4.526509] systemd[1]: Stopped target Timer Units.1101test # [ 4.547245] systemd[1]: Stopped target Basic System.1102second # [ 4.527530] systemd[1]: dbus.socket: Deactivated successfully.1103test # [ 4.548379] systemd[1]: Stopped target Initrd Root Device.1104test # [ 4.551035] systemd[1]: Stopped target Path Units.1105test # [ 4.551759] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1106second # [ 4.533327] systemd[1]: Closed D-Bus System Message Bus Socket.1107test # [ 4.554804] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1108second # [ 4.535881] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1109test # [ 4.560892] systemd[1]: Stopped target Slice Units.1110second # [ 4.541256] systemd[1]: Stopped Find NixOS closure.1111second # [ 4.541954] systemd[1]: Starting rw-sysroot-nix-store.service...1112test # [ 4.562874] systemd[1]: Stopped target Socket Units.1113second # [ 4.542892] systemd[1]: systemd-sysctl.service: Deactivated successfully.1114second # [ 4.543740] systemd[1]: Stopped Apply Kernel Variables.1115test # [ 4.564519] systemd[1]: Stopped target System Initialization.1116test # [ 4.565719] systemd[1]: Stopped target Swaps.1117test # [ 4.568146] systemd[1]: Stopped target Timer Units.1118test # [ 4.568788] systemd[1]: dbus.socket: Deactivated successfully.1119test # [ 4.569475] systemd[1]: Closed D-Bus System Message Bus Socket.1120test # [ 4.570169] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1121second # [ 4.550050] systemd[1]: systemd-modules-load.service: Deactivated successfully.1122test # [ 4.571170] systemd[1]: Stopped Find NixOS closure.1123second # [ 4.551693] systemd[1]: Stopped Load Kernel Modules.1124test # [ 4.571725] systemd[1]: Starting rw-sysroot-nix-store.service...1125test # [ 4.574143] systemd[1]: systemd-sysctl.service: Deactivated successfully.1126test # [ 4.575113] systemd[1]: Stopped Apply Kernel Variables.1127test # [ 4.575750] systemd[1]: systemd-modules-load.service: Deactivated successfully.1128test # [ 4.576784] systemd[1]: Stopped Load Kernel Modules.1129test # [ 4.577391] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1130test # [ 4.578294] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1131test # [ 4.579298] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1132second # [ 4.559804] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1133second # [ 4.563491] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1134second # [ 4.565283] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1135second # [ 4.567778] systemd[1]: Stopped Create System Files and Directories.1136test # [ 4.590629] systemd[1]: Stopped Create System Files and Directories.1137test # [ 4.591318] systemd[1]: Stopped target Local File Systems.1138second # [ 4.572292] systemd[1]: Stopped target Local File Systems.1139second # [ 4.572867] systemd[1]: Stopped target Preparation for Local File Systems.1140second # [ 4.573532] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1141second # [ 4.574250] systemd[1]: Stopped Coldplug All udev Devices.1142second # [ 4.574902] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1143second # [ 4.575638] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1144test # [ 4.596117] systemd[1]: Stopped target Preparation for Local File Systems.1145test # [ 4.596791] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1146test # [ 4.597523] systemd[1]: Stopped Coldplug All udev Devices.1147second # [ 4.577660] systemd[1]: Stopped Virtual Console Setup.1148test # [ 4.598045] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1149second # [ 4.578185] systemd[1]: initrd-cleanup.service: Deactivated successfully.1150test # [ 4.598863] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1151test # [ 4.599500] systemd[1]: Stopped Virtual Console Setup.1152second # [ 4.579375] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1153second # [ 4.580051] systemd[1]: systemd-udevd.service: Deactivated successfully.1154second # [ 4.580702] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1155second # [ 4.581406] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1156test # [ 4.599970] systemd[1]: systemd-udevd.service: Deactivated successfully.1157second # [ 4.582088] systemd[1]: Finished rw-sysroot-nix-store.service.1158second # [ 4.582797] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1159second # [ 4.583477] systemd[1]: Closed udev Control Socket.1160second # [ 4.583949] systemd[1]: Starting Cleanup udev Database...1161test # [ 4.604734] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1162second # [ 4.587530] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1163test # [ 4.608129] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1164test # [ 4.608773] systemd[1]: Closed udev Control Socket.1165test # [ 4.609220] systemd[1]: Starting Cleanup udev Database...1166test # [ 4.609699] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1167test # [ 4.610349] systemd[1]: Stopped Create Static Device Nodes in /dev.1168second # [ 4.592164] systemd[1]: Stopped Create Static Device Nodes in /dev.1169test # [ 4.612136] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1170second # [ 4.592924] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1171second # [ 4.593670] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1172second # [ 4.594314] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1173second # [ 4.595119] systemd[1]: Stopped Create List of Static Device Nodes.1174test # [ 4.616648] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1175test # [ 4.617302] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1176test # [ 4.617896] systemd[1]: Stopped Create List of Static Device Nodes.1177test # [ 4.618428] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1178test # [ 4.619041] systemd[1]: Finished rw-sysroot-nix-store.service.1179test # [ 4.624752] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1180test # [ 4.625461] systemd[1]: Finished Cleanup udev Database.1181test # [ 4.628677] systemd[1]: Reached target Switch Root.1182second # [ 4.608826] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1183test # [ 4.629166] systemd[1]: Starting NixOS Activation...1184second # [ 4.609562] systemd[1]: Finished Cleanup udev Database.1185second # [ 4.610050] systemd[1]: Reached target Switch Root.1186second # [ 4.612808] systemd[1]: Starting NixOS Activation...1187test # [ 4.701160] initrd-nixos-activation-start[172]: booting system configuration /nix/store/5xlw8xlb2i17jsicszzylcm6hq4s3znn-nixos-system-test-test1188second # [ 4.686223] initrd-nixos-activation-start[172]: booting system configuration /nix/store/255cn07si1s5z1lxy17xrnr4i6k6zl3v-nixos-system-second-test1189test # [ 4.728069] initrd-nixos-activation-start[172]: running activation script...1190second # [ 4.713197] initrd-nixos-activation-start[172]: running activation script...1191test # [ 4.935167] initrd-nixos-activation-start[195]: setting up /etc...1192second # [ 4.933520] initrd-nixos-activation-start[195]: setting up /etc...1193test # [ 5.027954] initrd-nixos-activation-start[195]: setting up age secrets...1194second # [ 5.026178] initrd-nixos-activation-start[195]: setting up age secrets...1195second # [ 5.112938] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1196second # [ 5.114727] systemd[1]: Finished NixOS Activation.1197second # [ 5.115450] systemd[1]: Starting Switch Root...1198test # [ 5.146325] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1199test # [ 5.148112] systemd[1]: Finished NixOS Activation.1200second # [ 5.128204] systemd[1]: Switching root.1201test # [ 5.148907] systemd[1]: Starting Switch Root...1202test # [ 5.167988] systemd[1]: Switching root.1203second # [ 5.298021] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1204test # [ 5.343367] systemd-journald[71]: Received SIGTERM from PID 1 (systemd).1205test # [ 10.071076] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1206test # [ 10.071117] systemd[1]: Detected virtualization qemu.1207test # [ 10.071156] systemd[1]: Detected architecture arm64.1208test # [ 10.071229] systemd[1]: Detected first boot.1209test # [ 10.073228] systemd[1]: Initializing machine ID from random generator.1210second # [ 10.072751] systemd[1]: systemd 261.3 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1211second # [ 10.072849] systemd[1]: Detected virtualization qemu.1212second # [ 10.072910] systemd[1]: Detected architecture arm64.1213second # [ 10.073033] systemd[1]: Detected first boot.1214second # [ 10.086698] systemd[1]: Initializing machine ID from random generator.1215test # [ 10.164648] systemd[1]: bpf-restrict-fs: LSM BPF program attached1216second # [ 10.226041] systemd[1]: bpf-restrict-fs: LSM BPF program attached1217test # [ 10.246563] systemd[1]: Applying preset policy.1218second # [ 10.314918] systemd[1]: Applying preset policy.1219test # [ 10.403796] systemd[1]: Populated /etc with preset unit settings.1220second # [ 10.464681] systemd[1]: Populated /etc with preset unit settings.1221test # [ 10.625576] systemd[1]: initrd-switch-root.service: Deactivated successfully.1222test # [ 10.625856] systemd[1]: Stopped initrd-switch-root.service.1223test # [ 10.626870] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1224test # [ 10.630602] systemd[1]: Created slice Slice /system/getty.1225test # [ 10.632639] systemd[1]: Created slice User and Session Slice.1226test # [ 10.633226] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1227test # [ 10.633832] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1228test # [ 10.634388] systemd[1]: Expecting device /dev/hvc0...1229test # [ 10.634932] systemd[1]: Expecting device /dev/ttyAMA0...1230test # [ 10.638578] systemd[1]: Reached target Local Encrypted Volumes.1231test # [ 10.640110] systemd[1]: Stopped target initrd-fs.target.1232test # [ 10.640596] systemd[1]: Stopped target initrd-root-fs.target.1233test # [ 10.641156] systemd[1]: Stopped target initrd-switch-root.target.1234test # [ 10.641729] systemd[1]: Reached target Virtual Machines and Containers.1235test # [ 10.642290] systemd[1]: Reached target Path Units.1236test # [ 10.642814] systemd[1]: Reached target Remote File Systems.1237test # [ 10.646487] systemd[1]: Reached target Slice Units.1238test # [ 10.646955] systemd[1]: Reached target Swaps.1239test # [ 10.650345] systemd[1]: Listening on Query the User Interactively for a Password.1240test # [ 10.653235] systemd[1]: Listening on Process Core Dump Socket.1241test # [ 10.654815] systemd[1]: Listening on Credential Encryption/Decryption.1242test # [ 10.657662] systemd[1]: Listening on Factory Reset Management.1243test # [ 10.658202] systemd[1]: Listening on Hostname Service Socket.1244test # [ 10.662236] systemd[1]: Starting Journal Log Access Socket...1245test # [ 10.663957] systemd[1]: Listening on Journal Audit Socket.1246test # [ 10.665951] systemd[1]: Listening on Console Output Muting Service Socket.1247test # [ 10.666579] systemd[1]: Listening on Network Management Metrics Varlink Socket.1248test # [ 10.669608] systemd[1]: Listening on Network Management Varlink Socket.1249test # [ 10.670853] systemd[1]: Listening on Network Management Netlink Socket.1250test # [ 10.672489] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1251test # [ 10.672981] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1252test # [ 10.673498] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1253test # [ 10.679182] systemd[1]: Listening on Disk Repartitioning Service Socket.1254test # [ 10.679699] systemd[1]: Listening on Resolve Monitor Varlink Socket.1255test # [ 10.680255] systemd[1]: Listening on Resolve Service Varlink Socket.1256test # [ 10.680784] systemd[1]: Listening on udev Control Socket.1257test # [ 10.681355] systemd[1]: Listening on udev Varlink Socket.1258test # [ 10.685981] systemd[1]: Mounting Huge Pages File System...1259test # [ 10.688363] systemd[1]: Mounting POSIX Message Queue File System...1260test # [ 10.697542] systemd[1]: Mounting Kernel Debug File System...1261second # [ 10.685834] systemd[1]: initrd-switch-root.service: Deactivated successfully.1262test # [ 10.706931] systemd[1]: Mounting Kernel Trace File System...1263second # [ 10.686760] systemd[1]: Stopped initrd-switch-root.service.1264second # [ 10.688597] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1265second # [ 10.689804] systemd[1]: Created slice Slice /system/getty.1266second # [ 10.693264] systemd[1]: Created slice User and Session Slice.1267second # [ 10.693823] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1268second # [ 10.696285] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1269second # [ 10.696747] systemd[1]: Expecting device /dev/hvc0...1270second # [ 10.697301] systemd[1]: Expecting device /dev/ttyAMA0...1271test # [ 10.717709] systemd[1]: Starting Create List of Static Device Nodes...1272second # [ 10.699879] systemd[1]: Reached target Local Encrypted Volumes.1273test # [ 10.718149] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1274second # [ 10.700323] systemd[1]: Stopped target initrd-fs.target.1275second # [ 10.700901] systemd[1]: Stopped target initrd-root-fs.target.1276second # [ 10.701472] systemd[1]: Stopped target initrd-switch-root.target.1277second # [ 10.704400] systemd[1]: Reached target Virtual Machines and Containers.1278second # [ 10.704829] systemd[1]: Reached target Path Units.1279second # [ 10.705464] systemd[1]: Reached target Remote File Systems.1280second # [ 10.708069] systemd[1]: Reached target Slice Units.1281second # [ 10.708524] systemd[1]: Reached target Swaps.1282second # [ 10.711593] systemd[1]: Listening on Query the User Interactively for a Password.1283second # [ 10.713626] systemd[1]: Listening on Process Core Dump Socket.1284second # [ 10.716173] systemd[1]: Listening on Credential Encryption/Decryption.1285test # [ 10.734976] systemd[1]: Mounting Kernel Configuration File System...1286second # [ 10.718595] systemd[1]: Listening on Factory Reset Management.1287test # [ 10.738971] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1288second # [ 10.719027] systemd[1]: Listening on Hostname Service Socket.1289second # [ 10.721388] systemd[1]: Starting Journal Log Access Socket...1290second # [ 10.724383] systemd[1]: Listening on Journal Audit Socket.1291test # [ 10.742498] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1292test # [ 10.742879] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1293second # [ 10.727031] systemd[1]: Listening on Console Output Muting Service Socket.1294second # [ 10.727661] systemd[1]: Listening on Network Management Metrics Varlink Socket.1295second # [ 10.728297] systemd[1]: Listening on Network Management Varlink Socket.1296second # [ 10.728877] systemd[1]: Listening on Network Management Netlink Socket.1297second # [ 10.729576] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1298second # [ 10.733699] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1299second # [ 10.735863] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1300second # [ 10.740414] systemd[1]: Listening on Disk Repartitioning Service Socket.1301second # [ 10.740913] systemd[1]: Listening on Resolve Monitor Varlink Socket.1302second # [ 10.741516] systemd[1]: Listening on Resolve Service Varlink Socket.1303second # [ 10.744190] systemd[1]: Listening on udev Control Socket.1304second # [ 10.744693] systemd[1]: Listening on udev Varlink Socket.1305test # [ 10.763984] systemd[1]: Mounting FUSE Control File System...1306second # [ 10.747877] systemd[1]: Mounting Huge Pages File System...1307test # [ 10.765205] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671308second # [ 10.750333] systemd[1]: Mounting POSIX Message Queue File System...1309second # [ 10.759034] systemd[1]: Mounting Kernel Debug File System...1310test # [ 10.783513] systemd[1]: Starting Journal Service...1311second # [ 10.771129] systemd[1]: Mounting Kernel Trace File System...1312test # [ 10.792777] systemd[1]: Starting Load Kernel Modules...1313second # [ 10.776545] systemd[1]: Starting Create List of Static Device Nodes...1314second # [ 10.779549] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1315test # [ 10.807583] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1316second # [ 10.794127] systemd[1]: Mounting Kernel Configuration File System...1317second # [ 10.794576] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1318second # [ 10.796320] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1319second # [ 10.796736] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1320test # [ 10.819967] systemd[1]: Starting Remount Root and Kernel File Systems...1321test # [ 10.820364] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322test # [ 10.830105] systemd[1]: Starting Coldplug All udev Devices...1323second # [ 10.813550] systemd[1]: Mounting FUSE Control File System...1324second # [ 10.813985] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671325test # [ 10.840499] systemd[1]: Listening on Journal Log Access Socket.1326test # [ 10.840992] systemd[1]: Mounted Huge Pages File System.1327test # [ 10.841357] systemd[1]: Mounted POSIX Message Queue File System.1328test # [ 10.841726] systemd[1]: Mounted Kernel Debug File System.1329test # [ 10.842114] systemd[1]: Mounted Kernel Trace File System.1330test # [ 10.850134] systemd[1]: Finished Create List of Static Device Nodes.1331second # [ 10.840445] systemd[1]: Starting Journal Service...1332second # [ 10.851292] systemd[1]: Starting Load Kernel Modules...1333test # [ 10.870381] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1334test # [ 10.886406] systemd-journald[321]: Collecting audit messages is enabled.1335test # [ 10.890627] systemd[1]: Mounted Kernel Configuration File System.1336second # [ 10.874751] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1337second # [ 10.885049] systemd[1]: Starting Remount Root and Kernel File Systems...1338second # [ 10.885421] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1339test # [ 10.907575] systemd[1]: Finished Load Kernel Modules.1340test # [ 10.913834] systemd[1]: Starting Firewall...1341test # [ 10.922254] systemd[1]: Starting Apply Kernel Variables...1342test # [ 10.916861] systemd[1]: Queued start job for default target Multi-User System.1343test # [ 10.918496] systemd[1]: systemd-journald.service: Deactivated successfully.1344test # [ 10.919271] systemd-modules-load[322]: Module 'atkbd' is built in1345test # [ 10.919906] systemd-modules-load[322]: Module 'ctr' is built in1346test # [ 10.928713] systemd[1]: Started Journal Service.1347test # [ 10.930259] EXT4-fs (vda): re-mounted f57741b7-2a0a-45db-96ac-6d3265aa5282.1348second # [ 10.915823] systemd[1]: Starting Coldplug All udev Devices...1349second # [ 10.917771] systemd[1]: Listening on Journal Log Access Socket.1350test # [ 10.929926] systemd-modules-load[322]: Module 'loop' is built in1351second # [ 10.921348] systemd-journald[299]: Collecting audit messages is enabled.1352second # [ 10.924421] systemd[1]: Mounted Huge Pages File System.1353second # [ 10.924866] systemd[1]: Mounted POSIX Message Queue File System.1354second # [ 10.925242] systemd[1]: Mounted Kernel Debug File System.1355second # [ 10.925603] systemd[1]: Mounted Kernel Trace File System.1356second # [ 10.932614] systemd[1]: Finished Create List of Static Device Nodes.1357second # [ 10.933106] systemd[1]: Mounted Kernel Configuration File System.1358second # [ 10.926054] systemd[1]: Queued start job for default target Multi-User System.1359second # [ 10.927117] systemd[1]: systemd-journald.service: Deactivated successfully.1360second # [ 10.927988] systemd-modules-load[300]: Module 'atkbd' is built in1361second # [ 10.934069] systemd-modules-load[300]: Module 'ctr' is built in1362second # [ 10.934736] systemd-modules-load[300]: Module 'loop' is built in1363second # [ 10.949123] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1364second # [ 10.949610] systemd[1]: Started Journal Service.1365test # [ 10.970325] systemd[1]: Finished Remount Root and Kernel File Systems.1366test # [ 10.970995] systemd[1]: Mounted FUSE Control File System.1367second # [ 10.954986] systemd[1]: Finished Load Kernel Modules.1368test # [ 10.983900] systemd[1]: Listening on Disk Image Download Service Socket.1369test # [ 10.987262] systemd-oomd[323]: No swap; memory pressure usage will be degraded1370test # [ 10.994013] systemd[1]: Starting Flush Journal to Persistent Storage...1371test # [ 10.994638] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1372second # [ 10.976415] systemd[1]: Starting Firewall...1373test # [ 11.004836] systemd[1]: Starting Load/Save OS Random Seed...1374test # [ 11.005413] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1375test # [ 11.006116] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1376second # [ 10.986431] systemd[1]: Starting Apply Kernel Variables...1377second # [ 10.992236] systemd[1]: Mounted FUSE Control File System.1378second # [ 11.008545] EXT4-fs (vda): re-mounted d4728724-7795-49c7-8616-25a8305a52be.1379second # [ 11.010173] systemd[1]: Finished Remount Root and Kernel File Systems.1380second # [ 11.011222] systemd[1]: Listening on Disk Image Download Service Socket.1381second # [ 11.028278] systemd-oomd[301]: No swap; memory pressure usage will be degraded1382second # [ 11.037054] systemd[1]: Starting Flush Journal to Persistent Storage...1383second # [ 11.037722] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1384second # [ 11.045545] systemd[1]: Starting Load/Save OS Random Seed...1385second # [ 11.046117] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1386test # [ 11.072867] systemd-journald[321]: Received client request to flush runtime journal.1387second # [ 11.056074] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1388second # [ 11.112576] systemd-journald[299]: Received client request to flush runtime journal.1389test # [ 11.164332] systemd[1]: Finished Apply Kernel Variables.1390test # [ 11.165020] systemd[1]: Starting Network Name Resolution...1391test # [ 11.169771] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1392test # [ 11.170951] systemd[1]: Starting Create Static Device Nodes in /dev...1393test # [ 11.171549] systemd[1]: Finished Load/Save OS Random Seed.1394test # [ 11.180220] systemd[1]: Reached target First Boot Complete.1395test # [ 11.181360] systemd[1]: Finished Flush Journal to Persistent Storage.1396second # [ 11.164494] systemd[1]: Finished Apply Kernel Variables.1397second # [ 11.165069] systemd[1]: Starting Network Name Resolution...1398second # [ 11.171306] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1399second # [ 11.172445] systemd[1]: Finished Load/Save OS Random Seed.1400second # [ 11.172961] systemd[1]: Reached target First Boot Complete.1401second # [ 11.177366] systemd[1]: Starting Create Static Device Nodes in /dev...1402second # [ 11.184491] systemd[1]: Finished Flush Journal to Persistent Storage.1403test # [ 11.241422] systemd-resolved[347]: Positive Trust Anchors:1404test # [ 11.242058] systemd-resolved[347]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1405test # [ 11.242960] systemd-resolved[347]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161406test # [ 11.243841] systemd-resolved[347]: 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 test1407second # [ 11.296689] systemd-resolved[325]: Positive Trust Anchors:1408test # [ 11.317313] systemd[1]: Finished Create Static Device Nodes in /dev.1409second # [ 11.297585] systemd-resolved[325]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1410second # [ 11.298498] systemd-resolved[325]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161411test # [ 11.319320] systemd[1]: Reached target Preparation for Local File Systems.1412test # [ 11.326262] systemd[1]: Starting Rule-based Manager for Device Events and Files...1413second # [ 11.299387] systemd-resolved[325]: 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 test1414test # [ 11.407963] systemd-resolved[347]: Using system hostname 'test'.1415test # [ 11.410585] systemd[1]: Started Network Name Resolution.1416second # [ 11.421754] systemd[1]: Finished Create Static Device Nodes in /dev.1417second # [ 11.422424] systemd[1]: Reached target Preparation for Local File Systems.1418second # [ 11.429219] systemd[1]: Starting Rule-based Manager for Device Events and Files...1419test # [ 11.494786] systemd[1]: Finished Coldplug All udev Devices.1420test # [ 11.508303] systemd-udevd[360]: Using default interface naming scheme 'v261'.1421second # [ 11.496480] systemd-resolved[325]: Using system hostname 'second'.1422second # [ 11.499798] systemd[1]: Started Network Name Resolution.1423second # [ 11.555409] systemd[1]: Finished Coldplug All udev Devices.1424test # [ 11.580157] systemd[1]: Started Rule-based Manager for Device Events and Files.1425second # [ 11.574583] systemd-udevd[339]: Using default interface naming scheme 'v261'.1426test # [ 11.624750] systemd[1]: Mounting /run/wrappers...1427second # [ 11.644126] systemd[1]: Started Rule-based Manager for Device Events and Files.1428test # [ 11.699670] systemd[1]: Mounted /run/wrappers.1429test # [ 11.700473] systemd[1]: Reached target Local File Systems.1430second # [ 11.681254] systemd[1]: Mounting /run/wrappers...1431test # [ 11.704807] systemd[1]: Listening on Boot Loader Control Service Socket.1432test # [ 11.710787] systemd[1]: Starting register-nix-paths.service...1433test # [ 11.714316] systemd[1]: Starting Create SUID/SGID Wrappers...1434test # [ 11.714933] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1435test # [ 11.723368] systemd[1]: Starting Save Transient machine-id to Disk...1436test # [ 11.727742] systemd[1]: Starting Create System Files and Directories...1437test # [ 11.731908] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1438test # [ 11.766122] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1439second # [ 11.765152] systemd[1]: Mounted /run/wrappers.1440second # [ 11.765677] systemd[1]: Reached target Local File Systems.1441second # [ 11.769094] systemd[1]: Listening on Boot Loader Control Service Socket.1442second # [ 11.775045] systemd[1]: Starting register-nix-paths.service...1443second # [ 11.779440] systemd[1]: Starting Create SUID/SGID Wrappers...1444second # [ 11.780134] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1445second # [ 11.783840] systemd[1]: Starting Save Transient machine-id to Disk...1446second # [ 11.794288] systemd[1]: Starting Create System Files and Directories...1447second # [ 11.795922] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1448test # [ 11.844225] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1449test # [ 11.853978] systemd[1]: Finished Save Transient machine-id to Disk.1450second # [ 11.856506] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse1451test # [ 11.915173] systemd[1]: Finished Create System Files and Directories.1452second # [ 11.902910] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1453test # [ 11.924105] systemd[1]: Starting Rebuild Journal Catalog...1454test # [ 11.929411] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1455second # [ 11.910428] systemd[1]: Finished Save Transient machine-id to Disk.1456test # [ 11.999419] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1457second # [ 11.980372] systemd[1]: Finished Create System Files and Directories.1458second # [ 11.988198] systemd[1]: Starting Rebuild Journal Catalog...1459second # [ 11.995239] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1460test # [ 12.066986] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1461second # [ 12.083184] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1462test # [ 12.113389] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1463test # [ 12.122864] systemd[1]: Finished Rebuild Journal Catalog.1464test # [ 12.128152] systemd[1]: Starting Update is Completed...1465second # [ 12.164501] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1466second # [ 12.177135] systemd[1]: Finished Rebuild Journal Catalog.1467test # [ 12.200437] systemd[1]: Finished Update is Completed.1468second # [ 12.183603] systemd[1]: Starting Update is Completed...1469second # [ 12.203947] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1470second # [ 12.260937] systemd[1]: Finished Update is Completed.1471test # [ 12.417589] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.1472test # [ 12.424236] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line.1473second # [ 12.489441] (udev-worker)[370]: Network interface NamePolicy= disabled on kernel command line.1474second # [ 12.494425] (udev-worker)[373]: Network interface NamePolicy= disabled on kernel command line.1475test # [ 12.620118] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1476test # [ 12.622858] systemd[1]: Finished Create SUID/SGID Wrappers.1477test # [ 12.652219] systemd[1]: Finished register-nix-paths.service.1478test # [ 12.652844] systemd[1]: Reached target System Initialization.1479test # [ 12.653357] systemd[1]: Started Discard unused filesystem blocks once a week.1480test # [ 12.653972] systemd[1]: Started Daily Cleanup of Temporary Directories.1481test # [ 12.654961] systemd[1]: Reached target Timer Units.1482test # [ 12.661447] systemd[1]: Listening on D-Bus System Message Bus Socket.1483test # [ 12.662176] systemd[1]: Listening on Nix Daemon Socket.1484test # [ 12.668686] systemd[1]: Listening on Authorization Manager Agent Helper.1485test # [ 12.669401] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1486test # [ 12.670163] systemd[1]: Reached target Socket Units.1487test # [ 12.673639] systemd[1]: Starting D-Bus System Message Bus...1488second # [ 12.690066] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1489second # [ 12.692530] systemd[1]: Finished Create SUID/SGID Wrappers.1490second # [ 12.703157] systemd[1]: Finished register-nix-paths.service.1491second # [ 12.704746] systemd[1]: Reached target System Initialization.1492second # [ 12.705337] systemd[1]: Started Discard unused filesystem blocks once a week.1493second # [ 12.705978] systemd[1]: Started Daily Cleanup of Temporary Directories.1494second # [ 12.706605] systemd[1]: Reached target Timer Units.1495test # [ 12.733004] mousedev: PS/2 mouse device common for all mice1496second # [ 12.712849] systemd[1]: Listening on D-Bus System Message Bus Socket.1497second # [ 12.713507] systemd[1]: Listening on Nix Daemon Socket.1498second # [ 12.717885] systemd[1]: Listening on Authorization Manager Agent Helper.1499second # [ 12.718565] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1500second # [ 12.719316] systemd[1]: Reached target Socket Units.1501second # [ 12.724103] systemd[1]: Starting D-Bus System Message Bus...1502test # [ 12.809457] dbus-broker-launch[517]: Looking up NSS user entry for 'systemd-timesync'...1503test # [ 12.817912] dbus-broker-launch[517]: NSS returned no entry for 'systemd-timesync'1504test # [ 12.818688] dbus-broker-launch[517]: Invalid user-name in /nix/store/wyh7qn0kfyvi9gz8lkbiy5nrj86bpxzz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1505second # [ 12.814027] mousedev: PS/2 mouse device common for all mice1506second # [ 12.824438] dbus-broker-launch[496]: Looking up NSS user entry for 'systemd-timesync'...1507test # [ 12.848282] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1508second # [ 12.834191] dbus-broker-launch[496]: NSS returned no entry for 'systemd-timesync'1509second # [ 12.834893] dbus-broker-launch[496]: Invalid user-name in /nix/store/wyh7qn0kfyvi9gz8lkbiy5nrj86bpxzz-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1510test # [ 12.855013] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1511test # [ 12.858478] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1512test # [ 12.862899] dbus-broker-launch[517]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1513test # [ 12.870757] systemd[1]: Started D-Bus System Message Bus.1514test # [ 12.871277] systemd[1]: Reached target Basic System.1515test # [ 12.875645] systemd[1]: Starting Network Manager Script Dispatcher Service...1516test # [ 12.879819] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1517test # [ 12.884332] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1518second # [ 12.883423] dbus-broker-launch[496]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1519second # [ 12.888893] dbus-broker-launch[496]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/z6dj4b78fh6101ipz5cbg52xd271qgdd-networkmanager-1.58.1/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1520second # [ 12.890772] dbus-broker-launch[496]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/zaklcm28mfdjnfq1076w3nzwsyjwkw1p-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1521test # [ 12.921189] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1522second # [ 12.902383] dbus-broker-launch[496]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/km79636izr3pfdnzj6kd4kb57b0wl7gm-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1523second # [ 12.910259] systemd[1]: Started D-Bus System Message Bus.1524second # [ 12.910815] systemd[1]: Reached target Basic System.1525second # [ 12.911293] systemd[1]: Starting Network Manager Script Dispatcher Service...1526test # [ 12.931926] dbus-broker-launch[517]: Ready1527second # [ 12.911940] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1528test # [ 12.936717] systemd[1]: Started backdoor.service.1529second # [ 12.921997] systemd[1]: Started backdoor.service.1530second # [ 12.937908] systemd[1]: Starting Import lastlog data into lastlog2 database...1531test # [ 12.981150] systemd[1]: Starting Import lastlog data into lastlog2 database...1532second # [ 12.963678] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1533second # [ 12.972082] dbus-broker-launch[496]: Ready1534test # [ 13.009802] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1535second # [ 12.992306] systemd[1]: Starting Post-Boot Actions...1536test # [ 13.024748] systemd[1]: Starting Post-Boot Actions...1537second # [ 13.019106] systemd[1]: Started Reset console on configuration changes.1538test # [ 13.066705] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[535]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1539test # [ 13.078053] systemd[1]: Started Reset console on configuration changes.1540second # [ 13.063297] jfm1sfzvhs9cnbhrfml5p82i6z6c2sxr-wifi-secrets[515]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1541test # [ 13.085136] adg9kzka51ddw5qgki73hk89404rrphj-wifi-secrets[536]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1542second # [ 13.103638] systemd[1]: Finished Post-Boot Actions.1543second # [ 13.122790] systemd[1]: Finished Firewall.1544second # [ 13.124590] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1545test # [ 13.149118] systemd[1]: Finished Firewall.1546second # [ 13.129425] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1547test # [ 13.150335] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1548test # [ 13.155742] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1549second # connecting to host...1550test # [ 13.162748] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1551second # [ 13.145488] systemd[1]: Reached target Preparation for Network.1552test # [ 13.167567] pqf2w5skr70322kcnwqj9n6c53wanj8f-wifi-secrets[538]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1553second # [ 13.149930] systemd[1]: Listening on Network Management Resolve Hook Socket.1554test # connecting to host...1555test # [ 13.177532] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1556second # [ 13.164974] systemd[1]: Starting Network Manager...1557second # [ 13.175917] systemd[1]: Starting Network Management...1558test # [ 13.202330] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1559test # [ 13.212106] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1560second # [ 13.210155] systemd[1]: Started Name Service Cache Daemon (nsncd).1561test: Guest shell says: b'Spawning backdoor root shell...\n'1562second # [ 13.224461] systemd[1]: Reached target Host and Network Name Lookups.1563second # [ 13.225075] systemd[1]: Reached target User and Group Name Lookups.1564test # [ 13.244871] systemd[1]: Started Name Service Cache Daemon (nsncd).1565test # [ 13.256441] systemd[1]: Started Network Manager Script Dispatcher Service.1566test: connected to guest root shell1567test # [ 13.257101] systemd[1]: Reached target Preparation for Network.1568test: (connecting took 13.53 seconds)1569second # [ 13.261510] systemd[1]: Starting User Login Management...1570test: (finished: waiting for the VM to finish booting, in 13.53 seconds)1571test # [ 13.264408] systemd[1]: Reached target Host and Network Name Lookups.1572test # [ 13.265010] systemd[1]: Reached target User and Group Name Lookups.1573test # [ 13.265529] systemd[1]: Listening on Network Management Resolve Hook Socket.1574test # [ 13.272179] systemd[1]: Starting Network Manager...1575test # [ 13.280199] systemd[1]: Starting User Login Management...1576test # [ 13.287235] systemd[1]: Starting Network Management...1577second # [ 13.273870] nsncd[519]: Oct 04 20:14:41.005 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1578test # [ 13.306218] nsncd[544]: Oct 04 20:14:40.051 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1579second # [ 13.308110] systemd[1]: Started Network Manager Script Dispatcher Service.1580test # [ 13.355661] systemd[1]: Finished Post-Boot Actions.1581second # [ 13.352408] systemd[1]: Finished Import lastlog data into lastlog2 database.1582test # [ 13.377356] systemd[1]: Finished Import lastlog data into lastlog2 database.1583second # [ 13.432131] systemd-logind[558]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1584second # [ 13.436257] systemd-logind[558]: Watching system buttons on /dev/input/event0 (gpio-keys)1585second # [ 13.441876] systemd-logind[558]: New seat seat0.1586second # [ 13.442318] systemd[1]: Started User Login Management.1587second # [ 13.455587] systemd[1]: Starting linger-users.service...1588test # [ 13.493659] systemd-logind[584]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1589test # [ 13.498616] systemd-logind[584]: Watching system buttons on /dev/input/event0 (gpio-keys)1590test # [ 13.499863] systemd-logind[584]: New seat seat0.1591test # [ 13.505719] systemd[1]: Started User Login Management.1592test # [ 13.513200] systemd[1]: Starting linger-users.service...1593second # [ 13.561426] cfg80211: Loading compiled-in X.509 certificates for regulatory database1594second # [ 13.569988] systemd[1]: linger-users.service: Deactivated successfully.1595second # [ 13.571978] systemd[1]: Finished linger-users.service.1596test # [ 13.620420] cfg80211: Loading compiled-in X.509 certificates for regulatory database1597second # [ 13.621430] systemd[1]: Starting Hostname Service...1598second # [ 13.630187] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1599second # [ 13.630683] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1600test # [ 13.645217] systemd[1]: linger-users.service: Deactivated successfully.1601test # [ 13.646418] systemd[1]: Finished linger-users.service.1602second # [ 13.664334] systemd-networkd[541]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1603test # [ 13.686650] systemd[1]: Starting Hostname Service...1604test # [ 13.695663] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1605test # [ 13.696171] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1606second # [ 13.672406] systemd-networkd[541]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1607second # [ 13.674298] systemd-networkd[541]: lo: Link UP1608second # [ 13.674736] systemd-networkd[541]: lo: Gained carrier1609second # [ 13.683956] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1610second # [ 13.692864] systemd[1]: Started Network Management.1611second # [ 13.693380] systemd-networkd[541]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1612second # [ 13.694107] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1613second # [ 13.708264] systemd-networkd[541]: eth1: Link UP1614second # [ 13.708760] systemd-networkd[541]: eth1: Gained carrier1615second # [ 13.709233] systemd-networkd[541]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1616test # [ 13.741378] systemd-networkd[585]: /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.1617test # [ 13.751914] systemd-networkd[585]: /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.1618test # [ 13.763974] systemd-networkd[585]: lo: Link UP1619test # [ 13.764494] systemd-networkd[585]: lo: Gained carrier1620test # [ 13.764951] systemd[1]: Started Network Management.1621test # [ 13.770894] systemd-networkd[585]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1622test # [ 13.780087] systemd-networkd[585]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1623test # [ 13.780864] systemd-networkd[585]: eth1: Link UP1624test # [ 13.781308] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1625test # [ 13.781945] systemd-networkd[585]: eth1: Gained carrier1626test # [ 13.782423] systemd-networkd[585]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1627second # [ 13.773094] systemd[1]: Started Hostname Service.1628second # [ 13.786513] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1629test # [ 13.832485] systemd[1]: Started Hostname Service.1630test # [ 13.851807] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1631second # [ 13.851598] systemd-networkd[541]: eth0: Link UP1632second # [ 13.853234] systemd-networkd[541]: eth0: Gained carrier1633second # [ 13.871384] systemd[1]: Started Network Manager.1634second # [ 13.873122] systemd[1]: Reached target Network.1635test # [ 13.898760] systemd-networkd[585]: eth0: Link UP1636test # [ 13.899270] systemd-networkd[585]: eth0: Gained carrier1637second # [ 13.880377] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1638second # [ 13.886400] systemd[1]: Starting Permit User Sessions...1639test # [ 13.920341] systemd[1]: Started Network Manager.1640test # [ 13.923277] systemd[1]: Reached target Network.1641test # [ 13.928250] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1642test # [ 13.934010] systemd[1]: Starting Permit User Sessions...1643second # [ 14.006689] systemd[1]: Finished Permit User Sessions.1644second # [ 14.018651] systemd[1]: Started Getty on tty1.1645second # [ 14.023182] systemd[1]: Reached target Login Prompts.1646test # [ 14.050722] systemd[1]: Finished Permit User Sessions.1647test # [ 14.074731] systemd[1]: Started Getty on tty1.1648test # [ 14.075268] systemd[1]: Reached target Login Prompts.1649second # [ 14.064909] systemd-resolved[325]: eth0: Bus client set default route setting: yes1650second # [ 14.070332] systemd-resolved[325]: eth0: Bus client set DNS server list to: 10.0.2.31651test # [ 14.123208] NetworkManager[583]: <warn> [1791144880.9286] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1652test # [ 14.128948] NetworkManager[583]: <warn> [1791144880.9306] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1653test # [ 14.144075] systemd-resolved[347]: eth0: Bus client set default route setting: yes1654test # [ 14.155216] systemd-resolved[347]: eth0: Bus client set DNS server list to: 10.0.2.31655second # [ 14.135744] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1656second # [ 14.140758] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1657second # [ 14.151238] systemd[1]: Reached target Multi-User System.1658second # [ 14.155528] systemd[1]: Startup finished in 755ms (kernel) + 8.833s (initrd) + 4.563s (userspace) = 14.152s.1659test # [ 14.188181] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1660test # [ 14.190610] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1661test # [ 14.202939] systemd[1]: Reached target Multi-User System.1662test # [ 14.206397] systemd[1]: Startup finished in 775ms (kernel) + 8.829s (initrd) + 4.599s (userspace) = 14.204s.1663second # [ 14.387030] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31664test # [ 14.480210] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:06.0/virtio5/input/input31665test: (finished: waiting for unit NetworkManager.service, in 14.78 seconds)1666test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1667test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.03 seconds)1668cleanup1669kill QemuMachine (pid 47)1670second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1671second # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1672second # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1673second # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1674kill QemuMachine (pid 48)1675test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/81ag9nxz1flw3xjkv1vvqg8y2ipx2ins-python3-3.14.7/bin/python3.14)1676test # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1677test # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1678test # [2026-10-04T20:14:41Z INFO virtiofsd] Client disconnected, shutting down1679(finished: cleanup, in 0.22 seconds)1680Traceback (most recent call last):1681 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1682 sys.exit(main())1683 ~~~~^^1684 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1685 driver.run_tests()1686 ~~~~~~~~~~~~~~~~^^1687 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 437, in run_tests1688 self.test_script()1689 ~~~~~~~~~~~~~~~~^^1690 File "/nix/store/v7jjlkxf97a1d9c41xc7m8xw03k84200-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 395, in test_script1691 exec(self.tests, symbols, None)1692 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1693 File "<string>", line 4, in <module>1694AssertionError: Expected mock password in connection file:1695[connection]1696autoconnect=true1697id=1698type=wifi16991700[wifi]1701mode=infrastructure1702ssid=17031704[wifi-security]1705key-mgmt=wpa-psk1706psk=1707