vm-test-run-wifi
checks.aarch64-linux.wifi
· build #579
· 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...14test # Formatting '/build/vm-state-test/tmp.ENiWHFApP8', fmt=raw size=107374182415test # mke2fs 1.47.4 (6-Mar-2025)16test # Discarding device blocks: 0/262144 done17test: QEMU running (pid 15)18test # Creating filesystem with 262144 4k blocks and 65536 inodes19second # Disk image does not exist, creating the virtualisation disk image...20test # Filesystem UUID: 56d4a911-a653-4e8e-b22f-44cb1c53210821second # Formatting '/build/vm-state-second/tmp.MbUbHAjbkS', fmt=raw size=107374182422test # Superblock backups stored on blocks:23second # mke2fs 1.47.4 (6-Mar-2025)24test # 32768, 98304, 163840, 22937625second # Discarding device blocks: 0/262144 done26test # 27second # Creating filesystem with 262144 4k blocks and 65536 inodes28test # Allocating group tables: 0/8 done29second # Filesystem UUID: 993ae094-1997-4a07-9e01-c1efb38c501630test # Writing inode tables: 0/8 done31second # Superblock backups stored on blocks:32test # Creating journal (8192 blocks): done33second # 32768, 98304, 163840, 22937634test # Writing superblocks and filesystem accounting information: 0/8 done35second # 36test # 37second # Allocating group tables: 0/8 done38test # Virtualisation disk image created.39second # Writing inode tables: 0/8 done40second: QEMU running (pid 14)41second # Creating journal (8192 blocks): done42second # Writing superblocks and filesystem accounting information: 0/8 done43second # 44second # Virtualisation disk image created.45(finished: start all VMs, in 0.51 seconds)46test: waiting for unit NetworkManager.service47test: waiting for the VM to finish booting48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49test # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 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 s186328 r8192 d116776 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 alternatives84second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]85test # [ 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/vnx28pnp5dxll0qi02770d6mgc991wx7-nixos-system-test-test/init regInfo=/nix/store/k7ldx6xivizvdmg5s75h8048k9yhj6ss-closure-info/registration console=ttyAMA0,115200n8 console=tty086second # [ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 UTC 202687second # [ 0.000000] KASLR enabled88test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/k7ldx6xivizvdmg5s75h8048k9yhj6ss-closure-info/registration", will be passed to user space.89second # [ 0.000000] random: crng init done90test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes91second # [ 0.000000] Machine model: linux,dummy-virt92second # [ 0.000000] efi: UEFI not found.93test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)94second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT95test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)96second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]97test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB98second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]99test # [ 0.000000] software IO TLB: area num 1.100second # [ 0.000000] Zone ranges:101second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]102test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)103second # [ 0.000000] DMA32 empty104test # [ 0.000000] Fallback order for Node 0: 0105second # [ 0.000000] Normal empty106second # [ 0.000000] Device empty107test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144108test # [ 0.000000] Policy zone: DMA109second # [ 0.000000] Movable zone start for each node110second # [ 0.000000] Early memory node ranges111test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off112second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]113test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1114second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]115test # [ 0.000000] allocated 2097152 bytes of page_ext116test # [ 0.000000] ftrace: allocating 74760 entries in 294 pages117second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000118test # [ 0.000000] ftrace: allocated 294 pages with 4 groups119second # [ 0.000000] psci: probing for conduit method from DT.120test # [ 0.000000] rcu: Hierarchical RCU implementation.121second # [ 0.000000] psci: PSCIv1.3 detected in firmware.122test # [ 0.000000] rcu: RCU event tracing is enabled.123second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs124second # [ 0.000000] psci: Trusted OS migration not required125test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.126second # [ 0.000000] psci: SMC Calling Convention v1.1127test # [ 0.000000] Trampoline variant of Tasks RCU enabled.128test # [ 0.000000] Rude variant of Tasks RCU enabled.129second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)130test # [ 0.000000] Tracing variant of Tasks RCU enabled.131second # [ 0.000000] percpu: Embedded 76 pages/cpu s186328 r8192 d116776 u311296132test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.133second # [ 0.000000] Detected PIPT I-cache on CPU0134test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1135second # [ 0.000000] CPU features: detected: GICv3 CPU interface136second # [ 0.000000] CPU features: detected: Spectre-v4137test # [ 0.000000] RCU Tasks: 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-BHB139test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.140second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR141second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)142test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.143second # [ 0.000000] CPU features: detected: ARM erratum 1418040144test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0145second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion146test # [ 0.000000] GICv3: 256 SPIs implemented147test # [ 0.000000] GICv3: 0 Extended SPIs implemented148second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing149test # [ 0.000000] Root IRQ handler: gic_handle_irq150second # [ 0.000000] alternatives: applying boot alternatives151test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI152test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0153test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000154test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]155test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)156second # [ 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/dl2aabs0kza68ac6qwixlrs1a1hj5yb5-nixos-system-second-test/init regInfo=/nix/store/ps5fdgs5kgx1fla1xvmzlc7haf5qyca6-closure-info/registration console=ttyAMA0,115200n8 console=tty0157test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)158test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000159second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/ps5fdgs5kgx1fla1xvmzlc7haf5qyca6-closure-info/registration", will be passed to user space.160test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000161second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes162test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.163second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)164test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns165second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)166test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040167second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB168test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).169second # [ 0.000000] software IO TLB: area num 1.170test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns171second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)172second # [ 0.000000] Fallback order for Node 0: 0173test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns174second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144175test # [ 0.000060] arm-pv: using stolen time PV176second # [ 0.000000] Policy zone: DMA177second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off178test # [ 0.000476] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)179test # [ 0.000774] Console: colour dummy device 80x25180second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1181test # [ 0.000792] printk: legacy console [tty0] enabled182second # [ 0.000000] allocated 2097152 bytes of page_ext183second # [ 0.000000] ftrace: allocating 74760 entries in 294 pages184test # [ 0.001015] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)185second # [ 0.000000] ftrace: allocated 294 pages with 4 groups186test # [ 0.001021] pid_max: default: 32768 minimum: 301187second # [ 0.000000] rcu: Hierarchical RCU implementation.188test # [ 0.001107] LSM: initializing lsm=capability,landlock,yama,bpf,ima189second # [ 0.000000] rcu: RCU event tracing is enabled.190test # [ 0.001308] landlock: Up and running.191test # [ 0.001310] Yama: becoming mindful.192second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.193test # [ 0.001841] LSM support for eBPF active194second # [ 0.000000] Trampoline variant of Tasks RCU enabled.195second # [ 0.000000] Rude variant of Tasks RCU enabled.196test # [ 0.002003] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)197second # [ 0.000000] Tracing variant of Tasks RCU enabled.198test # [ 0.002021] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)199second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.200test # [ 0.004209] cacheinfo: Unable to detect cache hierarchy for CPU 0201second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1202test # [ 0.004946] rcu: Hierarchical SRCU implementation.203test # [ 0.004951] rcu: Max phase no-delay instances is 1000.204second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.205test # [ 0.005959] fsl-mc MSI: its@8080000 domain created206test # [ 0.006116] EFI services will not be available.207second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.208test # [ 0.006243] smp: Bringing up secondary CPUs ...209test # [ 0.006274] smp: Brought up 1 node, 1 CPU210second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.211test # [ 0.006276] SMP: Total of 1 processors activated.212test # [ 0.006278] CPU: All CPU(s) started at EL1213second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0214second # [ 0.000000] GICv3: 256 SPIs implemented215test # [ 0.006291] CPU features: detected: 32-bit EL0 Support216second # [ 0.000000] GICv3: 0 Extended SPIs implemented217test # [ 0.006294] CPU features: detected: Data cache clean to the PoU not required for I/D coherence218second # [ 0.000000] Root IRQ handler: gic_handle_irq219second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI220test # [ 0.006296] CPU features: detected: Common not Private translations221second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0222test # [ 0.006299] CPU features: detected: CRC32 instructions223second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000224test # [ 0.006300] CPU features: detected: Data cache clean to Point of Persistence225second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]226test # [ 0.006303] CPU features: detected: Enhanced Virtualization Traps227test # [ 0.006313] CPU features: detected: RCpc load-acquire (LDAPR)228second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)229test # [ 0.006315] CPU features: detected: LSE atomic instructions230test # [ 0.006316] CPU features: detected: Privileged Access Never231second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)232test # [ 0.006318] CPU features: detected: PMUv3233second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000234test # [ 0.006320] CPU features: detected: RAS Extension Support235second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000236test # [ 0.006323] CPU features: detected: Speculative Store Bypassing Safe (SSBS)237test # [ 0.006346] alternatives: applying system-wide alternatives238second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.239test # [ 0.007876] CPU features: detected: Hardware dirty bit management on CPU0240second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns241second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040242test # [ 0.008066] Memory: 893148K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114120K reserved, 32768K cma-reserved)243test # [ 0.008667] devtmpfs: initialized244second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).245test # [ 0.010101] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)246second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns247test # [ 0.010122] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).248second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns249test # [ 0.010259] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL250second # [ 0.000059] arm-pv: using stolen time PV251test # [ 0.010263] 0 pages in range for non-PLT usage252test # [ 0.010264] 508336 pages in range for PLT usage253second # [ 0.000415] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)254test # [ 0.010396] pinctrl core: initialized pinctrl subsystem255second # [ 0.000691] Console: colour dummy device 80x25256test # [ 0.011215] DMI not present or invalid.257second # [ 0.000708] printk: legacy console [tty0] enabled258test # [ 0.014660] NET: Registered PF_NETLINK/PF_ROUTE protocol family259test # [ 0.017083] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations260second # [ 0.000927] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)261second # [ 0.000933] pid_max: default: 32768 minimum: 301262test # [ 0.017240] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations263second # [ 0.001012] LSM: initializing lsm=capability,landlock,yama,bpf,ima264second # [ 0.001185] landlock: Up and running.265test # [ 0.017409] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations266second # [ 0.001187] Yama: becoming mindful.267test # [ 0.017431] audit: initializing netlink subsys (disabled)268second # [ 0.001701] LSM support for eBPF active269test # [ 0.018057] thermal_sys: Registered thermal governor 'fair_share'270second # [ 0.001858] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)271test # [ 0.018059] thermal_sys: Registered thermal governor 'bang_bang'272second # [ 0.001876] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)273test # [ 0.018062] thermal_sys: Registered thermal governor 'step_wise'274second # [ 0.003812] cacheinfo: Unable to detect cache hierarchy for CPU 0275test # [ 0.018063] thermal_sys: Registered thermal governor 'user_space'276second # [ 0.004642] rcu: Hierarchical SRCU implementation.277test # [ 0.018065] thermal_sys: Registered thermal governor 'power_allocator'278second # [ 0.004647] rcu: Max phase no-delay instances is 1000.279second # [ 0.005626] fsl-mc MSI: its@8080000 domain created280test # [ 0.018102] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1281test # [ 0.018119] cpuidle: using governor ladder282second # [ 0.005808] EFI services will not be available.283test # [ 0.018124] cpuidle: using governor menu284second # [ 0.005926] smp: Bringing up secondary CPUs ...285second # [ 0.005954] smp: Brought up 1 node, 1 CPU286test # [ 0.018248] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.287second # [ 0.005956] SMP: Total of 1 processors activated.288test # [ 0.018270] ASID allocator initialised with 32768 entries289second # [ 0.005958] CPU: All CPU(s) started at EL1290test # [ 0.018974] Serial: AMBA PL011 UART driver291second # [ 0.005971] CPU features: detected: 32-bit EL0 Support292test # [ 0.022200] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1293test # [ 0.022365] printk: console [ttyAMA0] enabled294second # [ 0.005973] CPU features: detected: Data cache clean to the PoU not required for I/D coherence295second # [ 0.005976] CPU features: detected: Common not Private translations296second # [ 0.005978] CPU features: detected: CRC32 instructions297second # [ 0.005980] CPU features: detected: Data cache clean to Point of Persistence298second # [ 0.005984] CPU features: detected: Enhanced Virtualization Traps299second # [ 0.005993] CPU features: detected: RCpc load-acquire (LDAPR)300second # [ 0.005995] CPU features: detected: LSE atomic instructions301second # [ 0.005997] CPU features: detected: Privileged Access Never302second # [ 0.005998] CPU features: detected: PMUv3303second # [ 0.006000] CPU features: detected: RAS Extension Support304second # [ 0.006004] CPU features: detected: Speculative Store Bypassing Safe (SSBS)305second # [ 0.006026] alternatives: applying system-wide alternatives306second # [ 0.007479] CPU features: detected: Hardware dirty bit management on CPU0307test # [ 0.095051] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages308second # [ 0.007722] Memory: 892900K/1048576K available (24320K kernel code, 7086K rwdata, 26340K rodata, 4736K init, 1103K bss, 114128K reserved, 32768K cma-reserved)309second # [ 0.008217] devtmpfs: initialized310test # [ 0.095077] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page311second # [ 0.009654] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)312test # [ 0.095093] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages313test # [ 0.095097] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page314second # [ 0.009676] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).315test # [ 0.095100] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages316second # [ 0.009811] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL317second # [ 0.009814] 0 pages in range for non-PLT usage318test # [ 0.095103] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page319second # [ 0.009815] 508336 pages in range for PLT usage320test # [ 0.095106] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages321second # [ 0.009948] pinctrl core: initialized pinctrl subsystem322test # [ 0.095109] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page323second # [ 0.010796] DMI not present or invalid.324second # [ 0.014340] NET: Registered PF_NETLINK/PF_ROUTE protocol family325second # [ 0.016727] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations326second # [ 0.016881] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations327test # [ 0.100220] fbcon: Taking over console328test # [ 0.100244] ACPI: Interpreter disabled.329second # [ 0.017053] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations330second # [ 0.017077] audit: initializing netlink subsys (disabled)331second # [ 0.017685] thermal_sys: Registered thermal governor 'fair_share'332second # [ 0.017688] thermal_sys: Registered thermal governor 'bang_bang'333second # [ 0.017691] thermal_sys: Registered thermal governor 'step_wise'334second # [ 0.017693] thermal_sys: Registered thermal governor 'user_space'335second # [ 0.017695] thermal_sys: Registered thermal governor 'power_allocator'336second # [ 0.017744] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1337second # [ 0.017762] cpuidle: using governor ladder338second # [ 0.017767] cpuidle: using governor menu339second # [ 0.017893] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.340second # [ 0.017915] ASID allocator initialised with 32768 entries341second # [ 0.018595] Serial: AMBA PL011 UART driver342second # [ 0.022204] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1343test # [ 0.106809] iommu: Default domain type: Translated344second # [ 0.022403] printk: console [ttyAMA0] enabled345test # [ 0.106827] iommu: DMA domain TLB invalidation policy: strict mode346test # [ 0.107212] SCSI subsystem initialized347test # [ 0.108574] usbcore: registered new interface driver usbfs348test # [ 0.108609] usbcore: registered new interface driver hub349test # [ 0.108620] usbcore: registered new device driver usb350test # [ 0.108868] pps_core: LinuxPPS API ver. 1 registered351test # [ 0.108876] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>352test # [ 0.108882] PTP clock support registered353test # [ 0.108913] EDAC MC: Ver: 3.0.0354test # [ 0.112137] scmi_core: SCMI protocol bus registered355test # [ 0.112893] FPGA manager framework356test # [ 0.113889] vgaarb: loaded357test # [ 0.114305] clocksource: Switched to clocksource arch_sys_counter358second # [ 0.100828] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages359second # [ 0.100853] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page360second # [ 0.100860] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages361second # [ 0.100863] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page362second # [ 0.100866] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages363second # [ 0.100869] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page364second # [ 0.100873] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages365second # [ 0.100876] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page366second # [ 0.106258] fbcon: Taking over console367second # [ 0.106279] ACPI: Interpreter disabled.368second # [ 0.107639] iommu: Default domain type: Translated369test # [ 0.124009] VFS: Disk quotas dquot_6.6.0370second # [ 0.107651] iommu: DMA domain TLB invalidation policy: strict mode371test # [ 0.124051] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)372second # [ 0.108926] SCSI subsystem initialized373second # [ 0.109525] usbcore: registered new interface driver usbfs374second # [ 0.109561] usbcore: registered new interface driver hub375test # [ 0.126325] netfs: FS-Cache loaded376test # [ 0.126464] pnp: PnP ACPI: disabled377second # [ 0.109571] usbcore: registered new device driver usb378second # [ 0.109805] pps_core: LinuxPPS API ver. 1 registered379second # [ 0.109811] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>380second # [ 0.109818] PTP clock support registered381second # [ 0.109854] EDAC MC: Ver: 3.0.0382second # [ 0.113075] scmi_core: SCMI protocol bus registered383second # [ 0.113850] FPGA manager framework384test # [ 0.128802] NET: Registered PF_INET protocol family385test # [ 0.128983] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)386second # [ 0.120945] vgaarb: loaded387second # [ 0.121476] clocksource: Switched to clocksource arch_sys_counter388second # [ 0.127274] VFS: Disk quotas dquot_6.6.0389second # [ 0.127315] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)390second # [ 0.129701] netfs: FS-Cache loaded391second # [ 0.129830] pnp: PnP ACPI: disabled392second # [ 0.132380] NET: Registered PF_INET protocol family393second # [ 0.132559] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.152977] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.153010] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.153025] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.153062] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398test # [ 0.153131] TCP: Hash tables configured (established 8192 bind 8192)399test # [ 0.153233] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.153289] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401test # [ 0.153336] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402test # [ 0.153460] NET: Registered PF_UNIX/PF_LOCAL protocol family403test # [ 0.153530] NET: Registered PF_XDP protocol family404test # [ 0.153554] PCI: CLS 0 bytes, default 64405test # [ 0.153780] Trying to unpack rootfs image as initramfs...406test # [ 0.168525] kvm [1]: HYP mode not available407second # [ 0.156874] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)408second # [ 0.156908] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)409second # [ 0.156923] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)410second # [ 0.156962] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)411second # [ 0.157030] TCP: Hash tables configured (established 8192 bind 8192)412second # [ 0.157116] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)413second # [ 0.157174] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)414second # [ 0.157199] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)415second # [ 0.157374] NET: Registered PF_UNIX/PF_LOCAL protocol family416second # [ 0.157406] NET: Registered PF_XDP protocol family417second # [ 0.157428] PCI: CLS 0 bytes, default 64418second # [ 0.157592] Trying to unpack rootfs image as initramfs...419second # [ 0.172091] kvm [1]: HYP mode not available420test # [ 0.236928] Initialise system trusted keyrings421test # [ 0.237478] workingset: timestamp_bits=42 max_order=18 bucket_order=0422test # [ 0.238516] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.239037] 9p: Installing v9fs 9p2000 file system support424second # [ 0.240401] Initialise system trusted keyrings425test # [ 0.257997] Key type asymmetric registered426test # [ 0.258026] Asymmetric key parser 'x509' registered427test # [ 0.258095] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)428second # [ 0.245557] workingset: timestamp_bits=42 max_order=18 bucket_order=0429second # [ 0.246670] squashfs: version 4.0 (2009/01/31) Phillip Lougher430second # [ 0.247234] 9p: Installing v9fs 9p2000 file system support431test # [ 0.266438] io scheduler mq-deadline registered432test # [ 0.266461] io scheduler kyber registered433test # [ 0.269462] pl061_gpio 9030000.pl061: PL061 GPIO chip registered434test # [ 0.270239] ledtrig-cpu: registered to indicate activity on CPUs435test # [ 0.271041] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:436test # [ 0.271070] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000437test # [ 0.271096] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000438test # [ 0.271102] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000439test # [ 0.271139] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits440test # [ 0.271179] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]441test # [ 0.271262] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00442test # [ 0.271280] pci_bus 0000:00: root bus resource [bus 00-ff]443test # [ 0.271286] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]444test # [ 0.271289] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]445test # [ 0.271293] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]446test # [ 0.271380] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint447test # [ 0.271959] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint448test # [ 0.272071] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]449test # [ 0.272081] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]450second # [ 0.266682] Key type asymmetric registered451second # [ 0.266713] Asymmetric key parser 'x509' registered452test # [ 0.272100] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]453test # [ 0.272112] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]454second # [ 0.266777] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)455test # [ 0.272391] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint456test # [ 0.272500] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]457test # [ 0.272511] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]458test # [ 0.272532] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]459test # [ 0.272798] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint460test # [ 0.272907] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]461test # [ 0.272917] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]462test # [ 0.272938] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]463test # [ 0.273202] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint464test # [ 0.273309] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]465test # [ 0.273320] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]466second # [ 0.273547] io scheduler mq-deadline registered467test # [ 0.273338] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]468second # [ 0.273570] io scheduler kyber registered469test # [ 0.273608] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint470test # [ 0.273716] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]471test # [ 0.273727] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]472test # [ 0.273747] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473test # [ 0.274061] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint474test # [ 0.274172] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]475second # [ 0.276773] pl061_gpio 9030000.pl061: PL061 GPIO chip registered476test # [ 0.274183] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]477test # [ 0.274202] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]478second # [ 0.277659] ledtrig-cpu: registered to indicate activity on CPUs479second # [ 0.278031] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:480second # [ 0.278055] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000481second # [ 0.278069] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000482second # [ 0.278075] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000483second # [ 0.278107] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits484second # [ 0.278143] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]485second # [ 0.278239] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00486test # [ 0.298603] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint487second # [ 0.278257] pci_bus 0000:00: root bus resource [bus 00-ff]488test # [ 0.298735] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]489second # [ 0.278263] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]490test # [ 0.298746] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]491second # [ 0.278267] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]492test # [ 0.298771] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]493second # [ 0.278271] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]494test # [ 0.298783] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]495test # [ 0.299074] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint496second # [ 0.278363] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint497test # [ 0.299204] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]498second # [ 0.278809] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint499test # [ 0.299223] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]500second # [ 0.278924] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]501second # [ 0.278936] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]502test # [ 0.299513] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint503test # [ 0.299627] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]504second # [ 0.278956] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505test # [ 0.299645] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506second # [ 0.278967] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]507test # [ 0.299889] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint508second # [ 0.279257] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint509test # [ 0.299996] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]510second # [ 0.279369] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]511second # [ 0.279380] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]512test # [ 0.300158] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint513test # [ 0.300348] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]514second # [ 0.279400] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]515test # [ 0.300361] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]516second # [ 0.279671] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint517test # [ 0.300381] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518second # [ 0.279781] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]519test # [ 0.300668] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint520second # [ 0.279792] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]521test # [ 0.300781] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]522second # [ 0.279811] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]523test # [ 0.300792] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]524second # [ 0.280083] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint525test # [ 0.300812] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]526second # [ 0.280195] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]527test # [ 0.301213] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned528second # [ 0.280205] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]529test # [ 0.301224] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned530second # [ 0.280225] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]531test # [ 0.301229] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned532second # [ 0.280506] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint533test # [ 0.301262] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned534second # [ 0.280619] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]535second # [ 0.280630] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]536test # [ 0.301292] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned537second # [ 0.280650] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538test # [ 0.301321] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned539second # [ 0.280922] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint540test # [ 0.301350] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned541second # [ 0.281035] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]542test # [ 0.301382] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned543second # [ 0.281045] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]544test # [ 0.301413] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned545second # [ 0.281065] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]546test # [ 0.301444] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned547second # [ 0.281343] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint548test # [ 0.301474] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned549second # [ 0.281460] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]550second # [ 0.281471] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]551test # [ 0.301503] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned552test # [ 0.301581] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned553test # [ 0.301612] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned554test # [ 0.301626] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned555test # [ 0.301641] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned556test # [ 0.301657] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned557test # [ 0.301671] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned558test # [ 0.301686] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned559test # [ 0.301702] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned560test # [ 0.301717] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned561test # [ 0.301732] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned562test # [ 0.301747] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned563test # [ 0.301763] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned564test # [ 0.301778] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned565test # [ 0.301793] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned566test # [ 0.301807] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned567test # [ 0.301823] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned568test # [ 0.301838] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned569test # [ 0.301859] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned570test # [ 0.301874] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned571test # [ 0.301889] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned572test # [ 0.301904] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned573second # [ 0.309565] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]574test # [ 0.301918] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned575second # [ 0.309580] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]576test # [ 0.301937] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]577test # [ 0.301943] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]578second # [ 0.309937] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint579test # [ 0.301947] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]580second # [ 0.310060] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]581second # [ 0.310079] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582second # [ 0.310361] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint583second # [ 0.310477] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]584second # [ 0.310496] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585second # [ 0.310748] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint586second # [ 0.310859] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]587second # [ 0.311157] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint588second # [ 0.311324] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]589second # [ 0.311337] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]590second # [ 0.311358] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]591second # [ 0.311673] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint592second # [ 0.311793] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]593second # [ 0.311805] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]594test # [ 0.338750] pci 0000:00:0a.0: enabling device (0000 -> 0002)595second # [ 0.311826] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]596second # [ 0.312264] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned597second # [ 0.312275] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned598second # [ 0.312281] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned599second # [ 0.312315] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned600second # [ 0.312347] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned601second # [ 0.312379] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned602second # [ 0.312410] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned603second # [ 0.312441] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned604second # [ 0.312473] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned605test # [ 0.345397] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)606second # [ 0.312505] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned607second # [ 0.312536] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned608second # [ 0.312566] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned609second # [ 0.312621] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned610second # [ 0.312652] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned611second # [ 0.312666] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned612second # [ 0.312681] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned613second # [ 0.312695] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned614second # [ 0.312710] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned615second # [ 0.312725] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned616second # [ 0.312741] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned617second # [ 0.312756] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned618second # [ 0.312772] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned619second # [ 0.312787] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned620second # [ 0.312802] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned621second # [ 0.312818] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned622second # [ 0.312834] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned623second # [ 0.312848] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned624second # [ 0.312863] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned625second # [ 0.312879] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned626second # [ 0.312899] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned627second # [ 0.312915] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned628test # [ 0.355506] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)629second # [ 0.312930] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned630second # [ 0.312945] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned631second # [ 0.312960] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned632second # [ 0.312981] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]633second # [ 0.312987] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]634second # [ 0.312991] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]635second # [ 0.313710] pci 0000:00:0a.0: enabling device (0000 -> 0002)636test # [ 0.362498] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)637test # [ 0.364415] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)638test # [ 0.366226] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)639second # [ 0.359196] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)640test # [ 0.375554] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)641second # [ 0.361207] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)642test # [ 0.377402] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)643second # [ 0.371684] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)644second # [ 0.373457] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)645test # [ 0.388576] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)646test # [ 0.390912] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)647test # [ 0.392986] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)648second # [ 0.384635] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)649test # [ 0.402957] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)650second # [ 0.387114] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)651second # [ 0.389117] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)652test # [ 0.407156] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled653test # [ 0.408726] msm_serial: driver initialized654test # [ 0.408830] SuperH (H)SCI(F) driver initialized655test # [ 0.408867] STM32 USART driver initialized656second # [ 0.398756] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)657second # [ 0.400453] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)658second # [ 0.410387] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)659second # [ 0.412749] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)660test # [ 0.435948] loop: module loaded661test # [ 0.436101] virtio_blk virtio5: 1/0/0 default/read/poll queues662test # [ 0.436699] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)663second # [ 0.422283] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled664second # [ 0.423932] msm_serial: driver initialized665second # [ 0.424030] SuperH (H)SCI(F) driver initialized666second # [ 0.424073] STM32 USART driver initialized667test # [ 0.442778] megasas: 07.734.00.00-rc1668test # [ 0.443340] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]669test # [ 0.445160] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000670test # [ 0.445199] Intel/Sharp Extended Query Table at 0x0031671test # [ 0.446892] Using buffer write method672test # [ 0.446938] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]673test # [ 0.448730] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000674test # [ 0.448750] Intel/Sharp Extended Query Table at 0x0031675test # [ 0.455496] Using buffer write method676test # [ 0.455534] Concatenating MTD devices:677test # [ 0.455538] (0): "0.flash"678test # [ 0.455541] (1): "0.flash"679test # [ 0.455544] into device "0.flash"680second # [ 0.449979] loop: module loaded681second # [ 0.450131] virtio_blk virtio5: 1/0/0 default/read/poll queues682second # [ 0.450773] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)683second # [ 0.453923] megasas: 07.734.00.00-rc1684second # [ 0.454437] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685second # [ 0.456948] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686second # [ 0.457020] Intel/Sharp Extended Query Table at 0x0031687second # [ 0.466503] Using buffer write method688second # [ 0.466582] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689second # [ 0.469291] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690second # [ 0.469319] Intel/Sharp Extended Query Table at 0x0031691second # [ 0.473201] Using buffer write method692second # [ 0.473247] Concatenating MTD devices:693second # [ 0.473252] (0): "0.flash"694second # [ 0.473255] (1): "0.flash"695second # [ 0.473258] into device "0.flash"696test # [ 0.675592] Freeing initrd memory: 25512K697test # [ 0.683223] tun: Universal TUN/TAP device driver, 1.6698test # [ 0.686112] thunder_xcv, ver 1.0699test # [ 0.686145] thunder_bgx, ver 1.0700test # [ 0.686160] nicpf, ver 1.0701test # [ 0.687356] e1000: Intel(R) PRO/1000 Network Driver702test # [ 0.687372] e1000: Copyright (c) 1999-2006 Intel Corporation.703test # [ 0.687392] e1000e: Intel(R) PRO/1000 Network Driver704test # [ 0.687397] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.705test # [ 0.687411] igb: Intel(R) Gigabit Ethernet Network Driver706test # [ 0.687414] igb: Copyright (c) 2007-2014 Intel Corporation.707test # [ 0.687428] igbvf: Intel(R) Gigabit Virtual Function Network Driver708test # [ 0.687432] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.709test # [ 0.687530] sky2: driver version 1.30710test # [ 0.688325] usbcore: registered new interface driver usb-storage711test # [ 0.688417] usbcore: registered new interface driver usbserial_generic712test # [ 0.688438] usbserial: USB Serial support registered for generic713test # [ 0.688725] hv_vmbus: registering driver hyperv_keyboard714test # [ 0.695180] ehci-pci 0000:00:0a.0: EHCI Host Controller715test # [ 0.695216] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716test # [ 0.695449] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717test # [ 0.697495] rtc-pl031 9010000.pl031: registered as rtc0718test # [ 0.697546] rtc-pl031 9010000.pl031: setting system clock to 2026-09-04T00:18:56 UTC (1788481136)719test # [ 0.697764] i2c_dev: i2c /dev entries driver720test # [ 0.700787] sdhci: Secure Digital Host Controller Interface driver721test # [ 0.700807] sdhci: Copyright(c) Pierre Ossman722test # [ 0.700946] Synopsys Designware Multimedia Card Interface Driver723test # [ 0.701114] sdhci-pltfm: SDHCI platform and OF driver helper724test # [ 0.702111] hid: raw HID events driver (C) Jiri Kosina725test # [ 0.702259] usbcore: registered new interface driver usbhid726test # [ 0.702264] usbhid: USB HID core driver727second # [ 0.688106] Freeing initrd memory: 25520K728test # [ 0.705979] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available729test # [ 0.707057] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00730test # [ 0.707958] hub 1-0:1.0: USB hub found731test # [ 0.708318] hub 1-0:1.0: 6 ports detected732test # [ 0.709452] drop_monitor: Initializing network drop monitor service733test # [ 0.709640] NET: Registered PF_INET6 protocol family734test # [ 0.711302] Segment Routing with IPv6735test # [ 0.711331] In-situ OAM (IOAM) with IPv6736second # [ 0.696024] tun: Universal TUN/TAP device driver, 1.6737test # [ 0.711411] NET: Registered PF_PACKET protocol family738test # [ 0.712510] 9pnet: Installing 9P2000 support739test # [ 0.714166] Key type dns_resolver registered740second # [ 0.698743] thunder_xcv, ver 1.0741second # [ 0.698775] thunder_bgx, ver 1.0742second # [ 0.698787] nicpf, ver 1.0743second # [ 0.699136] e1000: Intel(R) PRO/1000 Network Driver744second # [ 0.699146] e1000: Copyright (c) 1999-2006 Intel Corporation.745test # [ 0.717804] registered taskstats version 1746test # [ 0.717987] Loading compiled-in X.509 certificates747second # [ 0.699164] e1000e: Intel(R) PRO/1000 Network Driver748second # [ 0.699170] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.749second # [ 0.699197] igb: Intel(R) Gigabit Ethernet Network Driver750second # [ 0.699202] igb: Copyright (c) 2007-2014 Intel Corporation.751second # [ 0.699213] igbvf: Intel(R) Gigabit Virtual Function Network Driver752second # [ 0.699217] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.753second # [ 0.699292] sky2: driver version 1.30754second # [ 0.700163] usbcore: registered new interface driver usb-storage755second # [ 0.700213] usbcore: registered new interface driver usbserial_generic756second # [ 0.700233] usbserial: USB Serial support registered for generic757second # [ 0.700549] hv_vmbus: registering driver hyperv_keyboard758test # [ 0.723401] Demotion targets for Node 0: null759test # [ 0.723512] Key type .fscrypt registered760test # [ 0.723518] Key type fscrypt-provisioning registered761second # [ 0.708434] ehci-pci 0000:00:0a.0: EHCI Host Controller762test # [ 0.723628] ima: No TPM chip found, activating TPM-bypass!763second # [ 0.708476] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1764test # [ 0.723642] ima: Allocated hash algorithm: sha1765test # [ 0.723701] ima: No architecture policies found766second # [ 0.708710] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000767test # [ 0.726479] input: gpio-keys as /devices/platform/gpio-keys/input/input0768second # [ 0.710527] rtc-pl031 9010000.pl031: registered as rtc0769second # [ 0.710571] rtc-pl031 9010000.pl031: setting system clock to 2026-09-04T00:18:56 UTC (1788481136)770second # [ 0.710803] i2c_dev: i2c /dev entries driver771second # [ 0.713976] sdhci: Secure Digital Host Controller Interface driver772second # [ 0.713993] sdhci: Copyright(c) Pierre Ossman773second # [ 0.714151] Synopsys Designware Multimedia Card Interface Driver774second # [ 0.714338] sdhci-pltfm: SDHCI platform and OF driver helper775second # [ 0.715400] hid: raw HID events driver (C) Jiri Kosina776second # [ 0.715548] usbcore: registered new interface driver usbhid777second # [ 0.715553] usbhid: USB HID core driver778second # [ 0.719318] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00779second # [ 0.719720] hub 1-0:1.0: USB hub found780second # [ 0.719750] hub 1-0:1.0: 6 ports detected781second # [ 0.721137] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available782test # [ 0.737452] clk: Disabling unused clocks783test # [ 0.737480] PM: genpd: Disabling unused power domains784second # [ 0.722857] drop_monitor: Initializing network drop monitor service785second # [ 0.722990] NET: Registered PF_INET6 protocol family786second # [ 0.724712] Segment Routing with IPv6787second # [ 0.724741] In-situ OAM (IOAM) with IPv6788test # [ 0.740842] Freeing unused kernel memory: 4736K789test # [ 0.740943] Run /init as init process790second # [ 0.724799] NET: Registered PF_PACKET protocol family791second # [ 0.725993] 9pnet: Installing 9P2000 support792second # [ 0.727641] Key type dns_resolver registered793second # [ 0.731402] registered taskstats version 1794second # [ 0.731603] Loading compiled-in X.509 certificates795second # [ 0.737024] Demotion targets for Node 0: null796second # [ 0.737122] Key type .fscrypt registered797second # [ 0.737128] Key type fscrypt-provisioning registered798second # [ 0.737242] ima: No TPM chip found, activating TPM-bypass!799second # [ 0.737257] ima: Allocated hash algorithm: sha1800second # [ 0.737322] ima: No architecture policies found801second # [ 0.740526] input: gpio-keys as /devices/platform/gpio-keys/input/input0802test # [ 0.760970] systemd[1]: Successfully made /usr/ read-only.803second # [ 0.751325] clk: Disabling unused clocks804second # [ 0.751351] PM: genpd: Disabling unused power domains805second # [ 0.754751] Freeing unused kernel memory: 4736K806second # [ 0.754849] Run /init as init process807second # [ 0.776124] systemd[1]: Successfully made /usr/ read-only.808test # [ 0.954444] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 0.965549] usb 1-1: new high-speed USB device number 2 using ehci-pci810test # [ 1.095871] 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)811test # [ 1.103516] systemd[1]: Detected virtualization qemu.812test # [ 1.104962] systemd[1]: Detected architecture arm64.813test # [ 1.106269] systemd[1]: Running in initrd.814test # [ 1.107326] systemd[1]: Initializing machine ID from random generator.815test # [ 1.107658] systemd[1]: Hostname set to <test>.816test # [ 1.117466] 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/input1817second # [ 1.111027] 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)818second # [ 1.119407] systemd[1]: Detected virtualization qemu.819second # [ 1.120637] systemd[1]: Detected architecture arm64.820second # [ 1.121811] systemd[1]: Running in initrd.821second # [ 1.124161] systemd[1]: Initializing machine ID from random generator.822second # [ 1.126664] systemd[1]: Hostname set to <second>.823second # [ 1.133563] 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/input1824test # [ 1.202541] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825test # [ 1.219716] systemd[1]: Queued start job for default target Initrd Default Target.826test # [ 1.227910] systemd[1]: Created slice Slice /system/modprobe.827test # [ 1.228754] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.828test # [ 1.229579] systemd[1]: Expecting device /dev/disk/by-label/nixos...829test # [ 1.230260] systemd[1]: Reached target Path Units.830test # [ 1.230846] systemd[1]: Reached target Slice Units.831test # [ 1.231381] systemd[1]: Reached target Swaps.832test # [ 1.231825] systemd[1]: Reached target Timer Units.833test # [ 1.232542] systemd[1]: Listening on D-Bus System Message Bus Socket.834test # [ 1.233376] systemd[1]: Listening on Journal Socket (/dev/log).835test # [ 1.234140] systemd[1]: Listening on Journal Sockets.836test # [ 1.234978] systemd[1]: Listening on udev Control Socket.837test # [ 1.235697] systemd[1]: Listening on udev Kernel Socket.838test # [ 1.236227] systemd[1]: Reached target Socket Units.839test # [ 1.237969] systemd[1]: Starting Create List of Static Device Nodes...840second # [ 1.225704] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0841test # [ 1.244988] systemd[1]: Starting Load Kernel Module 9pnet_virtio...842test # [ 1.245080] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs843test # [ 1.247839] systemd[1]: Mounting Kernel Configuration File System...844second # [ 1.238143] systemd[1]: Queued start job for default target Initrd Default Target.845test # [ 1.259312] systemd[1]: Starting Journal Service...846second # [ 1.247570] systemd[1]: Created slice Slice /system/modprobe.847second # [ 1.248466] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.848second # [ 1.249391] systemd[1]: Expecting device /dev/disk/by-label/nixos...849second # [ 1.250220] systemd[1]: Reached target Path Units.850second # [ 1.250781] systemd[1]: Reached target Slice Units.851second # [ 1.251395] systemd[1]: Reached target Swaps.852second # [ 1.251896] systemd[1]: Reached target Timer Units.853second # [ 1.252672] systemd[1]: Listening on D-Bus System Message Bus Socket.854second # [ 1.253683] systemd[1]: Listening on Journal Socket (/dev/log).855second # [ 1.254545] systemd[1]: Listening on Journal Sockets.856second # [ 1.255288] systemd[1]: Listening on udev Control Socket.857second # [ 1.256041] systemd[1]: Listening on udev Kernel Socket.858second # [ 1.256669] systemd[1]: Reached target Socket Units.859second # [ 1.258623] systemd[1]: Starting Create List of Static Device Nodes...860second # [ 1.265643] systemd[1]: Starting Load Kernel Module 9pnet_virtio...861second # [ 1.266436] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs862test # [ 1.282467] systemd[1]: Starting Load Kernel Modules...863test # [ 1.282538] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki864second # [ 1.273636] systemd[1]: Mounting Kernel Configuration File System...865test # [ 1.294476] systemd[1]: Starting Coldplug All udev Devices...866test # [ 1.296055] systemd[1]: Finished Create List of Static Device Nodes.867test # [ 1.297424] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.868second # [ 1.281664] systemd[1]: Starting Journal Service...869test # [ 1.301954] systemd-journald[73]: Collecting audit messages is disabled.870test # [ 1.303373] systemd[1]: Finished Load Kernel Module 9pnet_virtio.871test # [ 1.303633] systemd[1]: Mounted Kernel Configuration File System.872second # [ 1.295424] systemd[1]: Starting Load Kernel Modules...873second # [ 1.296035] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki874test # [ 1.314610] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...875second # [ 1.309418] systemd[1]: Starting Coldplug All udev Devices...876second # [ 1.312664] systemd[1]: Finished Create List of Static Device Nodes.877second # [ 1.314252] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.878test # [ 1.330780] usb 1-2: new high-speed USB device number 3 using ehci-pci879second # [ 1.321750] systemd[1]: Finished Load Kernel Module 9pnet_virtio.880second # [ 1.322190] systemd[1]: Mounted Kernel Configuration File System.881second # [ 1.325787] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...882test # [ 1.350466] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883test # [ 1.354634] systemd[1]: Starting Create Static Device Nodes in /dev...884second # [ 1.345554] usb 1-2: new high-speed USB device number 3 using ehci-pci885test # [ 1.363122] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.886test # [ 1.374561] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev887second # [ 1.368839] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.888second # [ 1.370520] systemd-journald[73]: Collecting audit messages is disabled.889test # [ 1.386911] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0890test # [ 1.387148] [drm] features: -virgl +edid -resource_blob -host_visible891test # [ 1.387154] [drm] features: -context_init892test # [ 1.387638] [drm] number of scanouts: 1893test # [ 1.387654] [drm] number of cap sets: 0894test # [ 1.398529] systemd[1]: Started Journal Service.895second # [ 1.381955] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.896second # [ 1.385793] systemd[1]: Starting Create Static Device Nodes in /dev...897test # [ 1.392429] systemd-modules-load[74]: Inserted module 'dm_mod'898test # [ 1.393416] systemd-modules-load[74]: Module 'virtio_balloon' is built in899test # [ 1.405092] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic900test # [ 1.405115] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0901test # [ 1.399380] systemd-modules-load[74]: Module 'virtio_console' is built in902second # [ 1.389623] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev903second # [ 1.394542] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0904second # [ 1.394762] [drm] features: -virgl +edid -resource_blob -host_visible905second # [ 1.394768] [drm] features: -context_init906second # [ 1.395256] [drm] number of scanouts: 1907second # [ 1.395272] [drm] number of cap sets: 0908test # [ 1.412372] systemd[1]: Finished Create Static Device Nodes in /dev.909test # [ 1.413106] systemd[1]: Reached target Preparation for Local File Systems.910test # [ 1.413771] systemd[1]: Reached target Local File Systems.911second # [ 1.410044] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic912second # [ 1.410065] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0913test # [ 1.426812] Console: switching to colour frame buffer device 160x50914test # [ 1.422516] systemd[1]: Starting Create System Files and Directories...915test # [ 1.425024] systemd[1]: Starting Rule-based Manager for Device Events and Files...916test # [ 1.442126] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device917test # [ 1.436262] systemd-modules-load[74]: Inserted module 'virtio_gpu'918test # [ 1.445184] systemd-modules-load[74]: Module 'virtio_rng' is built in919test # [ 1.445909] systemd[1]: Finished Load Kernel Modules.920second # [ 1.437950] Console: switching to colour frame buffer device 160x50921second # [ 1.442976] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device922second # [ 1.445310] systemd[1]: Finished Create Static Device Nodes in /dev.923second # [ 1.445541] systemd[1]: Reached target Preparation for Local File Systems.924second # [ 1.445594] systemd[1]: Reached target Local File Systems.925test # [ 1.460258] systemd[1]: Starting Apply Kernel Variables...926second # [ 1.453886] systemd[1]: Starting Rule-based Manager for Device Events and Files...927test # [ 1.469896] systemd[1]: Finished Create System Files and Directories.928second # [ 1.462738] systemd-modules-load[74]: Inserted module 'dm_mod'929second # [ 1.469317] systemd[1]: Started Journal Service.930second # [ 1.472470] systemd-modules-load[74]: Module 'virtio_balloon' is built in931test # [ 1.484404] systemd[1]: Finished Apply Kernel Variables.932test # [ 1.500176] 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/input2933second # [ 1.480527] systemd-modules-load[74]: Module 'virtio_console' is built in934test # [ 1.502037] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0935second # [ 1.484272] systemd-modules-load[74]: Inserted module 'virtio_gpu'936second # [ 1.484992] systemd-modules-load[74]: Module 'virtio_rng' is built in937second # [ 1.488450] systemd[1]: Finished Load Kernel Modules.938second # [ 1.489131] systemd[1]: Starting Apply Kernel Variables...939second # [ 1.496639] systemd[1]: Starting Create System Files and Directories...940test # [ 1.513472] systemd-udevd[83]: Using default interface naming scheme 'v260'.941second # [ 1.507544] 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/input2942second # [ 1.509901] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0943second # [ 1.525856] systemd-udevd[80]: Using default interface naming scheme 'v260'.944test # [ 1.537639] systemd[1]: Started Rule-based Manager for Device Events and Files.945second # [ 1.546319] systemd[1]: Finished Apply Kernel Variables.946second # [ 1.548480] systemd[1]: Finished Create System Files and Directories.947second # [ 1.567415] systemd[1]: Started Rule-based Manager for Device Events and Files.948test # [ 1.600352] systemd[1]: Starting Virtual Console Setup...949second # [ 1.628141] systemd[1]: Starting Virtual Console Setup...950test # [ 1.652511] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.655093] systemd[1]: Finished Virtual Console Setup.952second # [ 1.676408] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.953second # [ 1.678877] systemd[1]: Finished Virtual Console Setup.954test # [ 1.909659] systemd[1]: Finished Coldplug All udev Devices.955test # [ 1.910253] systemd[1]: Reached target System Initialization.956test # [ 1.912120] systemd[1]: Reached target Basic System.957second # [ 1.953252] systemd[1]: Finished Coldplug All udev Devices.958second # [ 1.953885] systemd[1]: Reached target System Initialization.959second # [ 1.954413] systemd[1]: Reached target Basic System.960test # [ 2.014282] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.038829] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.075973] (udev-worker)[100]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.079679] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.964test # [ 2.099451] systemd[1]: Found device /dev/disk/by-label/nixos.965test # [ 2.104490] systemd[1]: Reached target Initrd Root Device.966test # [ 2.108128] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...967second # [ 2.140810] systemd[1]: Found device /dev/disk/by-label/nixos.968test # [ 2.153802] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks969test # [ 2.158141] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.970second # [ 2.149639] systemd[1]: Reached target Initrd Root Device.971second # [ 2.153726] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...972second # [ 2.205752] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks973second # [ 2.211801] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974test # [ 2.287121] systemd[1]: Mounting /sysroot...975test # [ 2.326518] EXT4-fs (vda): mounted filesystem 56d4a911-a653-4e8e-b22f-44cb1c532108 r/w with ordered data mode. Quota mode: none.976second # [ 2.311751] systemd[1]: Mounting /sysroot...977test # [ 2.324257] systemd[1]: Mounted /sysroot.978test # [ 2.324724] systemd[1]: Reached target Initrd Root File System.979test # [ 2.328483] systemd[1]: Starting Mountpoints Configured in the Real Root...980second # [ 2.337197] EXT4-fs (vda): mounted filesystem 993ae094-1997-4a07-9e01-c1efb38c5016 r/w with ordered data mode. Quota mode: none.981test # [ 2.347333] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.982second # [ 2.337477] systemd[1]: Mounted /sysroot.983second # [ 2.339982] systemd[1]: Reached target Initrd Root File System.984test # [ 2.351733] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...985test # [ 2.355021] systemd[1]: Reloading...986second # [ 2.346318] systemd[1]: Starting Mountpoints Configured in the Real Root...987second # [ 2.363246] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.988second # [ 2.369201] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...989second # [ 2.372839] systemd[1]: Reloading...990test # [ 2.544469] systemd[1]: Reloading finished in 190 ms.991test # [ 2.555994] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...992test # [ 2.558911] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...993test # [ 2.563212] systemd[1]: Starting Load Kernel Module 9pnet_virtio...994test # [ 2.571125] systemd[1]: initrd-parse-etc.service: Deactivated successfully.995second # [ 2.560439] systemd[1]: Reloading finished in 188 ms.996test # [ 2.574147] systemd[1]: Finished Mountpoints Configured in the Real Root.997test # [ 2.575608] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.998second # [ 2.574429] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...999test # [ 2.588691] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1000test # [ 2.589426] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1001second # [ 2.578564] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1002second # [ 2.583787] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1003second # [ 2.589851] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004second # [ 2.592145] systemd[1]: Finished Mountpoints Configured in the Real Root.1005second # [ 2.593561] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1006second # [ 2.608776] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1007second # [ 2.609488] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1008test # [ 2.968454] (udev-worker)[96]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory1009test # [ 2.974983] (udev-worker)[96]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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 directory1010test # [ 2.980932] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1011test # [ 2.982681] systemd[1]: Stopped Virtual Console Setup.1012test # [ 2.983246] systemd[1]: Stopping Virtual Console Setup...1013test # [ 2.985101] systemd[1]: Starting Virtual Console Setup...1014test # [ 3.011367] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1015test # [ 3.013909] systemd[1]: Finished Virtual Console Setup.1016second # [ 3.021603] (udev-worker)[94]: mtd0ro: Failed to find and pin callout binary "/nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-systemd-260.2/lib/udev/mtd_probe": No such file or directory1017second # [ 3.025553] (udev-worker)[94]: mtd0ro: /nix/store/2pprwd9bdjg4ssiam65rgjqfy64h0hbd-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 directory1018second # [ 3.043054] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1019second # [ 3.043776] systemd[1]: Stopped Virtual Console Setup.1020second # [ 3.044348] systemd[1]: Stopping Virtual Console Setup...1021second # [ 3.046584] systemd[1]: Starting Virtual Console Setup...1022second # [ 3.071380] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1023second # [ 3.073987] systemd[1]: Finished Virtual Console Setup.1024test # [ 3.289683] systemd[1]: Mounting /sysroot/nix/.ro-store...1025test # [ 3.297382] systemd[1]: Mounting /sysroot/nix/.rw-store...1026test # [ 3.312369] systemd[1]: Mounting /sysroot/run...1027second # [ 3.309867] systemd[1]: Mounting /sysroot/nix/.ro-store...1028test # [ 3.324943] systemd[1]: Mounting /sysroot/tmp/shared...1029second # [ 3.317858] systemd[1]: Mounting /sysroot/nix/.rw-store...1030test # [ 3.339626] systemd[1]: Mounting /sysroot/tmp/xchg...1031second # [ 3.332380] systemd[1]: Mounting /sysroot/run...1032second # [ 3.340340] systemd[1]: Mounting /sysroot/tmp/shared...1033test # [ 3.370851] systemd[1]: Mounted /sysroot/nix/.ro-store.1034second # [ 3.362322] systemd[1]: Mounting /sysroot/tmp/xchg...1035test # [ 3.378249] systemd[1]: Mounted /sysroot/nix/.rw-store.1036test # [ 3.383101] systemd[1]: Mounted /sysroot/run.1037test # [ 3.392377] systemd[1]: Starting rw-sysroot-nix-store.service...1038test # [ 3.395095] systemd[1]: Mounted /sysroot/tmp/shared.1039test # [ 3.397218] systemd[1]: Mounted /sysroot/tmp/xchg.1040second # [ 3.392312] systemd[1]: Mounted /sysroot/nix/.ro-store.1041second # [ 3.401340] systemd[1]: Mounted /sysroot/nix/.rw-store.1042second # [ 3.402797] systemd[1]: Mounted /sysroot/run.1043test # [ 3.415386] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1044test # [ 3.417119] systemd[1]: Finished rw-sysroot-nix-store.service.1045second # [ 3.406892] systemd[1]: Mounted /sysroot/tmp/shared.1046second # [ 3.412568] systemd[1]: Mounted /sysroot/tmp/xchg.1047second # [ 3.416170] systemd[1]: Starting rw-sysroot-nix-store.service...1048second # [ 3.433505] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1049second # [ 3.434606] systemd[1]: Finished rw-sysroot-nix-store.service.1050test # [ 4.286751] systemd[1]: Mounting /sysroot/nix/store...1051second # [ 4.306764] systemd[1]: Mounting /sysroot/nix/store...1052test # [ 4.329026] systemd[1]: Mounted /sysroot/nix/store.1053test # [ 4.330834] systemd[1]: Reached target Initrd File Systems.1054test # [ 4.334053] systemd[1]: Starting Find NixOS closure...1055test # [ 4.340809] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1056second # [ 4.331975] systemd[1]: Mounted /sysroot/nix/store.1057second # [ 4.333547] systemd[1]: Reached target Initrd File Systems.1058second # [ 4.334948] systemd[1]: Starting Find NixOS closure...1059second # [ 4.339543] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1060second # [ 4.359404] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1061test # [ 4.372086] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1062second # [ 4.362007] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1063test # [ 4.377213] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1064second # [ 4.373794] systemd[1]: Finished Find NixOS closure.1065second # [ 4.374652] systemd[1]: Reached target Initrd Default Target.1066second # [ 4.376272] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1067test # [ 4.390879] systemd[1]: Finished Find NixOS closure.1068test # [ 4.393546] systemd[1]: Reached target Initrd Default Target.1069test # [ 4.394821] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1070second # [ 4.399114] systemd[1]: initrd-cleanup.service: Deactivated successfully.1071second # [ 4.401934] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1072second # [ 4.402568] systemd[1]: Stopped target Initrd Default Target.1073second # [ 4.403125] systemd[1]: Stopped target Basic System.1074second # [ 4.403572] systemd[1]: Stopped target Initrd Root Device.1075second # [ 4.405979] systemd[1]: Stopped target Path Units.1076second # [ 4.406465] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077second # [ 4.407087] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1078second # [ 4.408766] systemd[1]: Stopped target Slice Units.1079test # [ 4.421926] systemd[1]: Stopped target Initrd Default Target.1080second # [ 4.412139] systemd[1]: Stopped target Socket Units.1081test # [ 4.423196] systemd[1]: Stopped target Basic System.1082second # [ 4.412624] systemd[1]: Stopped target System Initialization.1083second # [ 4.413097] systemd[1]: Stopped target Swaps.1084second # [ 4.413483] systemd[1]: Stopped target Timer Units.1085test # [ 4.424291] systemd[1]: Stopped target Initrd Root Device.1086second # [ 4.413921] systemd[1]: dbus.socket: Deactivated successfully.1087second # [ 4.414444] systemd[1]: Closed D-Bus System Message Bus Socket.1088second # [ 4.414950] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1089second # [ 4.415563] systemd[1]: Stopped Find NixOS closure.1090second # [ 4.415971] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1091test # [ 4.428312] systemd[1]: Stopped target Path Units.1092test # [ 4.429351] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1093test # [ 4.430347] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1094second # [ 4.420220] systemd[1]: Starting rw-sysroot-nix-store.service...1095second # [ 4.420839] systemd[1]: systemd-sysctl.service: Deactivated successfully.1096test # [ 4.431964] systemd[1]: Stopped target Slice Units.1097second # [ 4.421516] systemd[1]: Stopped Apply Kernel Variables.1098second # [ 4.422782] systemd[1]: systemd-modules-load.service: Deactivated successfully.1099second # [ 4.424458] systemd[1]: Stopped Load Kernel Modules.1100test # [ 4.436172] systemd[1]: Stopped target Socket Units.1101test # [ 4.436868] systemd[1]: Stopped target System Initialization.1102second # [ 4.426248] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1103test # [ 4.437573] systemd[1]: Stopped target Swaps.1104test # [ 4.438092] systemd[1]: Stopped target Timer Units.1105test # [ 4.438654] systemd[1]: dbus.socket: Deactivated successfully.1106test # [ 4.439365] systemd[1]: Closed D-Bus System Message Bus Socket.1107second # [ 4.428772] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1108second # [ 4.430278] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1109second # [ 4.432161] systemd[1]: Stopped Create System Files and Directories.1110second # [ 4.433415] systemd[1]: Stopped target Local File Systems.1111test # [ 4.444151] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1112test # [ 4.445175] systemd[1]: Stopped Find NixOS closure.1113second # [ 4.434038] systemd[1]: Stopped target Preparation for Local File Systems.1114second # [ 4.434648] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1115second # [ 4.435255] systemd[1]: Stopped Coldplug All udev Devices.1116test # [ 4.445730] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1117second # [ 4.435743] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1118test # [ 4.446859] systemd[1]: Starting rw-sysroot-nix-store.service...1119test # [ 4.450237] systemd[1]: systemd-sysctl.service: Deactivated successfully.1120test # [ 4.451927] systemd[1]: Stopped Apply Kernel Variables.1121second # [ 4.442623] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1122test # [ 4.453785] systemd[1]: systemd-modules-load.service: Deactivated successfully.1123test # [ 4.456082] systemd[1]: Stopped Load Kernel Modules.1124second # [ 4.445601] systemd[1]: Stopped Virtual Console Setup.1125second # [ 4.447228] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1126second # [ 4.449011] systemd[1]: Finished rw-sysroot-nix-store.service.1127test # [ 4.460187] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1128second # [ 4.449591] systemd[1]: systemd-udevd.service: Deactivated successfully.1129test # [ 4.460953] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1130second # [ 4.452271] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1131test # [ 4.462042] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1132second # [ 4.452927] systemd[1]: systemd-udevd.service: Consumed 1.010s CPU time over 2.996s wall clock time, 21.7M memory peak.1133second # [ 4.453782] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1134second # [ 4.454377] systemd[1]: Closed udev Control Socket.1135test # [ 4.465579] systemd[1]: Stopped Create System Files and Directories.1136second # [ 4.454808] systemd[1]: Starting Cleanup udev Database...1137test # [ 4.466259] systemd[1]: Stopped target Local File Systems.1138second # [ 4.455269] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1139second # [ 4.455912] systemd[1]: Stopped Create Static Device Nodes in /dev.1140test # [ 4.468310] systemd[1]: Stopped target Preparation for Local File Systems.1141test # [ 4.469440] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1142second # [ 4.460210] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1143test # [ 4.471588] systemd[1]: Stopped Coldplug All udev Devices.1144second # [ 4.461305] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1145test # [ 4.472753] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1146second # [ 4.462030] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1147test # [ 4.473453] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1148second # [ 4.462647] systemd[1]: Stopped Create List of Static Device Nodes.1149second # [ 4.463188] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1150test # [ 4.474107] systemd[1]: Stopped Virtual Console Setup.1151test # [ 4.474717] systemd[1]: systemd-udevd.service: Deactivated successfully.1152second # [ 4.463805] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1153test # [ 4.484190] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1154test # [ 4.484934] systemd[1]: initrd-cleanup.service: Deactivated successfully.1155test # [ 4.486740] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1156test # [ 4.487561] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1157test # [ 4.489505] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1158test # [ 4.491462] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1159test # [ 4.492164] systemd[1]: Finished rw-sysroot-nix-store.service.1160test # [ 4.493384] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1161test # [ 4.494711] systemd[1]: Closed udev Control Socket.1162test # [ 4.495751] systemd[1]: Starting Cleanup udev Database...1163second # [ 4.485255] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1164second # [ 4.486994] systemd[1]: Finished Cleanup udev Database.1165second # [ 4.487547] systemd[1]: Reached target Switch Root.1166test # [ 4.499628] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1167test # [ 4.500621] systemd[1]: Stopped Create Static Device Nodes in /dev.1168test # [ 4.501205] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1169test # [ 4.501866] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1170test # [ 4.502478] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1171test # [ 4.503090] systemd[1]: Stopped Create List of Static Device Nodes.1172second # [ 4.493037] systemd[1]: Starting NixOS Activation...1173test # [ 4.527995] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1174test # [ 4.530461] systemd[1]: Finished Cleanup udev Database.1175test # [ 4.530962] systemd[1]: Reached target Switch Root.1176test # [ 4.531654] systemd[1]: Starting NixOS Activation...1177second # [ 4.657629] initrd-nixos-activation-start[178]: booting system configuration /nix/store/dl2aabs0kza68ac6qwixlrs1a1hj5yb5-nixos-system-second-test1178test # [ 4.700251] initrd-nixos-activation-start[179]: booting system configuration /nix/store/vnx28pnp5dxll0qi02770d6mgc991wx7-nixos-system-test-test1179second # [ 4.713681] initrd-nixos-activation-start[178]: running activation script...1180test # [ 4.764848] initrd-nixos-activation-start[179]: running activation script...1181second # [ 5.165337] initrd-nixos-activation-start[202]: setting up /etc...1182test # [ 5.220262] initrd-nixos-activation-start[202]: setting up /etc...1183second # [ 5.438246] initrd-nixos-activation-start[202]: setting up age secrets...1184test # [ 5.499164] initrd-nixos-activation-start[202]: setting up age secrets...1185second # [ 5.682503] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1186second # [ 5.684133] systemd[1]: Finished NixOS Activation.1187second # [ 5.685096] systemd[1]: Starting Switch Root...1188second # [ 5.700716] systemd[1]: Switching root.1189second # [ 5.754136] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1190test # [ 5.833241] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191test # [ 5.835015] systemd[1]: Finished NixOS Activation.1192test # [ 5.835773] systemd[1]: Starting Switch Root...1193test # [ 5.852791] systemd[1]: Switching root.1194test # [ 5.912148] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195second # [ 6.331239] 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)1196second # [ 6.339023] systemd[1]: Detected virtualization qemu.1197second # [ 6.340690] systemd[1]: Detected architecture arm64.1198second # [ 6.342800] systemd[1]: Detected first boot.1199second # [ 6.347711] systemd[1]: Initializing machine ID from random generator.1200test # [ 6.502861] 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)1201test # [ 6.510102] systemd[1]: Detected virtualization qemu.1202test # [ 6.511969] systemd[1]: Detected architecture arm64.1203test # [ 6.513687] systemd[1]: Detected first boot.1204test # [ 6.518051] systemd[1]: Initializing machine ID from random generator.1205second # [ 6.638321] systemd[1]: bpf-restrict-fs: LSM BPF program attached1206test # [ 6.815421] systemd[1]: bpf-restrict-fs: LSM BPF program attached1207second # [ 6.827112] systemd[1]: Applying preset policy.1208test # [ 6.991221] systemd[1]: Applying preset policy.1209second # [ 7.415992] systemd[1]: Populated /etc with preset unit settings.1210test # [ 7.542868] systemd[1]: Populated /etc with preset unit settings.1211second # [ 7.975492] systemd[1]: initrd-switch-root.service: Deactivated successfully.1212second # [ 7.976362] systemd[1]: Stopped initrd-switch-root.service.1213second # [ 7.978627] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1214second # [ 7.981131] systemd[1]: Created slice Slice /system/getty.1215second # [ 7.982405] systemd[1]: Created slice User and Session Slice.1216second # [ 7.983525] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1217second # [ 7.984742] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1218second # [ 7.986078] systemd[1]: Expecting device /dev/hvc0...1219second # [ 7.987544] systemd[1]: Expecting device /dev/ttyAMA0...1220second # [ 7.989061] systemd[1]: Reached target Local Encrypted Volumes.1221second # [ 7.990595] systemd[1]: Stopped target initrd-fs.target.1222second # [ 7.992080] systemd[1]: Stopped target initrd-root-fs.target.1223second # [ 7.993023] systemd[1]: Stopped target initrd-switch-root.target.1224second # [ 7.994185] systemd[1]: Reached target Virtual Machines and Containers.1225second # [ 7.995258] systemd[1]: Reached target Path Units.1226second # [ 7.996171] systemd[1]: Reached target Remote File Systems.1227second # [ 7.997142] systemd[1]: Reached target Slice Units.1228second # [ 7.998118] systemd[1]: Reached target Swaps.1229second # [ 8.002586] systemd[1]: Listening on Process Core Dump Socket.1230second # [ 8.006588] systemd[1]: Listening on Credential Encryption/Decryption.1231second # [ 8.012055] systemd[1]: Starting Journal Log Access Socket...1232second # [ 8.014243] systemd[1]: Listening on Journal Audit Socket.1233second # [ 8.015986] systemd[1]: Listening on Network Management Metrics Varlink Socket.1234second # [ 8.017763] systemd[1]: Listening on Network Management Netlink Socket.1235second # [ 8.019511] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1236second # [ 8.021120] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1237second # [ 8.023256] systemd[1]: Listening on udev Control Socket.1238second # [ 8.028278] systemd[1]: Mounting Huge Pages File System...1239second # [ 8.032624] systemd[1]: Mounting POSIX Message Queue File System...1240second # [ 8.038495] systemd[1]: Mounting Kernel Debug File System...1241test # [ 8.060343] systemd[1]: initrd-switch-root.service: Deactivated successfully.1242test # [ 8.061214] systemd[1]: Stopped initrd-switch-root.service.1243second # [ 8.044809] systemd[1]: Mounting Kernel Trace File System...1244test # [ 8.063375] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1245test # [ 8.065691] systemd[1]: Created slice Slice /system/getty.1246test # [ 8.067477] systemd[1]: Created slice User and Session Slice.1247test # [ 8.068790] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1248test # [ 8.070128] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1249test # [ 8.071638] systemd[1]: Expecting device /dev/hvc0...1250test # [ 8.072699] systemd[1]: Expecting device /dev/ttyAMA0...1251test # [ 8.073791] systemd[1]: Reached target Local Encrypted Volumes.1252second # [ 8.058420] systemd[1]: Starting Create List of Static Device Nodes...1253test # [ 8.075381] systemd[1]: Stopped target initrd-fs.target.1254test # [ 8.076280] systemd[1]: Stopped target initrd-root-fs.target.1255test # [ 8.077784] systemd[1]: Stopped target initrd-switch-root.target.1256test # [ 8.079363] systemd[1]: Reached target Virtual Machines and Containers.1257test # [ 8.080389] systemd[1]: Reached target Path Units.1258test # [ 8.081299] systemd[1]: Reached target Remote File Systems.1259test # [ 8.082247] systemd[1]: Reached target Slice Units.1260second # [ 8.066282] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1261test # [ 8.083704] systemd[1]: Reached target Swaps.1262second # [ 8.067935] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1263test # [ 8.088269] systemd[1]: Listening on Process Core Dump Socket.1264test # [ 8.091951] systemd[1]: Listening on Credential Encryption/Decryption.1265test # [ 8.096743] systemd[1]: Starting Journal Log Access Socket...1266second # [ 8.082217] systemd[1]: Mounting Kernel Configuration File System...1267test # [ 8.098803] systemd[1]: Listening on Journal Audit Socket.1268second # [ 8.083947] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1269test # [ 8.100680] systemd[1]: Listening on Network Management Metrics Varlink Socket.1270second # [ 8.086216] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1271test # [ 8.102641] systemd[1]: Listening on Network Management Netlink Socket.1272test # [ 8.104575] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1273test # [ 8.106169] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1274test # [ 8.108782] systemd[1]: Listening on udev Control Socket.1275second # [ 8.095538] systemd[1]: Starting Load Kernel Module fuse...1276test # [ 8.112748] systemd[1]: Mounting Huge Pages File System...1277second # [ 8.098439] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671278test # [ 8.116780] systemd[1]: Mounting POSIX Message Queue File System...1279test # [ 8.123295] systemd[1]: Mounting Kernel Debug File System...1280test # [ 8.130787] systemd[1]: Mounting Kernel Trace File System...1281test # [ 8.140513] systemd[1]: Starting Create List of Static Device Nodes...1282test # [ 8.147350] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1283test # [ 8.148845] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1284test # [ 8.159574] systemd[1]: Mounting Kernel Configuration File System...1285test # [ 8.161681] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1286second # [ 8.146599] systemd[1]: Starting Journal Service...1287test # [ 8.166117] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1288test # [ 8.175841] systemd[1]: Starting Load Kernel Module fuse...1289test # [ 8.178727] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671290second # [ 8.172915] systemd[1]: Starting Load Kernel Modules...1291second # [ 8.190437] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1292second # [ 8.207548] systemd[1]: Starting Remount Root and Kernel File Systems...1293test # [ 8.229291] systemd[1]: Starting Journal Service...1294second # [ 8.215878] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1295test # [ 8.259710] systemd[1]: Starting Load Kernel Modules...1296second # [ 8.259736] systemd[1]: Starting Coldplug All udev Devices...1297second # [ 8.271136] systemd[1]: Listening on Journal Log Access Socket.1298second # [ 8.276851] fuse: init (API version 7.45)1299test # [ 8.293308] fuse: init (API version 7.45)1300test # [ 8.294490] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1301second # [ 8.281421] systemd[1]: Mounted Huge Pages File System.1302second # [ 8.286019] systemd[1]: Mounted POSIX Message Queue File System.1303second # [ 8.288255] systemd[1]: Mounted Kernel Debug File System.1304second # [ 8.291016] systemd-journald[301]: Collecting audit messages is enabled.1305second # [ 8.299056] systemd[1]: Mounted Kernel Trace File System.1306test # [ 8.317356] systemd[1]: Starting Remount Root and Kernel File Systems...1307test # [ 8.318681] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1308test # [ 8.323223] systemd[1]: Starting Coldplug All udev Devices...1309second # [ 8.305566] systemd[1]: Queued start job for default target Multi-User System.1310second # [ 8.306509] systemd[1]: systemd-journald.service: Deactivated successfully.1311second # [ 8.307377] systemd-modules-load[302]: Module 'atkbd' is built in1312second # [ 8.313797] systemd[1]: Finished Create List of Static Device Nodes.1313second # [ 8.314361] systemd[1]: Started Journal Service.1314second # [ 8.312751] systemd-modules-load[302]: Module 'ctr' is built in1315second # [ 8.313780] systemd-modules-load[302]: Module 'loop' is built in1316second # [ 8.315877] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1317second # [ 8.321252] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1318second # [ 8.322486] systemd[1]: Mounted Kernel Configuration File System.1319second # [ 8.325818] systemd[1]: Finished Load Kernel Modules.1320test # [ 8.347460] systemd[1]: Listening on Journal Log Access Socket.1321second # [ 8.332502] systemd[1]: modprobe@fuse.service: Deactivated successfully.1322second # [ 8.333184] systemd[1]: Finished Load Kernel Module fuse.1323test # [ 8.358636] systemd[1]: Mounted Huge Pages File System.1324test # [ 8.359180] systemd[1]: Mounted POSIX Message Queue File System.1325test # [ 8.359588] systemd[1]: Mounted Kernel Debug File System.1326test # [ 8.359961] systemd[1]: Mounted Kernel Trace File System.1327second # [ 8.346965] systemd[1]: Mounting FUSE Control File System...1328test # [ 8.369091] systemd[1]: Finished Create List of Static Device Nodes.1329second # [ 8.350582] systemd[1]: Starting Firewall...1330test # [ 8.371037] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1331second # [ 8.357228] systemd[1]: Starting Apply Kernel Variables...1332test # [ 8.379493] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1333test # [ 8.382231] systemd[1]: Mounted Kernel Configuration File System.1334second # [ 8.369597] EXT4-fs (vda): re-mounted 993ae094-1997-4a07-9e01-c1efb38c5016.1335test # [ 8.386598] systemd[1]: modprobe@fuse.service: Deactivated successfully.1336second # [ 8.367757] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1337test # [ 8.391133] systemd[1]: Finished Load Kernel Module fuse.1338second # [ 8.375928] systemd-oomd[303]: No swap; memory pressure usage will be degraded1339test # [ 8.403280] systemd[1]: Mounting FUSE Control File System...1340second # [ 8.385224] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1341second # [ 8.388092] systemd[1]: Finished Remount Root and Kernel File Systems.1342test # [ 8.411446] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1343test # [ 8.415505] systemd[1]: Finished Load Kernel Modules.1344test # [ 8.419217] systemd-journald[322]: Collecting audit messages is enabled.1345test # [ 8.422394] systemd[1]: Queued start job for default target Multi-User System.1346second # [ 8.412491] systemd[1]: Starting Flush Journal to Persistent Storage...1347test # [ 8.424110] systemd[1]: systemd-journald.service: Deactivated successfully.1348test # [ 8.435128] systemd[1]: Starting Firewall...1349second # [ 8.414766] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1350test # [ 8.427298] systemd-modules-load[323]: Module 'atkbd' is built in1351test # [ 8.430874] systemd-modules-load[323]: Module 'ctr' is built in1352second # [ 8.422158] systemd[1]: Starting Load/Save OS Random Seed...1353second # [ 8.425917] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1354test # [ 8.438130] systemd-modules-load[323]: Module 'loop' is built in1355test # [ 8.449293] systemd-oomd[324]: No swap; memory pressure usage will be degraded1356test # [ 8.462437] EXT4-fs (vda): re-mounted 56d4a911-a653-4e8e-b22f-44cb1c532108.1357test # [ 8.466066] systemd[1]: Starting Apply Kernel Variables...1358test # [ 8.477401] systemd[1]: Started Journal Service.1359test # [ 8.470291] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1360test # [ 8.485177] systemd[1]: Finished Remount Root and Kernel File Systems.1361second # [ 8.506026] systemd[1]: Mounted FUSE Control File System.1362second # [ 8.510078] systemd[1]: Finished Apply Kernel Variables.1363test # [ 8.525406] systemd[1]: Starting Flush Journal to Persistent Storage...1364test # [ 8.526217] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1365second # [ 8.518832] systemd[1]: Starting Network Name Resolution...1366test # [ 8.531444] systemd[1]: Starting Load/Save OS Random Seed...1367test # [ 8.536262] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1368second # [ 8.542832] systemd-journald[301]: Received client request to flush runtime journal.1369test # [ 8.565380] systemd[1]: Mounted FUSE Control File System.1370test # [ 8.612111] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1371second # [ 8.606006] systemd[1]: Finished Load/Save OS Random Seed.1372test # [ 8.626844] systemd[1]: Starting Create Static Device Nodes in /dev...1373second # [ 8.620366] systemd[1]: Reached target First Boot Complete.1374second # [ 8.620926] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1375second # [ 8.625854] systemd[1]: Starting Create Static Device Nodes in /dev...1376test # [ 8.646852] systemd-journald[322]: Received client request to flush runtime journal.1377second # [ 8.636835] systemd[1]: Finished Flush Journal to Persistent Storage.1378test # [ 8.696833] systemd[1]: Finished Apply Kernel Variables.1379test # [ 8.703584] systemd[1]: Finished Load/Save OS Random Seed.1380test # [ 8.712389] systemd[1]: Reached target First Boot Complete.1381test # [ 8.714763] systemd[1]: Starting Network Name Resolution...1382second # [ 8.704972] systemd-resolved[330]: Positive Trust Anchors:1383test # [ 8.719152] systemd[1]: Finished Flush Journal to Persistent Storage.1384second # [ 8.707533] systemd-resolved[330]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1385second # [ 8.709003] systemd-resolved[330]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161386second # [ 8.711263] systemd-resolved[330]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1387second # [ 8.748310] systemd[1]: Finished Create Static Device Nodes in /dev.1388second # [ 8.748970] systemd[1]: Reached target Preparation for Local File Systems.1389second # [ 8.752576] systemd[1]: Starting Rule-based Manager for Device Events and Files...1390test # [ 8.783711] systemd[1]: Finished Create Static Device Nodes in /dev.1391test # [ 8.784483] systemd[1]: Reached target Preparation for Local File Systems.1392test # [ 8.793840] systemd[1]: Starting Rule-based Manager for Device Events and Files...1393test # [ 8.825150] systemd-resolved[356]: Positive Trust Anchors:1394test # [ 8.826804] systemd-resolved[356]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1395test # [ 8.830518] systemd-resolved[356]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161396test # [ 8.834332] systemd-resolved[356]: 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 test1397second # [ 8.875720] systemd-udevd[346]: Using default interface naming scheme 'v260'.1398test # [ 8.941419] systemd-udevd[364]: Using default interface naming scheme 'v260'.1399second # [ 8.962945] systemd-resolved[330]: Using system hostname 'second'.1400second # [ 8.969280] systemd[1]: Started Network Name Resolution.1401second # [ 8.978767] systemd[1]: Mounting /run/wrappers...1402test # [ 9.055244] systemd[1]: Mounting /run/wrappers...1403second # [ 9.045293] systemd[1]: Mounted /run/wrappers.1404second # [ 9.045921] systemd[1]: Reached target Local File Systems.1405second # [ 9.049091] systemd[1]: Listening on Boot Loader Control Service Socket.1406second # [ 9.054862] systemd[1]: Starting register-nix-paths.service...1407second # [ 9.058075] systemd[1]: Starting Create SUID/SGID Wrappers...1408test # [ 9.070725] systemd-resolved[356]: Using system hostname 'test'.1409second # [ 9.060163] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1410second # [ 9.066231] systemd[1]: Starting Save Transient machine-id to Disk...1411test # [ 9.078752] systemd[1]: Started Network Name Resolution.1412second # [ 9.094942] systemd[1]: Starting Create System Files and Directories...1413test # [ 9.134340] systemd[1]: Mounted /run/wrappers.1414test # [ 9.135005] systemd[1]: Reached target Local File Systems.1415test # [ 9.138742] systemd[1]: Listening on Boot Loader Control Service Socket.1416test # [ 9.142560] systemd[1]: Starting register-nix-paths.service...1417test # [ 9.149949] systemd[1]: Starting Create SUID/SGID Wrappers...1418test # [ 9.152710] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419test # [ 9.158975] systemd[1]: Starting Save Transient machine-id to Disk...1420test # [ 9.172062] systemd[1]: Starting Create System Files and Directories...1421second # [ 9.205333] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1422second # [ 9.218178] systemd[1]: Finished Save Transient machine-id to Disk.1423second # [ 9.268688] systemd[1]: Finished Create System Files and Directories.1424test # [ 9.279601] systemd[1]: Started Rule-based Manager for Device Events and Files.1425second # [ 9.275294] systemd[1]: Started Rule-based Manager for Device Events and Files.1426second # [ 9.289204] systemd[1]: Starting Rebuild Journal Catalog...1427second # [ 9.293015] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1428test # [ 9.317234] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1429test # [ 9.336306] systemd[1]: Finished Save Transient machine-id to Disk.1430test # [ 9.370215] systemd[1]: Finished Create System Files and Directories.1431test # [ 9.384435] systemd[1]: Starting Rebuild Journal Catalog...1432test # [ 9.397445] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1433second # [ 9.419670] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1434test # [ 9.524831] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1435second # [ 9.521965] systemd[1]: Finished Rebuild Journal Catalog.1436second # [ 9.528332] systemd[1]: Starting Update is Completed...1437second # [ 9.599342] systemd[1]: Finished Update is Completed.1438test # [ 9.611821] systemd[1]: Finished Rebuild Journal Catalog.1439test # [ 9.626270] systemd[1]: Starting Update is Completed...1440test # [ 9.668598] systemd[1]: Finished Coldplug All udev Devices.1441second # [ 9.671773] systemd[1]: Finished Coldplug All udev Devices.1442test # [ 9.705793] systemd[1]: Finished Update is Completed.1443second # [ 9.830131] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1444test # [ 9.864229] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1445second # [ 10.096123] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1446test # [ 10.129628] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1447second # [ 10.159711] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1448second # [ 10.162058] systemd[1]: Finished Create SUID/SGID Wrappers.1449second # [ 10.171979] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1450test # [ 10.208247] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1451test # [ 10.219539] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1452test # [ 10.222257] systemd[1]: Finished Create SUID/SGID Wrappers.1453second # [ 10.343960] systemd[1]: Finished Firewall.1454second # [ 10.346549] systemd[1]: Reached target Preparation for Network.1455second # [ 10.350218] systemd[1]: Starting Network Management...1456second # [ 10.407626] systemd[1]: Finished register-nix-paths.service.1457test # [ 10.422520] systemd[1]: Finished Firewall.1458test # [ 10.424401] systemd[1]: Reached target Preparation for Network.1459second # [ 10.414431] systemd[1]: Reached target System Initialization.1460second # [ 10.414989] systemd[1]: Started Discard unused filesystem blocks once a week.1461second # [ 10.417663] systemd[1]: Started Daily Cleanup of Temporary Directories.1462test # [ 10.429174] systemd[1]: Starting Network Management...1463second # [ 10.419091] systemd[1]: Reached target Timer Units.1464second # [ 10.424225] systemd[1]: Listening on D-Bus System Message Bus Socket.1465second # [ 10.424814] systemd[1]: Listening on Nix Daemon Socket.1466second # [ 10.430585] systemd[1]: Listening on Authorization Manager Agent Helper.1467second # [ 10.431491] systemd[1]: Listening on Hostname Service Socket.1468second # [ 10.436436] systemd[1]: Reached target Socket Units.1469second # [ 10.438317] systemd[1]: Starting D-Bus System Message Bus...1470test # [ 10.457033] systemd[1]: Finished register-nix-paths.service.1471test # [ 10.458959] systemd[1]: Reached target System Initialization.1472test # [ 10.461083] systemd[1]: Started Discard unused filesystem blocks once a week.1473test # [ 10.463098] systemd[1]: Started Daily Cleanup of Temporary Directories.1474test # [ 10.466511] systemd[1]: Reached target Timer Units.1475test # [ 10.468839] systemd[1]: Listening on D-Bus System Message Bus Socket.1476test # [ 10.471524] systemd[1]: Listening on Nix Daemon Socket.1477test # [ 10.475119] systemd[1]: Listening on Authorization Manager Agent Helper.1478test # [ 10.476733] systemd[1]: Listening on Hostname Service Socket.1479test # [ 10.478361] systemd[1]: Reached target Socket Units.1480test # [ 10.481189] systemd[1]: Starting D-Bus System Message Bus...1481second # [ 10.472802] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line.1482second # [ 10.478450] (udev-worker)[393]: Network interface NamePolicy= disabled on kernel command line.1483test # [ 10.502240] (udev-worker)[475]: Network interface NamePolicy= disabled on kernel command line.1484test # [ 10.505465] (udev-worker)[465]: Network interface NamePolicy= disabled on kernel command line.1485second # [ 10.593222] cfg80211: Loading compiled-in X.509 certificates for regulatory database1486second # [ 10.634227] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1487second # [ 10.634751] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1488second # [ 10.631935] dbus-broker-launch[536]: Looking up NSS user entry for 'systemd-timesync'...1489test # [ 10.671481] cfg80211: Loading compiled-in X.509 certificates for regulatory database1490second # [ 10.651258] dbus-broker-launch[536]: NSS returned no entry for 'systemd-timesync'1491second # [ 10.652640] dbus-broker-launch[536]: Invalid user-name in /nix/store/f3v06a6b3g5mqzbl86aqgspihyk4yacj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1492test # [ 10.677781] dbus-broker-launch[558]: Looking up NSS user entry for 'systemd-timesync'...1493test # [ 10.695753] dbus-broker-launch[558]: NSS returned no entry for 'systemd-timesync'1494test # [ 10.698115] dbus-broker-launch[558]: Invalid user-name in /nix/store/f3v06a6b3g5mqzbl86aqgspihyk4yacj-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1495second # [ 10.692286] systemd-networkd[533]: /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.1496test # [ 10.714500] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1497test # [ 10.715020] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1498second # [ 10.699171] systemd-networkd[533]: /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.1499second # [ 10.707940] systemd-networkd[533]: lo: Link UP1500second # [ 10.710928] systemd-networkd[533]: lo: Gained carrier1501second # [ 10.714642] systemd[1]: Started Network Management.1502second # [ 10.718842] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1503second # [ 10.726072] systemd-networkd[533]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1504second # [ 10.726786] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1505second # [ 10.727404] systemd-networkd[533]: eth1: Link UP1506second # [ 10.727815] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1507second # [ 10.735638] mousedev: PS/2 mouse device common for all mice1508test # [ 10.752518] mousedev: PS/2 mouse device common for all mice1509second # [ 10.741057] systemd-networkd[533]: eth1: Gained carrier1510second # [ 10.744263] systemd-networkd[533]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1511test # [ 10.755474] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1512test # [ 10.758618] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1513second # [ 10.747802] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/nc2n2qi8lq7i3sr8wh2s74k57v8ivb7b-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1514test # [ 10.764617] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1515second # [ 10.754839] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/had2hwcdifwqkpz3ji6sxkvg8yb164rc-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1516test # [ 10.769733] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1517second # [ 10.760497] dbus-broker-launch[536]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/42037x5cj27f8dawijnxzb26db99wwxy-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1518second # [ 10.766610] systemd[1]: Started D-Bus System Message Bus.1519test # [ 10.780328] systemd-networkd[555]: /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.1520test # [ 10.785995] systemd-networkd[555]: /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.1521test # [ 10.791926] systemd[1]: Started D-Bus System Message Bus.1522test # [ 10.795143] systemd-networkd[555]: lo: Link UP1523test # [ 10.795800] systemd-networkd[555]: lo: Gained carrier1524test # [ 10.799355] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1525test # [ 10.802939] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1526test # [ 10.807058] systemd-networkd[555]: eth1: Link UP1527test # [ 10.808356] systemd-networkd[555]: eth1: Gained carrier1528test # [ 10.812444] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1529second # [ 10.806571] dbus-broker-launch[536]: Ready1530second # [ 10.813793] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1531second # [ 10.815710] systemd[1]: Reached target Basic System.1532second # [ 10.818687] systemd[1]: Starting Network Manager Script Dispatcher Service...1533second # [ 10.825269] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1534test # [ 10.839622] dbus-broker-launch[558]: Ready1535test # [ 10.841699] systemd[1]: Started Network Management.1536test # [ 10.843350] systemd[1]: Reached target Basic System.1537second # [ 10.835141] systemd[1]: Starting Network Manager...1538test # [ 10.850470] systemd[1]: Starting Network Manager Script Dispatcher Service...1539test # [ 10.853525] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1540second # [ 10.846379] systemd[1]: Started backdoor.service.1541test # [ 10.858502] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1542test # [ 10.868663] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1543test # [ 10.882831] systemd[1]: Starting Network Manager...1544second # [ 10.876852] systemd[1]: Starting Import lastlog data into lastlog2 database...1545test # [ 10.914207] systemd[1]: Started backdoor.service.1546second # [ 10.904244] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1547second # [ 10.917123] systemd[1]: Starting Post-Boot Actions...1548second # [ 10.932540] systemd[1]: Started Reset console on configuration changes.1549test # [ 10.947828] systemd[1]: Starting Import lastlog data into lastlog2 database...1550test # [ 10.961305] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1551second # [ 10.961926] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[549]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1552second # [ 10.993284] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1553test # [ 11.010587] systemd[1]: Starting Post-Boot Actions...1554second # [ 11.012206] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1555test # [ 11.023680] 8g1rx2i5wgny7771myl54j8za1ccscsg-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1556second # connecting to host...1557test # [ 11.033721] rssf13kza7rj2jljq39fngbf0z7n3am5-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1558test # [ 11.042654] 1n5h5lsamc1iqqvp1xy6dcsj8mpn1yiw-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1559test # [ 11.057393] systemd[1]: Started Reset console on configuration changes.1560test # [ 11.082858] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1561test # [ 11.086254] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1562test # connecting to host...1563second # [ 11.106713] systemd[1]: Finished Post-Boot Actions.1564second # [ 11.111753] systemd[1]: Finished Import lastlog data into lastlog2 database.1565test # [ 11.131953] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1566test # [ 11.138161] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1567test # [ 11.144768] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1568test # [ 11.148926] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1569second # [ 11.142068] nsncd[554]: Sep 04 00:19:06.935 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1570test # [ 11.156551] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1571second # [ 11.149007] systemd[1]: Started Name Service Cache Daemon (nsncd).1572second # [ 11.151176] systemd[1]: Reached target Host and Network Name Lookups.1573second # [ 11.155462] systemd[1]: Reached target User and Group Name Lookups.1574second # [ 11.158861] systemd[1]: Starting User Login Management...1575test: Guest shell says: b'Spawning backdoor root shell...\n'1576test: connected to guest root shell1577test: (connecting took 11.52 seconds)1578test: (finished: waiting for the VM to finish booting, in 11.52 seconds)1579test # [ 11.183286] systemd[1]: Finished Post-Boot Actions.1580second # [ 11.174611] systemd[1]: Started Network Manager Script Dispatcher Service.1581test # [ 11.212650] nsncd[575]: Sep 04 00:19:07.024 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1582test # [ 11.219895] systemd[1]: Started Name Service Cache Daemon (nsncd).1583test # [ 11.250529] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1584test # [ 11.256415] systemd[1]: Reached target Host and Network Name Lookups.1585test # [ 11.259092] systemd[1]: Reached target User and Group Name Lookups.1586test # [ 11.271667] systemd[1]: Starting User Login Management...1587second # [ 11.271225] systemd[1]: Starting Hostname Service...1588test # [ 11.299193] systemd[1]: Started Network Manager Script Dispatcher Service.1589test # [ 11.309799] systemd[1]: Finished Import lastlog data into lastlog2 database.1590second # [ 11.302921] systemd-logind[585]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1591second # [ 11.307009] systemd-logind[585]: Watching system buttons on /dev/input/event0 (gpio-keys)1592second # [ 11.311706] systemd-logind[585]: New seat seat0.1593second # [ 11.312947] systemd[1]: Started User Login Management.1594second # [ 11.316889] systemd[1]: Starting linger-users.service...1595test # [ 11.401944] systemd[1]: Starting Hostname Service...1596second # [ 11.393444] systemd[1]: linger-users.service: Deactivated successfully.1597second # [ 11.394781] systemd[1]: Finished linger-users.service.1598second # [ 11.406628] systemd[1]: Started Hostname Service.1599test # [ 11.430651] systemd-logind[620]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1600test # [ 11.436212] systemd-logind[620]: Watching system buttons on /dev/input/event0 (gpio-keys)1601test # [ 11.439520] systemd-logind[620]: New seat seat0.1602test # [ 11.441159] systemd[1]: Started User Login Management.1603test # [ 11.444958] systemd[1]: Starting linger-users.service...1604second # [ 11.497343] systemd-networkd[533]: eth0: Link UP1605second # [ 11.497842] systemd-networkd[533]: eth0: Gained carrier1606test # [ 11.516724] systemd[1]: linger-users.service: Deactivated successfully.1607test # [ 11.517767] systemd[1]: Finished linger-users.service.1608second # [ 11.518164] systemd[1]: Started Network Manager.1609second # [ 11.520080] systemd[1]: Reached target Network.1610test # [ 11.533528] systemd[1]: Started Hostname Service.1611second # [ 11.526749] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1612second # [ 11.531347] systemd[1]: Starting Permit User Sessions...1613test # [ 11.630914] systemd-networkd[555]: eth0: Link UP1614test # [ 11.631404] systemd-networkd[555]: eth0: Gained carrier1615test # [ 11.650198] systemd[1]: Started Network Manager.1616test # [ 11.651655] systemd[1]: Reached target Network.1617test # [ 11.657016] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1618test # [ 11.663925] systemd[1]: Starting Permit User Sessions...1619second # [ 11.687395] systemd[1]: Finished Permit User Sessions.1620second # [ 11.693584] systemd[1]: Started Getty on tty1.1621second # [ 11.696270] systemd[1]: Reached target Login Prompts.1622second # [ 11.770171] systemd-resolved[330]: eth0: Bus client set default route setting: yes1623second # [ 11.772559] systemd-resolved[330]: eth0: Bus client set DNS server list to: 10.0.2.31624test # [ 11.820829] systemd[1]: Finished Permit User Sessions.1625test # [ 11.828528] systemd[1]: Started Getty on tty1.1626second # [ 11.819025] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1627second # [ 11.821823] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1628test # [ 11.833825] systemd[1]: Reached target Login Prompts.1629second # [ 11.833002] systemd[1]: Reached target Multi-User System.1630second # [ 11.837016] systemd[1]: Startup finished in 756ms (kernel) + 5.084s (initrd) + 5.992s (userspace) = 11.833s.1631test # [ 11.868401] systemd-resolved[356]: eth0: Bus client set default route setting: yes1632test # [ 11.874594] systemd-resolved[356]: eth0: Bus client set DNS server list to: 10.0.2.31633test # [ 11.963362] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1634test # [ 11.964636] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1635test # [ 11.969609] systemd-networkd[555]: eth1: Gained IPv6LL1636test # [ 12.111529] systemd[1]: Reached target Multi-User System.1637test # [ 12.115574] systemd[1]: Startup finished in 737ms (kernel) + 5.257s (initrd) + 6.117s (userspace) = 12.112s.1638second # [ 12.321045] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31639test: (finished: waiting for unit NetworkManager.service, in 12.78 seconds)1640test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1641test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1642test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1643test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1644test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1645test # [ 12.562463] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31646second # [ 12.545746] systemd-networkd[533]: eth1: Gained IPv6LL1647second # [ 12.567064] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1648test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.05 seconds)1649(finished: run the VM test script, in 13.41 seconds)1650second # [ 12.604594] systemd[1]: Starting Virtual Console Setup...1651second # [ 12.625603] systemd-logind[585]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1652test script finished in 13.48s1653cleanup1654kill QemuMachine (pid 14)1655second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13)1656kill QemuMachine (pid 15)1657test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/d6kq2phq0zy5invmkfgg8h35zxaf13s9-python3-3.13.15/bin/python3.13)1658(finished: cleanup, in 0.23 seconds)