vm-test-run-wifi
default.checks.aarch64-linux.wifi
· build #294
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 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_ssh10start all VMs11second: starting vm12test: starting vm13test # Disk image does not exist, creating the virtualisation disk image...14second # Disk image does not exist, creating the virtualisation disk image...15test # Formatting '/build/vm-state-test/tmp.kz1NzY3b7w', fmt=raw size=107374182416test: QEMU running (pid 15)17second: QEMU running (pid 14)18second # Formatting '/build/vm-state-second/tmp.Cv6xThZyjL', fmt=raw size=107374182419test # mke2fs 1.47.4 (6-Mar-2025)20second # mke2fs 1.47.4 (6-Mar-2025)21test # Discarding device blocks: 0/262144 done22second # Discarding device blocks: 0/262144 done23test # Creating filesystem with 262144 4k blocks and 65536 inodes24(finished: start all VMs, in 0.53 seconds)25test # Filesystem UUID: b463b6ff-c664-4f93-b9e6-f8c293004dec26test # Superblock backups stored on blocks:27test: waiting for unit NetworkManager.service28second # Creating filesystem with 262144 4k blocks and 65536 inodes29test # 32768, 98304, 163840, 22937630test: waiting for the VM to finish booting31test # 32second # Filesystem UUID: f872a561-5625-4c36-b824-b8c12c9e6a9b33test # Allocating group tables: 0/8 done34second # Superblock backups stored on blocks:35test # Writing inode tables: 0/8 done36second # 32768, 98304, 163840, 22937637test # Creating journal (8192 blocks): done38second # 39test # Writing superblocks and filesystem accounting information: 0/8 done40second # Allocating group tables: 0/8 done41test # 42second # Writing inode tables: 0/8 done43test # Virtualisation disk image created.44second # Creating journal (8192 blocks): done45second # Writing superblocks and filesystem accounting information: 0/8 done46second # 47second # Virtualisation disk image created.48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49test # [ 0.000000] Linux version 6.18.43 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 6 17:27:09 UTC 202650test # [ 0.000000] KASLR enabled51test # [ 0.000000] random: crng init done52test # [ 0.000000] Machine model: linux,dummy-virt53test # [ 0.000000] efi: UEFI not found.54test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT55test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]56test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]57test # [ 0.000000] Zone ranges:58test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]59test # [ 0.000000] DMA32 empty60test # [ 0.000000] Normal empty61test # [ 0.000000] Device empty62test # [ 0.000000] Movable zone start for each node63test # [ 0.000000] Early memory node ranges64test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]65test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]66test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000067test # [ 0.000000] psci: probing for conduit method from DT.68test # [ 0.000000] psci: PSCIv1.3 detected in firmware.69test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs70test # [ 0.000000] psci: Trusted OS migration not required71test # [ 0.000000] psci: SMC Calling Convention v1.172test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)73test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129674test # [ 0.000000] Detected PIPT I-cache on CPU075test # [ 0.000000] CPU features: detected: GICv3 CPU interface76test # [ 0.000000] CPU features: detected: Spectre-v477test # [ 0.000000] CPU features: detected: Spectre-BHB78test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR79test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)80test # [ 0.000000] CPU features: detected: ARM erratum 141804081test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion82test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing83test # [ 0.000000] alternatives: applying boot alternatives84test # [ 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/b6nxbs0rjlfiq2hy90qarypf3gl02bhm-nixos-system-test-test/init regInfo=/nix/store/hc2zgcc8b2vm7h4axsqj6ig7kfj7g3rd-closure-info/registration console=ttyAMA0,115200n8 console=tty085test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/hc2zgcc8b2vm7h4axsqj6ig7kfj7g3rd-closure-info/registration", will be passed to user space.86second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]87test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes88test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)89test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)90test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB91test # [ 0.000000] software IO TLB: area num 1.92second # [ 0.000000] Linux version 6.18.43 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Thu Aug 6 17:27:09 UTC 202693second # [ 0.000000] KASLR enabled94second # [ 0.000000] random: crng init done95second # [ 0.000000] Machine model: linux,dummy-virt96second # [ 0.000000] efi: UEFI not found.97second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT98test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)99second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]100test # [ 0.000000] Fallback order for Node 0: 0101second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]102test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144103second # [ 0.000000] Zone ranges:104test # [ 0.000000] Policy zone: DMA105second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]106second # [ 0.000000] DMA32 empty107test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off108second # [ 0.000000] Normal empty109second # [ 0.000000] Device empty110test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1111second # [ 0.000000] Movable zone start for each node112test # [ 0.000000] allocated 2097152 bytes of page_ext113second # [ 0.000000] Early memory node ranges114test # [ 0.000000] ftrace: allocating 74743 entries in 292 pages115second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]116test # [ 0.000000] ftrace: allocated 292 pages with 3 groups117test # [ 0.000000] rcu: Hierarchical RCU implementation.118second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]119test # [ 0.000000] rcu: RCU event tracing is enabled.120second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000121test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.122second # [ 0.000000] psci: probing for conduit method from DT.123second # [ 0.000000] psci: PSCIv1.3 detected in firmware.124test # [ 0.000000] Trampoline variant of Tasks RCU enabled.125test # [ 0.000000] Rude variant of Tasks RCU enabled.126second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs127test # [ 0.000000] Tracing variant of Tasks RCU enabled.128second # [ 0.000000] psci: Trusted OS migration not required129second # [ 0.000000] psci: SMC Calling Convention v1.1130test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.131second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)132test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1133second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296134test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.135second # [ 0.000000] Detected PIPT I-cache on CPU0136second # [ 0.000000] CPU features: detected: GICv3 CPU interface137test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.138second # [ 0.000000] CPU features: detected: Spectre-v4139second # [ 0.000000] CPU features: detected: Spectre-BHB140test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.141second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR142test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0143test # [ 0.000000] GICv3: 256 SPIs implemented144second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)145test # [ 0.000000] GICv3: 0 Extended SPIs implemented146second # [ 0.000000] CPU features: detected: ARM erratum 1418040147test # [ 0.000000] Root IRQ handler: gic_handle_irq148test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI149second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion150test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0151second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing152test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000153second # [ 0.000000] alternatives: applying boot alternatives154test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]155test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)156test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)157test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000158second # [ 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/k9zwrlaml4d69mrzm8vpvvll1hmwm4y7-nixos-system-second-test/init regInfo=/nix/store/4rq1xswbrn2pqwb4xnr887cfjlp5g1xf-closure-info/registration console=ttyAMA0,115200n8 console=tty0159test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000160test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.161second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4rq1xswbrn2pqwb4xnr887cfjlp5g1xf-closure-info/registration", will be passed to user space.162test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns163second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes164test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040165second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)166test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).167second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)168test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns169second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB170second # [ 0.000000] software IO TLB: area num 1.171test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns172test # [ 0.000059] arm-pv: using stolen time PV173second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)174second # [ 0.000000] Fallback order for Node 0: 0175test # [ 0.000417] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)176second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144177test # [ 0.000693] Console: colour dummy device 80x25178second # [ 0.000000] Policy zone: DMA179test # [ 0.000705] printk: legacy console [tty0] enabled180second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off181test # [ 0.000922] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)182second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1183test # [ 0.000928] pid_max: default: 32768 minimum: 301184second # [ 0.000000] allocated 2097152 bytes of page_ext185test # [ 0.001009] LSM: initializing lsm=capability,landlock,yama,bpf,ima186second # [ 0.000000] ftrace: allocating 74743 entries in 292 pages187test # [ 0.001149] landlock: Up and running.188second # [ 0.000000] ftrace: allocated 292 pages with 3 groups189test # [ 0.001151] Yama: becoming mindful.190second # [ 0.000000] rcu: Hierarchical RCU implementation.191test # [ 0.001564] LSM support for eBPF active192second # [ 0.000000] rcu: RCU event tracing is enabled.193test # [ 0.001719] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)194second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.195test # [ 0.001739] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)196second # [ 0.000000] Trampoline variant of Tasks RCU enabled.197second # [ 0.000000] Rude variant of Tasks RCU enabled.198test # [ 0.003572] cacheinfo: Unable to detect cache hierarchy for CPU 0199second # [ 0.000000] Tracing variant of Tasks RCU enabled.200test # [ 0.004411] rcu: Hierarchical SRCU implementation.201test # [ 0.004416] rcu: Max phase no-delay instances is 1000.202second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.203test # [ 0.005349] fsl-mc MSI: its@8080000 domain created204second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1205test # [ 0.005544] EFI services will not be available.206test # [ 0.005643] smp: Bringing up secondary CPUs ...207second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208test # [ 0.005676] smp: Brought up 1 node, 1 CPU209test # [ 0.005680] SMP: Total of 1 processors activated.210second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.211test # [ 0.005682] CPU: All CPU(s) started at EL1212test # [ 0.005695] CPU features: detected: 32-bit EL0 Support213second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.214test # [ 0.005698] CPU features: detected: Data cache clean to the PoU not required for I/D coherence215second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0216second # [ 0.000000] GICv3: 256 SPIs implemented217test # [ 0.005701] CPU features: detected: Common not Private translations218second # [ 0.000000] GICv3: 0 Extended SPIs implemented219test # [ 0.005703] CPU features: detected: CRC32 instructions220second # [ 0.000000] Root IRQ handler: gic_handle_irq221second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI222test # [ 0.005705] CPU features: detected: Data cache clean to Point of Persistence223second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0224test # [ 0.005707] CPU features: detected: Enhanced Virtualization Traps225second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000226test # [ 0.005717] CPU features: detected: RCpc load-acquire (LDAPR)227second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]228test # [ 0.005719] CPU features: detected: LSE atomic instructions229second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)230test # [ 0.005721] CPU features: detected: Privileged Access Never231test # [ 0.005723] CPU features: detected: PMUv3232test # [ 0.005725] CPU features: detected: RAS Extension Support233second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)234test # [ 0.005728] CPU features: detected: Speculative Store Bypassing Safe (SSBS)235second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000236test # [ 0.005751] alternatives: applying system-wide alternatives237second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000238test # [ 0.007249] CPU features: detected: Hardware dirty bit management on CPU0239second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.240second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns241test # [ 0.007486] Memory: 893144K/1048576K available (24320K kernel code, 7086K rwdata, 26332K rodata, 4736K init, 1102K bss, 114152K reserved, 32768K cma-reserved)242test # [ 0.007997] devtmpfs: initialized243second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040244second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).245test # [ 0.009507] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)246test # [ 0.009531] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).247second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns248test # [ 0.009666] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL249second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns250test # [ 0.009669] 0 pages in range for non-PLT usage251second # [ 0.000057] arm-pv: using stolen time PV252test # [ 0.009670] 508336 pages in range for PLT usage253test # [ 0.009815] pinctrl core: initialized pinctrl subsystem254second # [ 0.000422] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)255test # [ 0.010646] DMI not present or invalid.256second # [ 0.000704] Console: colour dummy device 80x25257test # [ 0.014025] NET: Registered PF_NETLINK/PF_ROUTE protocol family258second # [ 0.000716] printk: legacy console [tty0] enabled259test # [ 0.016429] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations260second # [ 0.000925] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)261test # [ 0.016582] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations262second # [ 0.000931] pid_max: default: 32768 minimum: 301263test # [ 0.016752] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations264second # [ 0.001007] LSM: initializing lsm=capability,landlock,yama,bpf,ima265second # [ 0.001141] landlock: Up and running.266test # [ 0.016778] audit: initializing netlink subsys (disabled)267second # [ 0.001144] Yama: becoming mindful.268test # [ 0.017415] thermal_sys: Registered thermal governor 'fair_share'269second # [ 0.001580] LSM support for eBPF active270test # [ 0.017417] thermal_sys: Registered thermal governor 'bang_bang'271second # [ 0.001730] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)272test # [ 0.017421] thermal_sys: Registered thermal governor 'step_wise'273second # [ 0.001749] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)274test # [ 0.017423] thermal_sys: Registered thermal governor 'user_space'275second # [ 0.003524] cacheinfo: Unable to detect cache hierarchy for CPU 0276test # [ 0.017425] thermal_sys: Registered thermal governor 'power_allocator'277second # [ 0.004381] rcu: Hierarchical SRCU implementation.278test # [ 0.017461] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1279second # [ 0.004388] rcu: Max phase no-delay instances is 1000.280test # [ 0.017477] cpuidle: using governor ladder281second # [ 0.005361] fsl-mc MSI: its@8080000 domain created282test # [ 0.017481] cpuidle: using governor menu283second # [ 0.005556] EFI services will not be available.284test # [ 0.017613] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.285second # [ 0.005658] smp: Bringing up secondary CPUs ...286second # [ 0.005689] smp: Brought up 1 node, 1 CPU287test # [ 0.017637] ASID allocator initialised with 32768 entries288second # [ 0.005694] SMP: Total of 1 processors activated.289test # [ 0.018314] Serial: AMBA PL011 UART driver290second # [ 0.005696] CPU: All CPU(s) started at EL1291test # [ 0.022131] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1292second # [ 0.005709] CPU features: detected: 32-bit EL0 Support293test # [ 0.022306] printk: console [ttyAMA0] enabled294second # [ 0.005711] CPU features: detected: Data cache clean to the PoU not required for I/D coherence295second # [ 0.005714] CPU features: detected: Common not Private translations296second # [ 0.005716] CPU features: detected: CRC32 instructions297second # [ 0.005718] CPU features: detected: Data cache clean to Point of Persistence298second # [ 0.005721] CPU features: detected: Enhanced Virtualization Traps299second # [ 0.005730] CPU features: detected: RCpc load-acquire (LDAPR)300second # [ 0.005733] CPU features: detected: LSE atomic instructions301second # [ 0.005735] CPU features: detected: Privileged Access Never302second # [ 0.005737] CPU features: detected: PMUv3303second # [ 0.005739] CPU features: detected: RAS Extension Support304second # [ 0.005742] CPU features: detected: Speculative Store Bypassing Safe (SSBS)305second # [ 0.005764] alternatives: applying system-wide alternatives306second # [ 0.007320] CPU features: detected: Hardware dirty bit management on CPU0307test # [ 0.095335] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages308second # [ 0.007582] Memory: 893136K/1048576K available (24320K kernel code, 7086K rwdata, 26332K rodata, 4736K init, 1102K bss, 114152K reserved, 32768K cma-reserved)309test # [ 0.095367] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page310second # [ 0.008043] devtmpfs: initialized311test # [ 0.095371] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages312second # [ 0.009585] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)313test # [ 0.095377] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page314second # [ 0.009607] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).315test # [ 0.095381] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages316second # [ 0.009743] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL317test # [ 0.095384] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page318second # [ 0.009747] 0 pages in range for non-PLT usage319second # [ 0.009748] 508336 pages in range for PLT usage320test # [ 0.095387] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages321second # [ 0.009870] pinctrl core: initialized pinctrl subsystem322test # [ 0.095390] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page323second # [ 0.010656] DMI not present or invalid.324second # [ 0.013980] NET: Registered PF_NETLINK/PF_ROUTE protocol family325second # [ 0.016379] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations326second # [ 0.016534] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations327test # [ 0.100591] fbcon: Taking over console328test # [ 0.100615] ACPI: Interpreter disabled.329second # [ 0.016708] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations330second # [ 0.016731] audit: initializing netlink subsys (disabled)331second # [ 0.017347] thermal_sys: Registered thermal governor 'fair_share'332second # [ 0.017350] thermal_sys: Registered thermal governor 'bang_bang'333second # [ 0.017353] thermal_sys: Registered thermal governor 'step_wise'334second # [ 0.017355] thermal_sys: Registered thermal governor 'user_space'335second # [ 0.017357] thermal_sys: Registered thermal governor 'power_allocator'336second # [ 0.017397] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1337second # [ 0.017411] cpuidle: using governor ladder338second # [ 0.017416] cpuidle: using governor menu339second # [ 0.017558] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.340second # [ 0.017581] ASID allocator initialised with 32768 entries341second # [ 0.018241] Serial: AMBA PL011 UART driver342test # [ 0.107107] iommu: Default domain type: Translated343second # [ 0.021781] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1344second # [ 0.021968] printk: console [ttyAMA0] enabled345test # [ 0.107125] iommu: DMA domain TLB invalidation policy: strict mode346test # [ 0.107478] SCSI subsystem initialized347test # [ 0.109164] usbcore: registered new interface driver usbfs348test # [ 0.109203] usbcore: registered new interface driver hub349test # [ 0.109215] usbcore: registered new device driver usb350test # [ 0.109481] pps_core: LinuxPPS API ver. 1 registered351test # [ 0.109488] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>352test # [ 0.109494] PTP clock support registered353test # [ 0.109538] EDAC MC: Ver: 3.0.0354test # [ 0.112593] scmi_core: SCMI protocol bus registered355test # [ 0.113377] FPGA manager framework356test # [ 0.114406] vgaarb: loaded357second # [ 0.096151] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages358second # [ 0.096189] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page359test # [ 0.114846] clocksource: Switched to clocksource arch_sys_counter360second # [ 0.096194] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages361second # [ 0.096199] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page362test # [ 0.116262] VFS: Disk quotas dquot_6.6.0363second # [ 0.096202] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages364test # [ 0.116301] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)365second # [ 0.096205] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page366second # [ 0.096209] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages367second # [ 0.096212] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page368second # [ 0.101476] fbcon: Taking over console369second # [ 0.101500] ACPI: Interpreter disabled.370test # [ 0.123521] netfs: FS-Cache loaded371test # [ 0.123689] pnp: PnP ACPI: disabled372second # [ 0.107468] iommu: Default domain type: Translated373second # [ 0.107483] iommu: DMA domain TLB invalidation policy: strict mode374second # [ 0.108445] SCSI subsystem initialized375second # [ 0.109936] usbcore: registered new interface driver usbfs376second # [ 0.109976] usbcore: registered new interface driver hub377second # [ 0.109990] usbcore: registered new device driver usb378second # [ 0.110236] pps_core: LinuxPPS API ver. 1 registered379second # [ 0.110243] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>380second # [ 0.110257] PTP clock support registered381test # [ 0.127303] NET: Registered PF_INET protocol family382second # [ 0.110288] EDAC MC: Ver: 3.0.0383test # [ 0.127506] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)384second # [ 0.113456] scmi_core: SCMI protocol bus registered385second # [ 0.114256] FPGA manager framework386second # [ 0.115311] vgaarb: loaded387second # [ 0.123772] clocksource: Switched to clocksource arch_sys_counter388second # [ 0.124541] VFS: Disk quotas dquot_6.6.0389second # [ 0.124572] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390second # [ 0.126273] netfs: FS-Cache loaded391second # [ 0.126410] pnp: PnP ACPI: disabled392second # [ 0.129569] NET: Registered PF_INET protocol family393second # [ 0.129773] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.158166] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.158201] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.158216] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.158254] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398test # [ 0.158322] TCP: Hash tables configured (established 8192 bind 8192)399test # [ 0.158417] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.158483] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401test # [ 0.158536] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402test # [ 0.158656] NET: Registered PF_UNIX/PF_LOCAL protocol family403test # [ 0.158687] NET: Registered PF_XDP protocol family404test # [ 0.158711] PCI: CLS 0 bytes, default 64405test # [ 0.158954] Trying to unpack rootfs image as initramfs...406test # [ 0.173396] kvm [1]: HYP mode not available407second # [ 0.157580] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)408second # [ 0.157616] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)409second # [ 0.157632] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)410second # [ 0.157669] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)411second # [ 0.157737] TCP: Hash tables configured (established 8192 bind 8192)412second # [ 0.157842] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)413second # [ 0.157913] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)414second # [ 0.157963] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)415second # [ 0.158093] NET: Registered PF_UNIX/PF_LOCAL protocol family416second # [ 0.158168] NET: Registered PF_XDP protocol family417second # [ 0.158194] PCI: CLS 0 bytes, default 64418second # [ 0.158426] Trying to unpack rootfs image as initramfs...419second # [ 0.169985] kvm [1]: HYP mode not available420test # [ 0.247257] Initialise system trusted keyrings421test # [ 0.247838] workingset: timestamp_bits=42 max_order=18 bucket_order=0422test # [ 0.248843] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.249389] 9p: Installing v9fs 9p2000 file system support424second # [ 0.244175] Initialise system trusted keyrings425second # [ 0.244745] workingset: timestamp_bits=42 max_order=18 bucket_order=0426second # [ 0.245786] squashfs: version 4.0 (2009/01/31) Phillip Lougher427second # [ 0.246323] 9p: Installing v9fs 9p2000 file system support428test # [ 0.268755] Key type asymmetric registered429test # [ 0.268787] Asymmetric key parser 'x509' registered430test # [ 0.268858] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431test # [ 0.274945] io scheduler mq-deadline registered432test # [ 0.274968] io scheduler kyber registered433test # [ 0.278473] pl061_gpio 9030000.pl061: PL061 GPIO chip registered434test # [ 0.279424] ledtrig-cpu: registered to indicate activity on CPUs435test # [ 0.279880] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:436second # [ 0.265762] Key type asymmetric registered437test # [ 0.279918] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000438second # [ 0.265793] Asymmetric key parser 'x509' registered439test # [ 0.279935] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000440second # [ 0.265882] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)441test # [ 0.279942] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000442test # [ 0.279981] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits443test # [ 0.280008] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]444test # [ 0.280090] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00445test # [ 0.280110] pci_bus 0000:00: root bus resource [bus 00-ff]446test # [ 0.280116] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]447test # [ 0.280120] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]448test # [ 0.280124] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]449second # [ 0.271860] io scheduler mq-deadline registered450second # [ 0.271882] io scheduler kyber registered451test # [ 0.280224] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint452test # [ 0.280672] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint453test # [ 0.280790] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]454test # [ 0.280801] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]455test # [ 0.280822] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]456test # [ 0.280835] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]457second # [ 0.275183] pl061_gpio 9030000.pl061: PL061 GPIO chip registered458test # [ 0.281125] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint459test # [ 0.281239] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]460test # [ 0.281251] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]461test # [ 0.281271] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]462test # [ 0.281549] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint463test # [ 0.281660] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]464test # [ 0.281670] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]465test # [ 0.281690] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]466second # [ 0.276165] ledtrig-cpu: registered to indicate activity on CPUs467test # [ 0.281977] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint468second # [ 0.276533] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:469test # [ 0.282087] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]470test # [ 0.282098] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]471second # [ 0.276558] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000472test # [ 0.282117] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473second # [ 0.276574] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000474test # [ 0.282411] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint475second # [ 0.276580] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000476test # [ 0.282523] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]477test # [ 0.282534] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]478second # [ 0.276615] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits479test # [ 0.282553] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]480second # [ 0.276639] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]481test # [ 0.282834] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint482second # [ 0.276741] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00483second # [ 0.276760] pci_bus 0000:00: root bus resource [bus 00-ff]484second # [ 0.276765] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]485second # [ 0.276769] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]486second # [ 0.276773] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]487second # [ 0.276862] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint488second # [ 0.277295] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint489second # [ 0.277411] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]490second # [ 0.277423] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]491second # [ 0.277453] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492second # [ 0.277465] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]493second # [ 0.277756] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint494second # [ 0.277873] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]495second # [ 0.277885] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]496second # [ 0.277905] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.278186] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint498test # [ 0.307032] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]499second # [ 0.278299] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]500test # [ 0.307059] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]501second # [ 0.278309] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]502test # [ 0.307080] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]503second # [ 0.278329] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]504test # [ 0.307468] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint505second # [ 0.278608] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint506test # [ 0.307585] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]507second # [ 0.278720] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]508test # [ 0.307596] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]509second # [ 0.278731] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]510test # [ 0.307616] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511second # [ 0.278751] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]512test # [ 0.307631] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]513second # [ 0.279037] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint514test # [ 0.307934] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint515second # [ 0.279149] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]516test # [ 0.308050] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]517second # [ 0.279161] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]518test # [ 0.308069] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519second # [ 0.279180] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]520test # [ 0.308348] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint521second # [ 0.279459] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint522test # [ 0.308463] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]523second # [ 0.279572] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]524test # [ 0.308482] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525second # [ 0.279582] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]526second # [ 0.279602] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527test # [ 0.308739] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint528test # [ 0.308850] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]529test # [ 0.309017] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint530test # [ 0.309242] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]531test # [ 0.309256] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]532test # [ 0.309277] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]533test # [ 0.309578] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint534test # [ 0.309693] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]535test # [ 0.309704] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]536test # [ 0.309725] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537test # [ 0.310151] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned538test # [ 0.310162] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned539test # [ 0.310167] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned540test # [ 0.310199] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned541test # [ 0.310231] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned542test # [ 0.310262] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned543test # [ 0.310292] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned544test # [ 0.310322] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned545test # [ 0.310354] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned546test # [ 0.310386] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned547test # [ 0.310417] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned548test # [ 0.310447] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned549test # [ 0.310508] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned550test # [ 0.310575] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned551test # [ 0.310590] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned552test # [ 0.310604] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned553test # [ 0.310619] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned554test # [ 0.310635] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned555test # [ 0.310651] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned556second # [ 0.312088] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint557test # [ 0.310666] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned558second # [ 0.312259] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]559test # [ 0.310682] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned560second # [ 0.312272] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]561test # [ 0.310698] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned562second # [ 0.312293] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]563test # [ 0.310713] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned564second # [ 0.312307] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]565test # [ 0.310730] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned566second # [ 0.312641] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint567test # [ 0.310746] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned568second # [ 0.312757] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]569test # [ 0.310761] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned570second # [ 0.312776] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]571test # [ 0.310777] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned572test # [ 0.310792] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned573second # [ 0.313058] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint574test # [ 0.310807] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned575second # [ 0.313171] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]576test # [ 0.310822] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned577second # [ 0.313191] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578second # [ 0.313451] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint579second # [ 0.313562] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]580second # [ 0.313723] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint581second # [ 0.313934] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]582second # [ 0.313948] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]583second # [ 0.313968] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]584second # [ 0.314334] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint585second # [ 0.314446] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]586second # [ 0.314457] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]587second # [ 0.314477] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]588second # [ 0.314887] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned589second # [ 0.314899] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned590second # [ 0.314904] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned591test # [ 0.346941] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned592second # [ 0.314937] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned593test # [ 0.347023] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned594second # [ 0.314969] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned595test # [ 0.347039] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned596second # [ 0.314999] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned597test # [ 0.347056] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned598test # [ 0.347085] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]599second # [ 0.315030] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned600test # [ 0.347092] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]601test # [ 0.347096] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]602test # [ 0.347815] pci 0000:00:0a.0: enabling device (0000 -> 0002)603second # [ 0.315061] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned604second # [ 0.315093] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned605second # [ 0.315125] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned606second # [ 0.315156] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned607second # [ 0.315188] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned608second # [ 0.315263] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned609second # [ 0.315295] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned610second # [ 0.315309] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned611second # [ 0.315324] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned612second # [ 0.315339] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned613second # [ 0.315354] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned614second # [ 0.315371] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned615second # [ 0.315387] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned616second # [ 0.315402] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned617second # [ 0.315417] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned618second # [ 0.315432] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned619second # [ 0.315449] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned620second # [ 0.315465] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned621second # [ 0.315482] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned622second # [ 0.315498] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned623second # [ 0.315514] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned624second # [ 0.315529] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned625second # [ 0.315544] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned626second # [ 0.315566] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned627second # [ 0.315581] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned628second # [ 0.315596] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned629second # [ 0.315611] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned630second # [ 0.315632] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]631second # [ 0.315638] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]632test # [ 0.365280] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)633second # [ 0.315642] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]634second # [ 0.316365] pci 0000:00:0a.0: enabling device (0000 -> 0002)635second # [ 0.355594] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)636test # [ 0.375113] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)637test # [ 0.378334] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)638second # [ 0.365831] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)639test # [ 0.388378] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)640second # [ 0.372329] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)641second # [ 0.374290] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)642test # [ 0.391435] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)643test # [ 0.393461] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)644test # [ 0.403381] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)645test # [ 0.405610] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)646test # [ 0.408447] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)647test # [ 0.410816] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)648second # [ 0.388262] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)649second # [ 0.390415] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)650second # [ 0.392362] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)651second # [ 0.394504] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)652test # [ 0.420255] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)653test # [ 0.428663] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled654second # [ 0.404310] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)655second # [ 0.406365] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)656second # [ 0.408932] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)657test # [ 0.430344] msm_serial: driver initialized658test # [ 0.430478] SuperH (H)SCI(F) driver initialized659test # [ 0.430529] STM32 USART driver initialized660second # [ 0.421225] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661second # [ 0.422925] msm_serial: driver initialized662second # [ 0.423061] SuperH (H)SCI(F) driver initialized663second # [ 0.423107] STM32 USART driver initialized664test # [ 0.458747] loop: module loaded665test # [ 0.458912] virtio_blk virtio5: 1/0/0 default/read/poll queues666test # [ 0.459510] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)667second # [ 0.450146] loop: module loaded668second # [ 0.450299] virtio_blk virtio5: 1/0/0 default/read/poll queues669second # [ 0.450953] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)670second # [ 0.456370] megasas: 07.734.00.00-rc1671second # [ 0.456922] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]672second # [ 0.468878] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000673test # [ 0.471388] megasas: 07.734.00.00-rc1674second # [ 0.468914] Intel/Sharp Extended Query Table at 0x0031675second # [ 0.470617] Using buffer write method676test # [ 0.472098] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]677second # [ 0.470667] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]678test # [ 0.489441] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000679test # [ 0.489516] Intel/Sharp Extended Query Table at 0x0031680second # [ 0.481351] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000681second # [ 0.481390] Intel/Sharp Extended Query Table at 0x0031682second # [ 0.483293] Using buffer write method683second # [ 0.483334] Concatenating MTD devices:684second # [ 0.483338] (0): "0.flash"685second # [ 0.483341] (1): "0.flash"686second # [ 0.483343] into device "0.flash"687test # [ 0.507488] Using buffer write method688test # [ 0.507548] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689test # [ 0.509332] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690test # [ 0.509359] Intel/Sharp Extended Query Table at 0x0031691test # [ 0.518899] Using buffer write method692test # [ 0.518938] Concatenating MTD devices:693test # [ 0.518941] (0): "0.flash"694test # [ 0.518944] (1): "0.flash"695test # [ 0.518947] into device "0.flash"696second # [ 0.716864] Freeing initrd memory: 25544K697test # [ 0.738766] Freeing initrd memory: 25544K698second # [ 0.724946] tun: Universal TUN/TAP device driver, 1.6699test # [ 0.746891] tun: Universal TUN/TAP device driver, 1.6700second # [ 0.727882] thunder_xcv, ver 1.0701second # [ 0.727917] thunder_bgx, ver 1.0702second # [ 0.727932] nicpf, ver 1.0703second # [ 0.728324] e1000: Intel(R) PRO/1000 Network Driver704second # [ 0.728333] e1000: Copyright (c) 1999-2006 Intel Corporation.705second # [ 0.728352] e1000e: Intel(R) PRO/1000 Network Driver706test # [ 0.749855] thunder_xcv, ver 1.0707second # [ 0.728358] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.708test # [ 0.749890] thunder_bgx, ver 1.0709test # [ 0.749903] nicpf, ver 1.0710second # [ 0.728373] igb: Intel(R) Gigabit Ethernet Network Driver711test # [ 0.750278] e1000: Intel(R) PRO/1000 Network Driver712second # [ 0.728376] igb: Copyright (c) 2007-2014 Intel Corporation.713test # [ 0.750288] e1000: Copyright (c) 1999-2006 Intel Corporation.714second # [ 0.728387] igbvf: Intel(R) Gigabit Virtual Function Network Driver715test # [ 0.750310] e1000e: Intel(R) PRO/1000 Network Driver716second # [ 0.728390] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.717test # [ 0.750315] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.718second # [ 0.728565] sky2: driver version 1.30719test # [ 0.750331] igb: Intel(R) Gigabit Ethernet Network Driver720second # [ 0.729470] usbcore: registered new interface driver usb-storage721test # [ 0.750334] igb: Copyright (c) 2007-2014 Intel Corporation.722second # [ 0.729593] usbcore: registered new interface driver usbserial_generic723test # [ 0.750347] igbvf: Intel(R) Gigabit Virtual Function Network Driver724second # [ 0.729602] usbserial: USB Serial support registered for generic725test # [ 0.750352] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.726second # [ 0.729927] hv_vmbus: registering driver hyperv_keyboard727test # [ 0.750427] sky2: driver version 1.30728second # [ 0.738008] ehci-pci 0000:00:0a.0: EHCI Host Controller729second # [ 0.738065] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1730second # [ 0.738247] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000731test # [ 0.756445] usbcore: registered new interface driver usb-storage732test # [ 0.756595] usbcore: registered new interface driver usbserial_generic733second # [ 0.740077] rtc-pl031 9010000.pl031: registered as rtc0734test # [ 0.756606] usbserial: USB Serial support registered for generic735second # [ 0.740118] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T15:05:28 UTC (1786374328)736test # [ 0.756967] hv_vmbus: registering driver hyperv_keyboard737second # [ 0.740354] i2c_dev: i2c /dev entries driver738test # [ 0.758033] ehci-pci 0000:00:0a.0: EHCI Host Controller739test # [ 0.758074] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1740test # [ 0.758281] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000741second # [ 0.743490] sdhci: Secure Digital Host Controller Interface driver742second # [ 0.743513] sdhci: Copyright(c) Pierre Ossman743second # [ 0.743668] Synopsys Designware Multimedia Card Interface Driver744test # [ 0.762126] rtc-pl031 9010000.pl031: registered as rtc0745test # [ 0.762174] rtc-pl031 9010000.pl031: setting system clock to 2026-08-10T15:05:28 UTC (1786374328)746test # [ 0.762449] i2c_dev: i2c /dev entries driver747test # [ 0.765633] sdhci: Secure Digital Host Controller Interface driver748test # [ 0.765660] sdhci: Copyright(c) Pierre Ossman749test # [ 0.765831] Synopsys Designware Multimedia Card Interface Driver750test # [ 0.766036] sdhci-pltfm: SDHCI platform and OF driver helper751test # [ 0.768812] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00752test # [ 0.769781] hub 1-0:1.0: USB hub found753test # [ 0.770158] hub 1-0:1.0: 6 ports detected754second # [ 0.745328] sdhci-pltfm: SDHCI platform and OF driver helper755test # [ 0.771196] hid: raw HID events driver (C) Jiri Kosina756second # [ 0.746540] hid: raw HID events driver (C) Jiri Kosina757test # [ 0.771362] usbcore: registered new interface driver usbhid758test # [ 0.771368] usbhid: USB HID core driver759second # [ 0.746701] usbcore: registered new interface driver usbhid760second # [ 0.746706] usbhid: USB HID core driver761test # [ 0.837205] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available762second # [ 0.819270] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00763test # [ 0.838814] drop_monitor: Initializing network drop monitor service764second # [ 0.819520] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available765test # [ 0.838997] NET: Registered PF_INET6 protocol family766second # [ 0.820480] drop_monitor: Initializing network drop monitor service767second # [ 0.820680] NET: Registered PF_INET6 protocol family768test # [ 0.841618] Segment Routing with IPv6769second # [ 0.821214] hub 1-0:1.0: USB hub found770test # [ 0.841652] In-situ OAM (IOAM) with IPv6771second # [ 0.821262] hub 1-0:1.0: 6 ports detected772test # [ 0.841709] NET: Registered PF_PACKET protocol family773second # [ 0.825056] Segment Routing with IPv6774second # [ 0.825087] In-situ OAM (IOAM) with IPv6775second # [ 0.825143] NET: Registered PF_PACKET protocol family776second # [ 0.826343] 9pnet: Installing 9P2000 support777test # [ 0.842925] 9pnet: Installing 9P2000 support778test # [ 0.844655] Key type dns_resolver registered779second # [ 0.828389] Key type dns_resolver registered780test # [ 0.848450] registered taskstats version 1781test # [ 0.848626] Loading compiled-in X.509 certificates782second # [ 0.831826] registered taskstats version 1783second # [ 0.832019] Loading compiled-in X.509 certificates784test # [ 0.854288] Demotion targets for Node 0: null785test # [ 0.854389] Key type .fscrypt registered786test # [ 0.854394] Key type fscrypt-provisioning registered787test # [ 0.854516] ima: No TPM chip found, activating TPM-bypass!788test # [ 0.854530] ima: Allocated hash algorithm: sha1789second # [ 0.837876] Demotion targets for Node 0: null790test # [ 0.854585] ima: No architecture policies found791second # [ 0.837978] Key type .fscrypt registered792second # [ 0.837984] Key type fscrypt-provisioning registered793second # [ 0.838106] ima: No TPM chip found, activating TPM-bypass!794second # [ 0.838126] ima: Allocated hash algorithm: sha1795test # [ 0.857996] input: gpio-keys as /devices/platform/gpio-keys/input/input0796second # [ 0.838185] ima: No architecture policies found797second # [ 0.841172] input: gpio-keys as /devices/platform/gpio-keys/input/input0798test # [ 0.869106] clk: Disabling unused clocks799test # [ 0.869137] PM: genpd: Disabling unused power domains800second # [ 0.852929] clk: Disabling unused clocks801test # [ 0.872705] Freeing unused kernel memory: 4736K802second # [ 0.852955] PM: genpd: Disabling unused power domains803test # [ 0.872810] Run /init as init process804second # [ 0.856574] Freeing unused kernel memory: 4736K805second # [ 0.856703] Run /init as init process806second # [ 0.878472] systemd[1]: Successfully made /usr/ read-only.807test # [ 0.897999] systemd[1]: Successfully made /usr/ read-only.808test # [ 1.062870] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 1.067838] usb 1-1: new high-speed USB device number 2 using ehci-pci810test # [ 1.212858] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1811second # [ 1.213365] systemd[1]: systemd 260.2 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)812second # [ 1.218076] systemd[1]: Detected virtualization qemu.813second # [ 1.218786] systemd[1]: Detected architecture arm64.814second # [ 1.219634] systemd[1]: Running in initrd.815test # [ 1.232515] systemd[1]: systemd 260.2 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)816second # [ 1.220956] systemd[1]: Initializing machine ID from random generator.817test # [ 1.239766] systemd[1]: Detected virtualization qemu.818second # [ 1.222335] systemd[1]: Hostname set to <second>.819test # [ 1.240970] systemd[1]: Detected architecture arm64.820test # [ 1.242259] systemd[1]: Running in initrd.821test # [ 1.244375] systemd[1]: Initializing machine ID from random generator.822second # [ 1.226430] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1823test # [ 1.246283] systemd[1]: Hostname set to <test>.824test # [ 1.299097] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825second # [ 1.307493] systemd[1]: Queued start job for default target Initrd Default Target.826second # [ 1.312020] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0827second # [ 1.317302] systemd[1]: Created slice Slice /system/modprobe.828second # [ 1.318120] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.829second # [ 1.318922] systemd[1]: Expecting device /dev/disk/by-label/nixos...830second # [ 1.319616] systemd[1]: Reached target Path Units.831second # [ 1.319645] systemd[1]: Reached target Slice Units.832second # [ 1.319664] systemd[1]: Reached target Swaps.833second # [ 1.319683] systemd[1]: Reached target Timer Units.834second # [ 1.319868] systemd[1]: Listening on D-Bus System Message Bus Socket.835second # [ 1.320025] systemd[1]: Listening on Journal Socket (/dev/log).836second # [ 1.320155] systemd[1]: Listening on Journal Sockets.837second # [ 1.320263] systemd[1]: Listening on udev Control Socket.838second # [ 1.320363] systemd[1]: Listening on udev Kernel Socket.839second # [ 1.320389] systemd[1]: Reached target Socket Units.840second # [ 1.326404] systemd[1]: Starting Create List of Static Device Nodes...841second # [ 1.331897] systemd[1]: Starting Load Kernel Module 9pnet_virtio...842second # [ 1.332572] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs843test # [ 1.352484] systemd[1]: Queued start job for default target Initrd Default Target.844second # [ 1.340357] systemd[1]: Mounting Kernel Configuration File System...845test # [ 1.360337] systemd[1]: Created slice Slice /system/modprobe.846test # [ 1.361096] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.847test # [ 1.361912] systemd[1]: Expecting device /dev/disk/by-label/nixos...848test # [ 1.362519] systemd[1]: Reached target Path Units.849test # [ 1.363164] systemd[1]: Reached target Slice Units.850test # [ 1.363738] systemd[1]: Reached target Swaps.851test # [ 1.364169] systemd[1]: Reached target Timer Units.852test # [ 1.364835] systemd[1]: Listening on D-Bus System Message Bus Socket.853test # [ 1.365641] systemd[1]: Listening on Journal Socket (/dev/log).854second # [ 1.348052] systemd[1]: Starting Journal Service...855test # [ 1.366361] systemd[1]: Listening on Journal Sockets.856test # [ 1.366989] systemd[1]: Listening on udev Control Socket.857test # [ 1.367620] systemd[1]: Listening on udev Kernel Socket.858test # [ 1.368163] systemd[1]: Reached target Socket Units.859test # [ 1.369845] systemd[1]: Starting Create List of Static Device Nodes...860test # [ 1.374585] systemd[1]: Starting Load Kernel Module 9pnet_virtio...861test # [ 1.375311] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs862test # [ 1.383027] systemd[1]: Mounting Kernel Configuration File System...863second # [ 1.368670] systemd[1]: Starting Load Kernel Modules...864second # [ 1.369290] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki865test # [ 1.391101] systemd[1]: Starting Journal Service...866second # [ 1.377092] systemd[1]: Starting Coldplug All udev Devices...867second # [ 1.385500] systemd[1]: Finished Create List of Static Device Nodes.868second # [ 1.386426] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.869second # [ 1.386616] systemd[1]: Finished Load Kernel Module 9pnet_virtio.870second # [ 1.386833] systemd[1]: Mounted Kernel Configuration File System.871second # [ 1.391351] systemd-journald[73]: Collecting audit messages is disabled.872test # [ 1.415554] systemd[1]: Starting Load Kernel Modules...873test # [ 1.415655] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki874test # [ 1.419050] usb 1-2: new high-speed USB device number 3 using ehci-pci875second # [ 1.404462] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...876test # [ 1.427202] systemd[1]: Starting Coldplug All udev Devices...877test # [ 1.434999] systemd[1]: Finished Create List of Static Device Nodes.878test # [ 1.436941] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.879test # [ 1.439344] systemd[1]: Finished Load Kernel Module 9pnet_virtio.880test # [ 1.440578] systemd-journald[73]: Collecting audit messages is disabled.881test # [ 1.441458] systemd[1]: Mounted Kernel Configuration File System.882second # [ 1.424517] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883second # [ 1.431896] usb 1-2: new high-speed USB device number 3 using ehci-pci884second # [ 1.432326] systemd[1]: Starting Create Static Device Nodes in /dev...885test # [ 1.455513] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...886second # [ 1.454653] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.887test # [ 1.483685] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.888test # [ 1.487225] systemd[1]: Starting Create Static Device Nodes in /dev...889second # [ 1.472174] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev890second # [ 1.468809] systemd-modules-load[75]: Inserted module 'dm_mod'891second # [ 1.477379] systemd[1]: Started Journal Service.892second # [ 1.472338] systemd-modules-load[75]: Module 'virtio_balloon' is built in893second # [ 1.475515] systemd-modules-load[75]: Module 'virtio_console' is built in894test # [ 1.502075] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.895second # [ 1.485975] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0896second # [ 1.486212] [drm] features: -virgl +edid -resource_blob -host_visible897second # [ 1.486218] [drm] features: -context_init898second # [ 1.486712] [drm] number of scanouts: 1899second # [ 1.486727] [drm] number of cap sets: 0900second # [ 1.485925] systemd[1]: Finished Create Static Device Nodes in /dev.901second # [ 1.486715] systemd[1]: Reached target Preparation for Local File Systems.902second # [ 1.487432] systemd[1]: Reached target Local File Systems.903second # [ 1.501754] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic904second # [ 1.501777] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0905second # [ 1.497626] systemd[1]: Starting Create System Files and Directories...906test # [ 1.518971] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev907test # [ 1.523931] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0908test # [ 1.524150] [drm] features: -virgl +edid -resource_blob -host_visible909test # [ 1.524155] [drm] features: -context_init910test # [ 1.524641] [drm] number of scanouts: 1911test # [ 1.524657] [drm] number of cap sets: 0912second # [ 1.512879] systemd[1]: Starting Rule-based Manager for Device Events and Files...913test # [ 1.540346] systemd[1]: Started Journal Service.914test # [ 1.531877] systemd-modules-load[74]: Inserted module 'dm_mod'915test # [ 1.532847] systemd-modules-load[74]: Module 'virtio_balloon' is built in916second # [ 1.521792] Console: switching to colour frame buffer device 160x50917test # [ 1.547243] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic918test # [ 1.547265] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0919second # [ 1.535193] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device920test # [ 1.544408] systemd-modules-load[74]: Module 'virtio_console' is built in921test # [ 1.548593] systemd[1]: Finished Create Static Device Nodes in /dev.922test # [ 1.549491] systemd[1]: Reached target Preparation for Local File Systems.923test # [ 1.550228] systemd[1]: Reached target Local File Systems.924second # [ 1.535502] systemd-modules-load[75]: Inserted module 'virtio_gpu'925second # [ 1.536328] systemd-modules-load[75]: Module 'virtio_rng' is built in926test # [ 1.557326] systemd[1]: Starting Create System Files and Directories...927test # [ 1.561175] systemd[1]: Starting Rule-based Manager for Device Events and Files...928second # [ 1.547890] systemd[1]: Finished Load Kernel Modules.929test # [ 1.573034] Console: switching to colour frame buffer device 160x50930test # [ 1.585489] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2931test # [ 1.585609] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0932second # [ 1.564894] systemd[1]: Starting Apply Kernel Variables...933test # [ 1.592892] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device934second # [ 1.568804] systemd[1]: Finished Create System Files and Directories.935test # [ 1.589114] systemd-modules-load[74]: Inserted module 'virtio_gpu'936test # [ 1.590192] systemd-modules-load[74]: Module 'virtio_rng' is built in937second # [ 1.583278] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2938second # [ 1.585382] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0939test # [ 1.606025] systemd[1]: Finished Load Kernel Modules.940second # [ 1.595029] systemd[1]: Finished Apply Kernel Variables.941second # [ 1.603108] systemd-udevd[83]: Using default interface naming scheme 'v260'.942test # [ 1.624260] systemd[1]: Starting Apply Kernel Variables...943test # [ 1.625416] systemd[1]: Finished Create System Files and Directories.944second # [ 1.625089] systemd[1]: Started Rule-based Manager for Device Events and Files.945test # [ 1.651103] systemd[1]: Finished Apply Kernel Variables.946test # [ 1.657533] systemd-udevd[81]: Using default interface naming scheme 'v260'.947test # [ 1.678534] systemd[1]: Started Rule-based Manager for Device Events and Files.948second # [ 1.687166] systemd[1]: Starting Virtual Console Setup...949test # [ 1.749141] systemd[1]: Starting Virtual Console Setup...950second # [ 1.742159] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951second # [ 1.745091] systemd[1]: Finished Virtual Console Setup.952test # [ 1.818013] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.953test # [ 1.819110] systemd[1]: Finished Virtual Console Setup.954second # [ 2.021632] systemd[1]: Finished Coldplug All udev Devices.955second # [ 2.022238] systemd[1]: Reached target System Initialization.956second # [ 2.024171] systemd[1]: Reached target Basic System.957test # [ 2.077179] systemd[1]: Finished Coldplug All udev Devices.958test # [ 2.077770] systemd[1]: Reached target System Initialization.959test # [ 2.078269] systemd[1]: Reached target Basic System.960second # [ 2.122942] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.961second # [ 2.150820] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.962test # [ 2.183368] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.963test # [ 2.209387] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.964second # [ 2.200390] systemd[1]: Found device /dev/disk/by-label/nixos.965second # [ 2.201760] systemd[1]: Reached target Initrd Root Device.966second # [ 2.204191] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...967test # [ 2.260555] systemd[1]: Found device /dev/disk/by-label/nixos.968test # [ 2.261940] systemd[1]: Reached target Initrd Root Device.969test # [ 2.264114] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...970second # [ 2.254087] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks971second # [ 2.268911] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972test # [ 2.314456] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks973test # [ 2.322329] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974second # [ 2.372342] systemd[1]: Mounting /sysroot...975second # [ 2.403439] EXT4-fs (vda): mounted filesystem f872a561-5625-4c36-b824-b8c12c9e6a9b r/w with ordered data mode. Quota mode: none.976second # [ 2.401426] systemd[1]: Mounted /sysroot.977second # [ 2.402827] systemd[1]: Reached target Initrd Root File System.978second # [ 2.406951] systemd[1]: Starting Mountpoints Configured in the Real Root...979test # [ 2.423824] systemd[1]: Mounting /sysroot...980second # [ 2.431494] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.981second # [ 2.436587] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...982test # [ 2.461842] EXT4-fs (vda): mounted filesystem b463b6ff-c664-4f93-b9e6-f8c293004dec r/w with ordered data mode. Quota mode: none.983test # [ 2.454798] systemd[1]: Mounted /sysroot.984test # [ 2.455766] systemd[1]: Reached target Initrd Root File System.985second # [ 2.439985] systemd[1]: Reloading...986test # [ 2.458660] systemd[1]: Starting Mountpoints Configured in the Real Root...987test # [ 2.480270] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.988test # [ 2.485607] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...989test # [ 2.489354] systemd[1]: Reloading...990second # [ 2.628147] systemd[1]: Reloading finished in 189 ms.991second # [ 2.637437] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...992second # [ 2.638323] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...993second # [ 2.640238] systemd[1]: Starting Load Kernel Module 9pnet_virtio...994second # [ 2.648986] systemd[1]: initrd-parse-etc.service: Deactivated successfully.995second # [ 2.650293] systemd[1]: Finished Mountpoints Configured in the Real Root.996second # [ 2.651398] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.997second # [ 2.662961] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.998second # [ 2.663673] systemd[1]: Finished Load Kernel Module 9pnet_virtio.999test # [ 2.695430] systemd[1]: Reloading finished in 208 ms.1000test # [ 2.704120] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...1001test # [ 2.706807] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...1002test # [ 2.712386] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1003test # [ 2.719570] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004test # [ 2.722669] systemd[1]: Finished Mountpoints Configured in the Real Root.1005test # [ 2.723325] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1006test # [ 2.740496] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1007test # [ 2.741514] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1008second # [ 3.083103] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/mtd_probe": No such file or directory1009second # [ 3.086976] (udev-worker)[102]: mtd0ro: /nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1010second # [ 3.106316] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1011second # [ 3.107036] systemd[1]: Stopped Virtual Console Setup.1012second # [ 3.111020] systemd[1]: Stopping Virtual Console Setup...1013second # [ 3.111555] systemd[1]: Starting Virtual Console Setup...1014second # [ 3.125434] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1015second # [ 3.126157] systemd[1]: Stopped Virtual Console Setup.1016second # [ 3.128181] systemd[1]: Starting Virtual Console Setup...1017second # [ 3.142572] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.1018test # [ 3.158897] (udev-worker)[93]: mtd0ro: Failed to find and pin callout binary "/nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/mtd_probe": No such file or directory1019second # [ 3.144814] systemd[1]: Finished Virtual Console Setup.1020test # [ 3.162300] (udev-worker)[93]: mtd0ro: /nix/store/3lx91pbcy1yj4i9lqxfa2s4a7v92wg0h-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1021test # [ 3.181282] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1022test # [ 3.183912] systemd[1]: Stopped Virtual Console Setup.1023test # [ 3.184612] systemd[1]: Stopping Virtual Console Setup...1024test # [ 3.188090] systemd[1]: Starting Virtual Console Setup...1025test # [ 3.205693] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1026test # [ 3.206606] systemd[1]: Stopped Virtual Console Setup.1027test # [ 3.208202] systemd[1]: Starting Virtual Console Setup...1028test # [ 3.225431] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.1029test # [ 3.227904] systemd[1]: Finished Virtual Console Setup.1030second # [ 3.372949] systemd[1]: Mounting /sysroot/nix/.ro-store...1031second # [ 3.380260] systemd[1]: Mounting /sysroot/nix/.rw-store...1032second # [ 3.388296] systemd[1]: Mounting /sysroot/run...1033second # [ 3.393760] systemd[1]: Mounting /sysroot/tmp/shared...1034test # [ 3.420187] systemd[1]: Mounting /sysroot/nix/.ro-store...1035test # [ 3.428092] systemd[1]: Mounting /sysroot/nix/.rw-store...1036test # [ 3.432202] systemd[1]: Mounting /sysroot/run...1037second # [ 3.419649] systemd[1]: Mounting /sysroot/tmp/xchg...1038test # [ 3.437253] systemd[1]: Mounting /sysroot/tmp/shared...1039second # [ 3.423465] systemd[1]: Mounted /sysroot/run.1040second # [ 3.432105] systemd[1]: Mounted /sysroot/nix/.ro-store.1041second # [ 3.433569] systemd[1]: Mounted /sysroot/nix/.rw-store.1042second # [ 3.434437] systemd[1]: Mounted /sysroot/tmp/shared.1043second # [ 3.443236] systemd[1]: Starting rw-sysroot-nix-store.service...1044test # [ 3.460555] systemd[1]: Mounting /sysroot/tmp/xchg...1045test # [ 3.462287] systemd[1]: Mounted /sysroot/nix/.rw-store.1046second # [ 3.448250] systemd[1]: Mounted /sysroot/tmp/xchg.1047test # [ 3.470165] systemd[1]: Starting rw-sysroot-nix-store.service...1048test # [ 3.475404] systemd[1]: Mounted /sysroot/nix/.ro-store.1049second # [ 3.462878] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1050test # [ 3.478697] systemd[1]: Mounted /sysroot/run.1051second # [ 3.464415] systemd[1]: Finished rw-sysroot-nix-store.service.1052test # [ 3.480073] systemd[1]: Mounted /sysroot/tmp/shared.1053test # [ 3.494845] systemd[1]: Mounted /sysroot/tmp/xchg.1054test # [ 3.495849] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1055test # [ 3.497591] systemd[1]: Finished rw-sysroot-nix-store.service.1056second # [ 4.372370] systemd[1]: Mounting /sysroot/nix/store...1057test # [ 4.419238] systemd[1]: Mounting /sysroot/nix/store...1058second # [ 4.416907] systemd[1]: Mounted /sysroot/nix/store.1059second # [ 4.419098] systemd[1]: Reached target Initrd File Systems.1060second # [ 4.423507] systemd[1]: Starting Find NixOS closure...1061second # [ 4.432311] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062test # [ 4.450520] systemd[1]: Mounted /sysroot/nix/store.1063test # [ 4.451290] systemd[1]: Reached target Initrd File Systems.1064test # [ 4.452439] systemd[1]: Starting Find NixOS closure...1065test # [ 4.460360] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1066second # [ 4.462100] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1067second # [ 4.463145] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1068second # [ 4.477819] systemd[1]: Finished Find NixOS closure.1069test # [ 4.494203] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1070second # [ 4.480279] systemd[1]: Reached target Initrd Default Target.1071second # [ 4.482163] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1072test # [ 4.499338] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1073test # [ 4.512711] systemd[1]: Finished Find NixOS closure.1074test # [ 4.514745] systemd[1]: Reached target Initrd Default Target.1075test # [ 4.515907] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1076second # [ 4.509883] systemd[1]: Stopped target Initrd Default Target.1077second # [ 4.511151] systemd[1]: Stopped target Basic System.1078second # [ 4.513153] systemd[1]: Stopped target Initrd Root Device.1079second # [ 4.516305] systemd[1]: Stopped target Path Units.1080second # [ 4.516963] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1081second # [ 4.517916] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1082second # [ 4.518798] systemd[1]: Stopped target Slice Units.1083second # [ 4.519371] systemd[1]: Stopped target Socket Units.1084second # [ 4.519963] systemd[1]: Stopped target System Initialization.1085second # [ 4.524200] systemd[1]: Stopped target Swaps.1086test # [ 4.542488] systemd[1]: Stopped target Initrd Default Target.1087second # [ 4.528111] systemd[1]: Stopped target Timer Units.1088test # [ 4.543678] systemd[1]: Stopped target Basic System.1089second # [ 4.528719] systemd[1]: dbus.socket: Deactivated successfully.1090second # [ 4.529356] systemd[1]: Closed D-Bus System Message Bus Socket.1091second # [ 4.530048] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1092test # [ 4.545021] systemd[1]: Stopped target Initrd Root Device.1093second # [ 4.530834] systemd[1]: Stopped Find NixOS closure.1094second # [ 4.531353] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1095test # [ 4.548341] systemd[1]: Stopped target Path Units.1096test # [ 4.549079] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1097second # [ 4.535970] systemd[1]: Starting rw-sysroot-nix-store.service...1098test # [ 4.551501] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1099second # [ 4.536724] systemd[1]: systemd-sysctl.service: Deactivated successfully.1100second # [ 4.537407] systemd[1]: Stopped Apply Kernel Variables.1101test # [ 4.552735] systemd[1]: Stopped target Slice Units.1102second # [ 4.538115] systemd[1]: systemd-modules-load.service: Deactivated successfully.1103second # [ 4.539034] systemd[1]: Stopped Load Kernel Modules.1104test # [ 4.554827] systemd[1]: Stopped target Socket Units.1105second # [ 4.540360] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1106test # [ 4.556129] systemd[1]: Stopped target System Initialization.1107second # [ 4.543580] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1108test # [ 4.560127] systemd[1]: Stopped target Swaps.1109test # [ 4.560672] systemd[1]: Stopped target Timer Units.1110test # [ 4.561308] systemd[1]: dbus.socket: Deactivated successfully.1111test # [ 4.561919] systemd[1]: Closed D-Bus System Message Bus Socket.1112test # [ 4.562524] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1113test # [ 4.563296] systemd[1]: Stopped Find NixOS closure.1114test # [ 4.563813] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1115second # [ 4.548826] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1116second # [ 4.549552] systemd[1]: Stopped Create System Files and Directories.1117second # [ 4.550181] systemd[1]: Stopped target Local File Systems.1118second # [ 4.550674] systemd[1]: Stopped target Preparation for Local File Systems.1119second # [ 4.551273] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1120second # [ 4.551883] systemd[1]: Stopped Coldplug All udev Devices.1121test # [ 4.568342] systemd[1]: Starting rw-sysroot-nix-store.service...1122test # [ 4.569187] systemd[1]: systemd-sysctl.service: Deactivated successfully.1123test # [ 4.570236] systemd[1]: Stopped Apply Kernel Variables.1124test # [ 4.571547] systemd[1]: systemd-modules-load.service: Deactivated successfully.1125second # [ 4.558157] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1126second # [ 4.559318] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1127test # [ 4.574737] systemd[1]: Stopped Load Kernel Modules.1128second # [ 4.559987] systemd[1]: Stopped Virtual Console Setup.1129test # [ 4.575942] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1130second # [ 4.561709] systemd[1]: initrd-cleanup.service: Deactivated successfully.1131test # [ 4.582137] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1132test # [ 4.582850] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1133test # [ 4.583462] systemd[1]: Stopped Create System Files and Directories.1134second # [ 4.569992] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1135second # [ 4.570634] systemd[1]: systemd-udevd.service: Deactivated successfully.1136second # [ 4.571161] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1137second # [ 4.571727] systemd[1]: systemd-udevd.service: Consumed 1.012s CPU time over 3.056s wall clock time, 22.6M memory peak.1138test # [ 4.583987] systemd[1]: Stopped target Local File Systems.1139test # [ 4.588183] systemd[1]: Stopped target Preparation for Local File Systems.1140test # [ 4.589338] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1141test # [ 4.590854] systemd[1]: Stopped Coldplug All udev Devices.1142second # [ 4.575830] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1143test # [ 4.591438] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1144second # [ 4.577258] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1145test # [ 4.593353] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1146test # [ 4.594039] systemd[1]: Stopped Virtual Console Setup.1147test # [ 4.594496] systemd[1]: initrd-cleanup.service: Deactivated successfully.1148second # [ 4.579376] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1149second # [ 4.580581] systemd[1]: Finished rw-sysroot-nix-store.service.1150second # [ 4.581896] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1151second # [ 4.583294] systemd[1]: Closed udev Control Socket.1152second # [ 4.584227] systemd[1]: Starting Cleanup udev Database...1153test # [ 4.600092] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1154second # [ 4.585198] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1155test # [ 4.600838] systemd[1]: systemd-udevd.service: Deactivated successfully.1156second # [ 4.588169] systemd[1]: Stopped Create Static Device Nodes in /dev.1157second # [ 4.588743] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1158second # [ 4.589412] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1159test # [ 4.605548] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1160second # [ 4.591437] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1161second # [ 4.592155] systemd[1]: Stopped Create List of Static Device Nodes.1162test # [ 4.607305] systemd[1]: systemd-udevd.service: Consumed 1.023s CPU time over 3.043s wall clock time, 22.8M memory peak.1163test # [ 4.610645] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1164test # [ 4.611348] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1165test # [ 4.613196] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1166test # [ 4.615041] systemd[1]: Finished rw-sysroot-nix-store.service.1167test # [ 4.615589] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1168test # [ 4.617006] systemd[1]: Closed udev Control Socket.1169test # [ 4.620324] systemd[1]: Starting Cleanup udev Database...1170test # [ 4.621378] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1171test # [ 4.622030] systemd[1]: Stopped Create Static Device Nodes in /dev.1172test # [ 4.622553] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1173test # [ 4.624277] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1174test # [ 4.626046] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1175test # [ 4.626769] systemd[1]: Stopped Create List of Static Device Nodes.1176second # [ 4.619051] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1177second # [ 4.624210] systemd[1]: Finished Cleanup udev Database.1178second # [ 4.624747] systemd[1]: Reached target Switch Root.1179second # [ 4.625166] systemd[1]: Starting NixOS Activation...1180test # [ 4.650083] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1181test # [ 4.651847] systemd[1]: Finished Cleanup udev Database.1182test # [ 4.653315] systemd[1]: Reached target Switch Root.1183test # [ 4.654161] systemd[1]: Starting NixOS Activation...1184second # [ 4.783644] initrd-nixos-activation-start[181]: booting system configuration /nix/store/k9zwrlaml4d69mrzm8vpvvll1hmwm4y7-nixos-system-second-test1185test # [ 4.835093] initrd-nixos-activation-start[182]: booting system configuration /nix/store/b6nxbs0rjlfiq2hy90qarypf3gl02bhm-nixos-system-test-test1186second # [ 4.855662] initrd-nixos-activation-start[181]: running activation script...1187test # [ 4.900421] initrd-nixos-activation-start[182]: running activation script...1188second # [ 5.280397] initrd-nixos-activation-start[204]: setting up /etc...1189test # [ 5.340614] initrd-nixos-activation-start[205]: setting up /etc...1190second # [ 5.515957] initrd-nixos-activation-start[204]: setting up age secrets...1191test # [ 5.601875] initrd-nixos-activation-start[205]: setting up age secrets...1192second # [ 5.715822] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1193second # [ 5.717867] systemd[1]: Finished NixOS Activation.1194second # [ 5.718628] systemd[1]: Starting Switch Root...1195second # [ 5.738089] systemd[1]: Switching root.1196second # [ 5.793494] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1197test # [ 5.907039] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1198test # [ 5.908106] systemd[1]: Finished NixOS Activation.1199test # [ 5.909724] systemd[1]: Starting Switch Root...1200test # [ 5.925427] systemd[1]: Switching root.1201test # [ 5.984050] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1202second # [ 6.509579] systemd[1]: systemd 260.2 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)1203second # [ 6.516704] systemd[1]: Detected virtualization qemu.1204second # [ 6.518466] systemd[1]: Detected architecture arm64.1205second # [ 6.520805] systemd[1]: Detected first boot.1206second # [ 6.526525] systemd[1]: Initializing machine ID from random generator.1207test # [ 6.566941] systemd[1]: systemd 260.2 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)1208test # [ 6.570568] systemd[1]: Detected virtualization qemu.1209test # [ 6.571896] systemd[1]: Detected architecture arm64.1210test # [ 6.573674] systemd[1]: Detected first boot.1211test # [ 6.577956] systemd[1]: Initializing machine ID from random generator.1212second # [ 6.812201] systemd[1]: bpf-restrict-fs: LSM BPF program attached1213test # [ 6.843463] systemd[1]: bpf-restrict-fs: LSM BPF program attached1214second # [ 6.993599] systemd[1]: Applying preset policy.1215test # [ 7.027671] systemd[1]: Applying preset policy.1216second # [ 7.440909] systemd[1]: Populated /etc with preset unit settings.1217test # [ 7.486449] systemd[1]: Populated /etc with preset unit settings.1218test # [ 8.161065] systemd[1]: initrd-switch-root.service: Deactivated successfully.1219test # [ 8.162076] systemd[1]: Stopped initrd-switch-root.service.1220test # [ 8.164885] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1221test # [ 8.166783] systemd[1]: Created slice Slice /system/getty.1222test # [ 8.168806] systemd[1]: Created slice User and Session Slice.1223test # [ 8.169572] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1224test # [ 8.172045] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1225test # [ 8.173138] systemd[1]: Expecting device /dev/hvc0...1226test # [ 8.174150] systemd[1]: Expecting device /dev/ttyAMA0...1227test # [ 8.175393] systemd[1]: Reached target Local Encrypted Volumes.1228test # [ 8.176628] systemd[1]: Stopped target initrd-fs.target.1229test # [ 8.177690] systemd[1]: Stopped target initrd-root-fs.target.1230test # [ 8.178778] systemd[1]: Stopped target initrd-switch-root.target.1231test # [ 8.180448] systemd[1]: Reached target Virtual Machines and Containers.1232test # [ 8.182037] systemd[1]: Reached target Path Units.1233test # [ 8.183724] systemd[1]: Reached target Remote File Systems.1234test # [ 8.184817] systemd[1]: Reached target Slice Units.1235test # [ 8.186203] systemd[1]: Reached target Swaps.1236second # [ 8.172592] systemd[1]: initrd-switch-root.service: Deactivated successfully.1237second # [ 8.173761] systemd[1]: Stopped initrd-switch-root.service.1238test # [ 8.191320] systemd[1]: Listening on Process Core Dump Socket.1239second # [ 8.176985] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1240second # [ 8.179961] systemd[1]: Created slice Slice /system/getty.1241second # [ 8.180520] systemd[1]: Created slice User and Session Slice.1242second # [ 8.180644] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1243second # [ 8.180726] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1244second # [ 8.180796] systemd[1]: Expecting device /dev/hvc0...1245second # [ 8.180817] systemd[1]: Expecting device /dev/ttyAMA0...1246second # [ 8.180850] systemd[1]: Reached target Local Encrypted Volumes.1247second # [ 8.181375] systemd[1]: Stopped target initrd-fs.target.1248second # [ 8.181768] systemd[1]: Stopped target initrd-root-fs.target.1249second # [ 8.182091] systemd[1]: Stopped target initrd-switch-root.target.1250second # [ 8.182434] systemd[1]: Reached target Virtual Machines and Containers.1251second # [ 8.182746] systemd[1]: Reached target Path Units.1252second # [ 8.183037] systemd[1]: Reached target Remote File Systems.1253second # [ 8.183322] systemd[1]: Reached target Slice Units.1254second # [ 8.183613] systemd[1]: Reached target Swaps.1255test # [ 8.212318] systemd[1]: Listening on Credential Encryption/Decryption.1256second # [ 8.208661] systemd[1]: Listening on Process Core Dump Socket.1257test # [ 8.229252] systemd[1]: Starting Journal Log Access Socket...1258test # [ 8.231519] systemd[1]: Listening on Journal Audit Socket.1259test # [ 8.233104] systemd[1]: Listening on Network Management Metrics Varlink Socket.1260test # [ 8.235741] systemd[1]: Listening on Network Management Netlink Socket.1261test # [ 8.238250] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1262second # [ 8.222870] systemd[1]: Listening on Credential Encryption/Decryption.1263test # [ 8.240439] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1264test # [ 8.244171] systemd[1]: Listening on udev Control Socket.1265second # [ 8.229244] systemd[1]: Starting Journal Log Access Socket...1266second # [ 8.231294] systemd[1]: Listening on Journal Audit Socket.1267second # [ 8.233175] systemd[1]: Listening on Network Management Metrics Varlink Socket.1268test # [ 8.252347] systemd[1]: Mounting Huge Pages File System...1269second # [ 8.235369] systemd[1]: Listening on Network Management Netlink Socket.1270test # [ 8.256797] systemd[1]: Mounting POSIX Message Queue File System...1271second # [ 8.237948] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1272second # [ 8.239904] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1273second # [ 8.242970] systemd[1]: Listening on udev Control Socket.1274test # [ 8.263083] systemd[1]: Mounting Kernel Debug File System...1275second # [ 8.247712] systemd[1]: Mounting Huge Pages File System...1276second # [ 8.252056] systemd[1]: Mounting POSIX Message Queue File System...1277test # [ 8.276122] systemd[1]: Mounting Kernel Trace File System...1278second # [ 8.260527] systemd[1]: Mounting Kernel Debug File System...1279test # [ 8.285359] systemd[1]: Starting Create List of Static Device Nodes...1280second # [ 8.268328] systemd[1]: Mounting Kernel Trace File System...1281second # [ 8.280073] systemd[1]: Starting Create List of Static Device Nodes...1282test # [ 8.303083] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1283second # [ 8.287947] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1284test # [ 8.305604] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1285second # [ 8.290081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1286test # [ 8.321168] systemd[1]: Mounting Kernel Configuration File System...1287second # [ 8.307057] systemd[1]: Mounting Kernel Configuration File System...1288test # [ 8.326099] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1289test # [ 8.328591] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1290second # [ 8.314573] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1291second # [ 8.321543] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1292test # [ 8.345542] systemd[1]: Starting Load Kernel Module fuse...1293test # [ 8.348869] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671294second # [ 8.341065] systemd[1]: Starting Load Kernel Module fuse...1295second # [ 8.345063] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671296test # [ 8.411474] systemd[1]: Starting Journal Service...1297second # [ 8.395376] systemd[1]: Starting Journal Service...1298second # [ 8.409658] systemd[1]: Starting Load Kernel Modules...1299test # [ 8.437316] systemd[1]: Starting Load Kernel Modules...1300second # [ 8.435219] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1301test # [ 8.462565] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1302second # [ 8.448158] systemd[1]: Starting Remount Root and Kernel File Systems...1303second # [ 8.456093] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1304test # [ 8.478535] systemd[1]: Starting Remount Root and Kernel File Systems...1305test # [ 8.492775] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1306second # [ 8.490327] systemd[1]: Starting Coldplug All udev Devices...1307test # [ 8.515343] systemd[1]: Starting Coldplug All udev Devices...1308test # [ 8.517751] systemd[1]: Listening on Journal Log Access Socket.1309test # [ 8.518379] systemd[1]: Mounted Huge Pages File System.1310second # [ 8.514620] systemd[1]: Listening on Journal Log Access Socket.1311test # [ 8.536793] fuse: init (API version 7.45)1312test # [ 8.538956] systemd[1]: Mounted POSIX Message Queue File System.1313second # [ 8.522243] fuse: init (API version 7.45)1314test # [ 8.541311] systemd[1]: Mounted Kernel Debug File System.1315second # [ 8.528328] systemd[1]: Mounted Huge Pages File System.1316second # [ 8.533063] systemd[1]: Mounted POSIX Message Queue File System.1317second # [ 8.534951] systemd[1]: Mounted Kernel Debug File System.1318second # [ 8.537843] systemd[1]: Mounted Kernel Trace File System.1319test # [ 8.557382] systemd[1]: Mounted Kernel Trace File System.1320test # [ 8.560212] systemd[1]: Finished Create List of Static Device Nodes.1321second # [ 8.550257] systemd[1]: Finished Create List of Static Device Nodes.1322second # [ 8.556816] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1323test # [ 8.577507] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1324second # [ 8.561013] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1325second # [ 8.566318] systemd[1]: Mounted Kernel Configuration File System.1326test # [ 8.586382] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1327test # [ 8.593022] systemd[1]: Mounted Kernel Configuration File System.1328second # [ 8.578291] systemd[1]: modprobe@fuse.service: Deactivated successfully.1329test # [ 8.599186] systemd[1]: modprobe@fuse.service: Deactivated successfully.1330second # [ 8.581764] systemd[1]: Finished Load Kernel Module fuse.1331test # [ 8.603103] systemd[1]: Finished Load Kernel Module fuse.1332second # [ 8.585570] systemd-journald[304]: Collecting audit messages is enabled.1333second # [ 8.589280] systemd[1]: Finished Load Kernel Modules.1334test # [ 8.607868] systemd-journald[325]: Collecting audit messages is enabled.1335test # [ 8.612479] systemd[1]: Finished Load Kernel Modules.1336second # [ 8.598599] systemd[1]: Mounting FUSE Control File System...1337test # [ 8.620963] systemd[1]: Mounting FUSE Control File System...1338second # [ 8.598785] systemd[1]: Queued start job for default target Multi-User System.1339test # [ 8.617520] systemd[1]: Queued start job for default target Multi-User System.1340test # [ 8.629599] systemd[1]: Starting Firewall...1341second # [ 8.604823] systemd[1]: systemd-journald.service: Deactivated successfully.1342second # [ 8.605665] systemd-modules-load[305]: Module 'atkbd' is built in1343second # [ 8.606319] systemd-modules-load[305]: Module 'ctr' is built in1344second # [ 8.606941] systemd-modules-load[305]: Module 'loop' is built in1345second # [ 8.620133] EXT4-fs (vda): re-mounted f872a561-5625-4c36-b824-b8c12c9e6a9b.1346test # [ 8.629601] systemd[1]: systemd-journald.service: Deactivated successfully.1347test # [ 8.630945] systemd-modules-load[326]: Module 'atkbd' is built in1348test # [ 8.631684] systemd-modules-load[326]: Module 'ctr' is built in1349second # [ 8.622841] systemd[1]: Starting Firewall...1350test # [ 8.645089] systemd[1]: Starting Apply Kernel Variables...1351test # [ 8.636332] systemd-modules-load[326]: Module 'loop' is built in1352test # [ 8.651631] EXT4-fs (vda): re-mounted b463b6ff-c664-4f93-b9e6-f8c293004dec.1353second # [ 8.635335] systemd[1]: Starting Apply Kernel Variables...1354test # [ 8.671287] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1355second # [ 8.665945] systemd-oomd[306]: No swap; memory pressure usage will be degraded1356second # [ 8.672393] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1357test # [ 8.692763] systemd-oomd[327]: No swap; memory pressure usage will be degraded1358second # [ 8.686692] systemd[1]: Started Journal Service.1359test # [ 8.707731] systemd[1]: Started Journal Service.1360second # [ 8.684840] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1361test # [ 8.713138] systemd[1]: Finished Remount Root and Kernel File Systems.1362test # [ 8.713960] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1363second # [ 8.701407] systemd[1]: Finished Remount Root and Kernel File Systems.1364second # [ 8.786583] systemd[1]: Starting Flush Journal to Persistent Storage...1365second # [ 8.789816] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1366test # [ 8.809266] systemd[1]: Starting Flush Journal to Persistent Storage...1367second # [ 8.800450] systemd[1]: Starting Load/Save OS Random Seed...1368second # [ 8.801093] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1369test # [ 8.816901] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1370test # [ 8.823135] systemd[1]: Starting Load/Save OS Random Seed...1371test # [ 8.824078] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1372second # [ 8.820924] systemd[1]: Mounted FUSE Control File System.1373test # [ 8.838361] systemd[1]: Mounted FUSE Control File System.1374second # [ 8.831939] systemd[1]: Finished Apply Kernel Variables.1375second # [ 8.838792] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1376test # [ 8.856762] systemd[1]: Finished Apply Kernel Variables.1377test # [ 8.860202] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1378second # [ 8.862710] systemd[1]: Starting Network Name Resolution...1379test # [ 8.882483] systemd[1]: Starting Network Name Resolution...1380test # [ 8.912659] systemd[1]: Starting Create Static Device Nodes in /dev...1381second # [ 8.895556] systemd[1]: Starting Create Static Device Nodes in /dev...1382second # [ 8.932450] systemd-journald[304]: Received client request to flush runtime journal.1383test # [ 8.973571] systemd-journald[325]: Received client request to flush runtime journal.1384test # [ 9.301358] systemd[1]: Finished Create Static Device Nodes in /dev.1385test # [ 9.305983] systemd[1]: Reached target Preparation for Local File Systems.1386test # [ 9.312689] systemd[1]: Starting Rule-based Manager for Device Events and Files...1387test # [ 9.321095] systemd-resolved[357]: Positive Trust Anchors:1388test # [ 9.322375] systemd-resolved[357]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1389test # [ 9.325384] systemd-resolved[357]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161390second # [ 9.321278] systemd[1]: Finished Create Static Device Nodes in /dev.1391second # [ 9.322780] systemd[1]: Reached target Preparation for Local File Systems.1392test # [ 9.336433] systemd-resolved[357]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1393second # [ 9.331027] systemd[1]: Starting Rule-based Manager for Device Events and Files...1394second # [ 9.344354] systemd-resolved[335]: Positive Trust Anchors:1395second # [ 9.345395] systemd-resolved[335]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1396second # [ 9.348666] systemd-resolved[335]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161397second # [ 9.354846] systemd-resolved[335]: 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 test1398test # [ 9.375559] systemd[1]: Mounting /run/wrappers...1399test # [ 9.376299] systemd[1]: Finished Load/Save OS Random Seed.1400test # [ 9.380329] systemd[1]: Reached target First Boot Complete.1401test # [ 9.385063] systemd[1]: Mounted /run/wrappers.1402test # [ 9.388570] systemd[1]: Reached target Local File Systems.1403second # [ 9.379797] systemd-udevd[345]: Using default interface naming scheme 'v260'.1404second # [ 9.380726] systemd[1]: Mounting /run/wrappers...1405second # [ 9.381290] systemd[1]: Finished Load/Save OS Random Seed.1406second # [ 9.381855] systemd[1]: Reached target First Boot Complete.1407second # [ 9.382347] systemd[1]: Mounted /run/wrappers.1408second # [ 9.382769] systemd[1]: Reached target Local File Systems.1409second # [ 9.383246] systemd[1]: Listening on Boot Loader Control Service Socket.1410test # [ 9.398792] systemd-udevd[368]: Using default interface naming scheme 'v260'.1411second # [ 9.383800] systemd[1]: Starting register-nix-paths.service...1412test # [ 9.406251] systemd[1]: Listening on Boot Loader Control Service Socket.1413test # [ 9.412606] systemd[1]: Starting register-nix-paths.service...1414test # [ 9.420572] systemd[1]: Starting Create SUID/SGID Wrappers...1415test # [ 9.421289] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1416test # [ 9.423678] systemd[1]: Starting Save Transient machine-id to Disk...1417second # [ 9.403568] systemd[1]: Starting Create SUID/SGID Wrappers...1418second # [ 9.412641] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419second # [ 9.413569] systemd[1]: Starting Save Transient machine-id to Disk...1420second # [ 9.414162] systemd-resolved[335]: Using system hostname 'second'.1421second # [ 9.414695] systemd[1]: Finished Flush Journal to Persistent Storage.1422second # [ 9.415232] systemd[1]: Started Network Name Resolution.1423second # [ 9.415690] systemd[1]: Starting Create System Files and Directories...1424test # [ 9.436195] systemd[1]: Finished Flush Journal to Persistent Storage.1425test # [ 9.441706] systemd[1]: Starting Create System Files and Directories...1426test # [ 9.470440] systemd-resolved[357]: Using system hostname 'test'.1427test # [ 9.488515] systemd[1]: Started Network Name Resolution.1428second # [ 9.514037] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1429second # [ 9.519417] systemd[1]: Finished Save Transient machine-id to Disk.1430test # [ 9.542267] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1431test # [ 9.545615] systemd[1]: Finished Save Transient machine-id to Disk.1432test # [ 9.587769] systemd[1]: Finished Create System Files and Directories.1433test # [ 9.598683] systemd[1]: Starting Rebuild Journal Catalog...1434second # [ 9.587605] systemd[1]: Finished Create System Files and Directories.1435test # [ 9.603131] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1436second # [ 9.598308] systemd[1]: Starting Rebuild Journal Catalog...1437second # [ 9.600689] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1438second # [ 9.609207] systemd[1]: Started Rule-based Manager for Device Events and Files.1439test # [ 9.654066] systemd[1]: Started Rule-based Manager for Device Events and Files.1440second # [ 9.741981] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1441test # [ 9.777535] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1442test # [ 9.815827] systemd[1]: Finished Rebuild Journal Catalog.1443test # [ 9.827540] systemd[1]: Starting Update is Completed...1444second # [ 9.831658] systemd[1]: Finished Rebuild Journal Catalog.1445second # [ 9.843739] systemd[1]: Starting Update is Completed...1446test # [ 9.930759] systemd[1]: Finished Update is Completed.1447second # [ 9.932034] systemd[1]: Finished Update is Completed.1448second # [ 10.037947] systemd[1]: Finished Coldplug All udev Devices.1449test # [ 10.143076] systemd[1]: Finished Coldplug All udev Devices.1450second # [ 10.230256] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1451test # [ 10.294189] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1452second # [ 10.455953] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1453second # [ 10.458156] systemd[1]: Finished Create SUID/SGID Wrappers.1454second # [ 10.496269] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1455test # [ 10.549157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1456second # [ 10.535626] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1457test # [ 10.551257] systemd[1]: Finished Create SUID/SGID Wrappers.1458test # [ 10.572380] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1459test # [ 10.621221] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1460second # [ 10.638454] systemd[1]: Finished Firewall.1461second # [ 10.641045] systemd[1]: Reached target Preparation for Network.1462second # [ 10.649236] systemd[1]: Starting Network Management...1463test # [ 10.771801] systemd[1]: Finished Firewall.1464test # [ 10.773649] systemd[1]: Reached target Preparation for Network.1465test # [ 10.780233] systemd[1]: Starting Network Management...1466second # [ 10.824816] (udev-worker)[411]: Network interface NamePolicy= disabled on kernel command line.1467second # [ 10.828470] (udev-worker)[414]: Network interface NamePolicy= disabled on kernel command line.1468test # [ 10.905429] systemd[1]: Finished register-nix-paths.service.1469test # [ 10.907237] systemd[1]: Reached target System Initialization.1470second # [ 10.895550] systemd[1]: Finished register-nix-paths.service.1471test # [ 10.910732] systemd[1]: Started Discard unused filesystem blocks once a week.1472second # [ 10.896354] systemd[1]: Reached target System Initialization.1473second # [ 10.896894] systemd[1]: Started Discard unused filesystem blocks once a week.1474second # [ 10.897497] systemd[1]: Started Daily Cleanup of Temporary Directories.1475test # [ 10.912848] systemd[1]: Started Daily Cleanup of Temporary Directories.1476second # [ 10.898082] systemd[1]: Reached target Timer Units.1477second # [ 10.898518] systemd[1]: Listening on D-Bus System Message Bus Socket.1478second # [ 10.899064] systemd[1]: Listening on Nix Daemon Socket.1479test # [ 10.915342] systemd[1]: Reached target Timer Units.1480test # [ 10.917522] systemd[1]: Listening on D-Bus System Message Bus Socket.1481test # [ 10.919600] systemd[1]: Listening on Nix Daemon Socket.1482test # [ 10.925213] systemd[1]: Listening on Authorization Manager Agent Helper.1483test # [ 10.925816] systemd[1]: Listening on Hostname Service Socket.1484test # [ 10.926328] systemd[1]: Reached target Socket Units.1485second # [ 10.914068] systemd[1]: Listening on Authorization Manager Agent Helper.1486second # [ 10.914814] systemd[1]: Listening on Hostname Service Socket.1487test # [ 10.931684] systemd[1]: Starting D-Bus System Message Bus...1488second # [ 10.926555] cfg80211: Loading compiled-in X.509 certificates for regulatory database1489second # [ 10.921691] systemd[1]: Reached target Socket Units.1490second # [ 10.925161] systemd[1]: Starting D-Bus System Message Bus...1491test # [ 10.955529] (udev-worker)[427]: Network interface NamePolicy= disabled on kernel command line.1492test # [ 10.974900] (udev-worker)[431]: Network interface NamePolicy= disabled on kernel command line.1493second # [ 10.969352] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1494second # [ 10.969879] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1495test # [ 11.031823] cfg80211: Loading compiled-in X.509 certificates for regulatory database1496second # [ 11.024501] systemd-networkd[536]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1497second # [ 11.028647] systemd-networkd[536]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1498second # [ 11.036334] systemd-networkd[536]: lo: Link UP1499second # [ 11.036983] systemd-networkd[536]: lo: Gained carrier1500test # [ 11.066390] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1501test # [ 11.068206] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1502second # [ 11.043468] systemd[1]: Started Network Management.1503second # [ 11.049316] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1504second # [ 11.055409] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1505second # [ 11.065130] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1506second # [ 11.068499] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1507second # [ 11.071709] systemd-networkd[536]: eth1: Link UP1508second # [ 11.074927] systemd-networkd[536]: eth1: Gained carrier1509second # [ 11.077294] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1510second # [ 11.099453] mousedev: PS/2 mouse device common for all mice1511test # [ 11.110151] systemd-networkd[557]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1512test # [ 11.113251] systemd-networkd[557]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1513test # [ 11.118888] systemd-networkd[557]: lo: Link UP1514test # [ 11.121132] systemd-networkd[557]: lo: Gained carrier1515test # [ 11.124314] systemd[1]: Started Network Management.1516test # [ 11.128984] dbus-broker-launch[561]: Looking up NSS user entry for 'systemd-timesync'...1517second # [ 11.119477] dbus-broker-launch[542]: Looking up NSS user entry for 'systemd-timesync'...1518test # [ 11.131593] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1519second # [ 11.120977] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1520second # [ 11.134653] dbus-broker-launch[542]: NSS returned no entry for 'systemd-timesync'1521second # [ 11.136406] dbus-broker-launch[542]: Invalid user-name in /nix/store/zd0mj0i4bnn5z4jx7yddr843bdd3khb0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1522test # [ 11.152603] dbus-broker-launch[561]: NSS returned no entry for 'systemd-timesync'1523test # [ 11.154492] dbus-broker-launch[561]: Invalid user-name in /nix/store/zd0mj0i4bnn5z4jx7yddr843bdd3khb0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1524test # [ 11.161472] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1525test # [ 11.169080] systemd-networkd[557]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1526test # [ 11.174791] systemd-networkd[557]: eth1: Link UP1527test # [ 11.177592] systemd-networkd[557]: eth1: Gained carrier1528test # [ 11.179794] systemd-networkd[557]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1529second # [ 11.183172] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1530second # [ 11.188530] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1531test # [ 11.206430] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1532second # [ 11.193459] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/c3af85xim0zyds0g3slc36c1bnj8n0vd-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1533second # [ 11.201931] dbus-broker-launch[542]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/cwwqsy7vfh399nrfcwlcn4kk8k6vgyw2-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1534second # [ 11.206146] systemd[1]: Started D-Bus System Message Bus.1535test # [ 11.224361] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1536test # [ 11.228263] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/wbyl7s3yk9v0l5hszd1i7rg2jrzx1gxh-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1537test # [ 11.242424] mousedev: PS/2 mouse device common for all mice1538test # [ 11.234133] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/c3af85xim0zyds0g3slc36c1bnj8n0vd-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1539test # [ 11.239233] dbus-broker-launch[561]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/cwwqsy7vfh399nrfcwlcn4kk8k6vgyw2-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1540test # [ 11.246533] systemd[1]: Started D-Bus System Message Bus.1541second # [ 11.244279] dbus-broker-launch[542]: Ready1542second # [ 11.250181] systemd[1]: Reached target Basic System.1543second # [ 11.255074] systemd[1]: Starting Network Manager Script Dispatcher Service...1544second # [ 11.258620] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545second # [ 11.271678] systemd[1]: Starting Network Manager...1546test # [ 11.288658] dbus-broker-launch[561]: Ready1547test # [ 11.293365] systemd[1]: Reached target Basic System.1548test # [ 11.298748] systemd[1]: Starting Network Manager Script Dispatcher Service...1549second # [ 11.285637] systemd[1]: Started backdoor.service.1550test # [ 11.304067] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1551test # [ 11.308770] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1552test # [ 11.325218] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1553second # [ 11.319879] systemd[1]: Starting Import lastlog data into lastlog2 database...1554second # [ 11.338316] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1555test # [ 11.359356] systemd[1]: Starting Network Manager...1556second # [ 11.359042] systemd[1]: Starting Post-Boot Actions...1557test # [ 11.388083] systemd[1]: Started backdoor.service.1558test # [ 11.402263] systemd[1]: Starting Import lastlog data into lastlog2 database...1559second # [ 11.392651] systemd[1]: Started Reset console on configuration changes.1560second # [ 11.403876] f6xzm5kixfjy7jjnjkrzp2isxv790ipf-wifi-secrets[552]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1561test # [ 11.454262] f6xzm5kixfjy7jjnjkrzp2isxv790ipf-wifi-secrets[573]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1562test # [ 11.461013] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1563second # connecting to host...1564test # [ 11.469395] 5nnma1imjqgdsrcmbqy7gg7cjykpb6ji-wifi-secrets[575]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1565second # [ 11.461552] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1566test # [ 11.477695] f2pdrgggm4v4cwnq3ayrw6q28aaf8j7z-wifi-secrets[574]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1567second # [ 11.467001] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1568test # [ 11.499287] systemd[1]: Starting Post-Boot Actions...1569test # [ 11.533623] systemd[1]: Started Reset console on configuration changes.1570test # [ 11.555282] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1571second # [ 11.561008] systemd[1]: Finished Post-Boot Actions.1572test # [ 11.588624] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1573test # [ 11.594044] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1574test # [ 11.599353] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575test # [ 11.604613] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1576test # [ 11.612597] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1577second # [ 11.600352] systemd[1]: Started Name Service Cache Daemon (nsncd).1578test # connecting to host...1579second # [ 11.603569] systemd[1]: Finished Import lastlog data into lastlog2 database.1580second # [ 11.607277] systemd[1]: Reached target Host and Network Name Lookups.1581second # [ 11.611627] nsncd[556]: Aug 10 15:05:39.371 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1582second # [ 11.615698] systemd[1]: Reached target User and Group Name Lookups.1583second # [ 11.620112] systemd[1]: Starting User Login Management...1584second # [ 11.647064] systemd[1]: Started Network Manager Script Dispatcher Service.1585test # [ 11.680414] systemd[1]: Finished Post-Boot Actions.1586test: Guest shell says: b'Spawning backdoor root shell...\n'1587test: connected to guest root shell1588test: (connecting took 11.96 seconds)1589test: (finished: waiting for the VM to finish booting, in 11.96 seconds)1590test # [ 11.731325] systemd[1]: Started Network Manager Script Dispatcher Service.1591test # [ 11.738151] systemd[1]: Started Name Service Cache Daemon (nsncd).1592test # [ 11.739069] nsncd[580]: Aug 10 15:05:39.485 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1593test # [ 11.747968] systemd[1]: Reached target Host and Network Name Lookups.1594test # [ 11.752485] systemd[1]: Reached target User and Group Name Lookups.1595test # [ 11.757798] systemd[1]: Starting User Login Management...1596second # [ 11.751793] systemd[1]: Starting Hostname Service...1597test # [ 11.782199] systemd[1]: Finished Import lastlog data into lastlog2 database.1598second # [ 11.765957] systemd-logind[586]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1599second # [ 11.771579] systemd-logind[586]: Watching system buttons on /dev/input/event0 (gpio-keys)1600second # [ 11.775147] systemd-logind[586]: New seat seat0.1601second # [ 11.775594] systemd[1]: Started User Login Management.1602second # [ 11.786866] systemd[1]: Starting linger-users.service...1603second # [ 11.859763] systemd[1]: linger-users.service: Deactivated successfully.1604second # [ 11.860980] systemd[1]: Finished linger-users.service.1605test # [ 11.895384] systemd[1]: Starting Hostname Service...1606second # [ 11.889740] systemd[1]: Started Hostname Service.1607test # [ 11.922245] systemd-logind[623]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1608test # [ 11.927201] systemd-logind[623]: Watching system buttons on /dev/input/event0 (gpio-keys)1609test # [ 11.931047] systemd-logind[623]: New seat seat0.1610test # [ 11.932935] systemd[1]: Started User Login Management.1611test # [ 11.936624] systemd[1]: Starting linger-users.service...1612second # [ 11.986586] systemd-networkd[536]: eth0: Link UP1613second # [ 11.987090] systemd-networkd[536]: eth0: Gained carrier1614test # [ 12.012905] systemd[1]: linger-users.service: Deactivated successfully.1615test # [ 12.014805] systemd[1]: Finished linger-users.service.1616second # [ 12.008119] systemd[1]: Started Network Manager.1617second # [ 12.010675] systemd[1]: Reached target Network.1618test # [ 12.029265] systemd[1]: Started Hostname Service.1619second # [ 12.015050] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1620second # [ 12.021523] systemd[1]: Starting Permit User Sessions...1621test # [ 12.119572] systemd-networkd[557]: eth0: Link UP1622test # [ 12.120567] systemd-networkd[557]: eth0: Gained carrier1623test # [ 12.143590] systemd[1]: Started Network Manager.1624test # [ 12.144293] systemd[1]: Reached target Network.1625test # [ 12.150389] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1626test # [ 12.157807] systemd[1]: Starting Permit User Sessions...1627second # [ 12.178641] systemd[1]: Finished Permit User Sessions.1628second # [ 12.195176] systemd[1]: Started Getty on tty1.1629second # [ 12.195656] systemd[1]: Reached target Login Prompts.1630second # [ 12.253197] systemd-resolved[335]: eth0: Bus client set default route setting: yes1631second # [ 12.257161] systemd-resolved[335]: eth0: Bus client set DNS server list to: 10.0.2.31632test # [ 12.296747] systemd[1]: Finished Permit User Sessions.1633second # [ 12.288156] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1634second # [ 12.288899] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1635test # [ 12.310263] systemd[1]: Started Getty on tty1.1636test # [ 12.310877] systemd[1]: Reached target Login Prompts.1637test # [ 12.346379] NetworkManager[576]: <warn> [1786374340.0934] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1638test # [ 12.365117] systemd-resolved[357]: eth0: Bus client set default route setting: yes1639test # [ 12.366355] systemd-resolved[357]: eth0: Bus client set DNS server list to: 10.0.2.31640test # [ 12.402947] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1641test # [ 12.403737] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1642second # [ 12.392507] systemd[1]: Reached target Multi-User System.1643second # [ 12.394726] systemd[1]: Startup finished in 856ms (kernel) + 5.156s (initrd) + 6.380s (userspace) = 12.393s.1644test # [ 12.537699] systemd[1]: Reached target Multi-User System.1645test # [ 12.539801] systemd[1]: Startup finished in 869ms (kernel) + 5.203s (initrd) + 6.466s (userspace) = 12.539s.1646second # [ 12.576827] systemd-networkd[536]: eth1: Gained IPv6LL1647test # [ 12.704597] systemd-networkd[557]: eth1: Gained IPv6LL1648second # [ 12.798931] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31649test: (finished: waiting for unit NetworkManager.service, in 13.21 seconds)1650test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1651test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1652test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1653test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1654test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1655second # [ 13.045278] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1656test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.04 seconds)1657(finished: run the VM test script, in 13.86 seconds)1658second # [ 13.090940] systemd[1]: Starting Virtual Console Setup...1659test # [ 13.133421] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31660second # [ 13.114445] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1661second # [ 13.116261] systemd[1]: Stopped Virtual Console Setup.1662second # [ 13.122190] systemd[1]: Starting Virtual Console Setup...1663second # [ 13.122882] systemd-logind[586]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1664test script finished in 13.98s1665cleanup1666kill QemuMachine (pid 14)1667second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13)1668kill QemuMachine (pid 15)1669test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/1bhl5b9821ifsz1dbfm4wxbjbfywmsaq-python3-3.13.14/bin/python3.13)1670(finished: cleanup, in 0.32 seconds)