vm-test-run-wifi
checks.aarch64-linux.wifi
· build #700
· 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 vm13second # Disk image does not exist, creating the virtualisation disk image...14second # Formatting '/build/vm-state-second/tmp.12y1eoh0JD', fmt=raw size=107374182415second # mke2fs 1.47.4 (6-Mar-2025)16second # Discarding device blocks: 0/262144 done17second # Creating filesystem with 262144 4k blocks and 65536 inodes18second # Filesystem UUID: 6903694c-da93-4331-adf0-eac2de76668b19second # Superblock backups stored on blocks:20second # 32768, 98304, 163840, 22937621second # 22second # Allocating group tables: 0/8 done23second # Writing inode tables: 0/8 done24second # Creating journal (8192 blocks): done25second # Writing superblocks and filesystem accounting information: 0/8 done26second # 27second # Virtualisation disk image created.28second: QEMU running (pid 15)29test # Disk image does not exist, creating the virtualisation disk image...30test: QEMU running (pid 14)31test # Formatting '/build/vm-state-test/tmp.r14ZDwrWda', fmt=raw size=107374182432test # mke2fs 1.47.4 (6-Mar-2025)33test # Discarding device blocks: 0/262144 done34test # Creating filesystem with 262144 4k blocks and 65536 inodes35test # Filesystem UUID: c20ff810-82c8-40ee-9446-981f605f127a36test # Superblock backups stored on blocks:37test # 32768, 98304, 163840, 22937638test # 39test # Allocating group tables: 0/8 done40test # Writing inode tables: 0/8 done41test # Creating journal (8192 blocks): done42test # Writing superblocks and filesystem accounting information: 0/8 done43test # 44test # Virtualisation disk image created.45(finished: start all VMs, in 0.46 seconds)46test: waiting for unit NetworkManager.service47test: waiting for the VM to finish booting48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202650second # [ 0.000000] KASLR enabled51second # [ 0.000000] random: crng init done52second # [ 0.000000] Machine model: linux,dummy-virt53second # [ 0.000000] efi: UEFI not found.54second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT55second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]56second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]57second # [ 0.000000] Zone ranges:58second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]59second # [ 0.000000] DMA32 empty60second # [ 0.000000] Normal empty61second # [ 0.000000] Device empty62second # [ 0.000000] Movable zone start for each node63second # [ 0.000000] Early memory node ranges64second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]65second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]66second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000067second # [ 0.000000] psci: probing for conduit method from DT.68second # [ 0.000000] psci: PSCIv1.3 detected in firmware.69second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs70second # [ 0.000000] psci: Trusted OS migration not required71second # [ 0.000000] psci: SMC Calling Convention v1.172second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)73second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129674second # [ 0.000000] Detected PIPT I-cache on CPU075second # [ 0.000000] CPU features: detected: GICv3 CPU interface76second # [ 0.000000] CPU features: detected: Spectre-v477second # [ 0.000000] CPU features: detected: Spectre-BHB78second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR79second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)80second # [ 0.000000] CPU features: detected: ARM erratum 141804081second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion82second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing83second # [ 0.000000] alternatives: applying boot alternatives84second # [ 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/j1v4crk8h06q6z20jhdz651agnyqifwh-nixos-system-second-test/init regInfo=/nix/store/i8jqfnw9nrhs6p579m8g58arlhgk5mws-closure-info/registration console=ttyAMA0,115200n8 console=tty085second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/i8jqfnw9nrhs6p579m8g58arlhgk5mws-closure-info/registration", will be passed to user space.86second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes87second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)88second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)89second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB90second # [ 0.000000] software IO TLB: area num 1.91second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)92second # [ 0.000000] Fallback order for Node 0: 093second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214494second # [ 0.000000] Policy zone: DMA95second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off96second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=197second # [ 0.000000] allocated 2097152 bytes of page_ext98second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages99second # [ 0.000000] ftrace: allocated 294 pages with 4 groups100second # [ 0.000000] rcu: Hierarchical RCU implementation.101second # [ 0.000000] rcu: RCU event tracing is enabled.102second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.103second # [ 0.000000] Trampoline variant of Tasks RCU enabled.104second # [ 0.000000] Rude variant of Tasks RCU enabled.105second # [ 0.000000] Tracing variant of Tasks RCU enabled.106second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.107second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1108second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.109second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.110second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.111second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0112second # [ 0.000000] GICv3: 256 SPIs implemented113second # [ 0.000000] GICv3: 0 Extended SPIs implemented114second # [ 0.000000] Root IRQ handler: gic_handle_irq115second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI116second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0117second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000118second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]119second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)120second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)121second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000122second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000123second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.124second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns125second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040126second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).127second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns128second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns129second # [ 0.000054] arm-pv: using stolen time PV130second # [ 0.000355] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)131second # [ 0.000628] Console: colour dummy device 80x25132second # [ 0.000640] printk: legacy console [tty0] enabled133second # [ 0.000865] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)134second # [ 0.000870] pid_max: default: 32768 minimum: 301135second # [ 0.000940] LSM: initializing lsm=capability,landlock,yama,bpf,ima136second # [ 0.001135] landlock: Up and running.137second # [ 0.001138] Yama: becoming mindful.138second # [ 0.001511] LSM support for eBPF active139second # [ 0.001683] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)140test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]141second # [ 0.001704] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)142second # [ 0.004306] rcu: Hierarchical SRCU implementation.143test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 2026144test # [ 0.000000] KASLR enabled145second # [ 0.004312] rcu: Max phase no-delay instances is 1000.146test # [ 0.000000] random: crng init done147second # [ 0.005287] fsl-mc MSI: its@8080000 domain created148test # [ 0.000000] Machine model: linux,dummy-virt149second # [ 0.005433] EFI services will not be available.150test # [ 0.000000] efi: UEFI not found.151second # [ 0.005525] smp: Bringing up secondary CPUs ...152test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT153second # [ 0.005559] smp: Brought up 1 node, 1 CPU154second # [ 0.005561] SMP: Total of 1 processors activated.155test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]156second # [ 0.005563] CPU: All CPU(s) started at EL1157test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]158second # [ 0.005575] CPU features: detected: 32-bit EL0 Support159test # [ 0.000000] Zone ranges:160test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]161second # [ 0.005578] CPU features: detected: Data cache clean to the PoU not required for I/D coherence162test # [ 0.000000] DMA32 empty163test # [ 0.000000] Normal empty164second # [ 0.005581] CPU features: detected: Common not Private translations165test # [ 0.000000] Device empty166second # [ 0.005583] CPU features: detected: CRC32 instructions167test # [ 0.000000] Movable zone start for each node168test # [ 0.000000] Early memory node ranges169second # [ 0.005585] CPU features: detected: Data cache clean to Point of Persistence170test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]171second # [ 0.005588] CPU features: detected: Enhanced Virtualization Traps172test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]173second # [ 0.005598] CPU features: detected: RCpc load-acquire (LDAPR)174test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000175second # [ 0.005600] CPU features: detected: LSE atomic instructions176test # [ 0.000000] psci: probing for conduit method from DT.177second # [ 0.005602] CPU features: detected: Privileged Access Never178test # [ 0.000000] psci: PSCIv1.3 detected in firmware.179second # [ 0.005604] CPU features: detected: PMUv3180test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs181test # [ 0.000000] psci: Trusted OS migration not required182second # [ 0.005605] CPU features: detected: RAS Extension Support183test # [ 0.000000] psci: SMC Calling Convention v1.1184second # [ 0.005608] CPU features: detected: Speculative Store Bypassing Safe (SSBS)185test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)186second # [ 0.005629] alternatives: applying system-wide alternatives187test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296188second # [ 0.007001] CPU features: detected: Hardware dirty bit management on CPU0189test # [ 0.000000] Detected PIPT I-cache on CPU0190test # [ 0.000000] CPU features: detected: GICv3 CPU interface191test # [ 0.000000] CPU features: detected: Spectre-v4192second # [ 0.007224] Memory: 893584K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113428K reserved, 32768K cma-reserved)193test # [ 0.000000] CPU features: detected: Spectre-BHB194second # [ 0.007679] devtmpfs: initialized195test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR196second # [ 0.009132] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)197test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)198test # [ 0.000000] CPU features: detected: ARM erratum 1418040199second # [ 0.009155] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).200test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion201second # [ 0.009290] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL202second # [ 0.009293] 0 pages in range for non-PLT usage203test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing204second # [ 0.009294] 508336 pages in range for PLT usage205test # [ 0.000000] alternatives: applying boot alternatives206second # [ 0.009426] pinctrl core: initialized pinctrl subsystem207second # [ 0.010175] DMI not present or invalid.208second # [ 0.013458] NET: Registered PF_NETLINK/PF_ROUTE protocol family209second # [ 0.015756] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations210second # [ 0.015903] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations211test # [ 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/cxqrz5vkhg22v52q8wapgmk904vn4zwv-nixos-system-test-test/init regInfo=/nix/store/4hxx3fxsz64a5cjv12j7rfp6p3fgq1l9-closure-info/registration console=ttyAMA0,115200n8 console=tty0212second # [ 0.016073] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations213second # [ 0.016090] audit: initializing netlink subsys (disabled)214second # [ 0.016740] thermal_sys: Registered thermal governor 'fair_share'215test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/4hxx3fxsz64a5cjv12j7rfp6p3fgq1l9-closure-info/registration", will be passed to user space.216second # [ 0.016742] thermal_sys: Registered thermal governor 'bang_bang'217test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes218second # [ 0.016745] thermal_sys: Registered thermal governor 'step_wise'219test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)220second # [ 0.016747] thermal_sys: Registered thermal governor 'user_space'221second # [ 0.016749] thermal_sys: Registered thermal governor 'power_allocator'222test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)223test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB224second # [ 0.016778] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1225test # [ 0.000000] software IO TLB: area num 1.226second # [ 0.016793] cpuidle: using governor ladder227second # [ 0.016797] cpuidle: using governor menu228test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)229second # [ 0.016919] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.230test # [ 0.000000] Fallback order for Node 0: 0231second # [ 0.016940] ASID allocator initialised with 32768 entries232test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144233second # [ 0.017574] Serial: AMBA PL011 UART driver234test # [ 0.000000] Policy zone: DMA235test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off236second # [ 0.020606] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1237second # [ 0.020739] printk: console [ttyAMA0] enabled238test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1239test # [ 0.000000] allocated 2097152 bytes of page_ext240test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages241test # [ 0.000000] ftrace: allocated 294 pages with 4 groups242test # [ 0.000000] rcu: Hierarchical RCU implementation.243test # [ 0.000000] rcu: RCU event tracing is enabled.244test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.245test # [ 0.000000] Trampoline variant of Tasks RCU enabled.246test # [ 0.000000] Rude variant of Tasks RCU enabled.247test # [ 0.000000] Tracing variant of Tasks RCU enabled.248test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.249test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1250test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.251test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.252test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.253test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0254test # [ 0.000000] GICv3: 256 SPIs implemented255test # [ 0.000000] GICv3: 0 Extended SPIs implemented256test # [ 0.000000] Root IRQ handler: gic_handle_irq257test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI258test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0259test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000260second # [ 0.097796] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages261test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]262second # [ 0.097813] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page263test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)264second # [ 0.097818] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages265second # [ 0.097824] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page266test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)267second # [ 0.097827] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages268test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000269second # [ 0.097830] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page270test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000271second # [ 0.097833] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages272test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.273second # [ 0.097836] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page274test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns275test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040276test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).277second # [ 0.102906] fbcon: Taking over console278second # [ 0.102924] ACPI: Interpreter disabled.279test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns280second # [ 0.104179] iommu: Default domain type: Translated281test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns282test # [ 0.000052] arm-pv: using stolen time PV283second # [ 0.104189] iommu: DMA domain TLB invalidation policy: strict mode284second # [ 0.105399] SCSI subsystem initialized285test # [ 0.000345] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)286test # [ 0.000582] Console: colour dummy device 80x25287test # [ 0.000593] printk: legacy console [tty0] enabled288second # [ 0.106009] usbcore: registered new interface driver usbfs289second # [ 0.106041] usbcore: registered new interface driver hub290test # [ 0.000808] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)291second # [ 0.106051] usbcore: registered new device driver usb292test # [ 0.000813] pid_max: default: 32768 minimum: 301293second # [ 0.106251] pps_core: LinuxPPS API ver. 1 registered294test # [ 0.000897] LSM: initializing lsm=capability,landlock,yama,bpf,ima295test # [ 0.001018] landlock: Up and running.296second # [ 0.106257] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>297test # [ 0.001021] Yama: becoming mindful.298second # [ 0.106263] PTP clock support registered299test # [ 0.001353] LSM support for eBPF active300second # [ 0.106291] EDAC MC: Ver: 3.0.0301test # [ 0.001510] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)302second # [ 0.109303] scmi_core: SCMI protocol bus registered303test # [ 0.001541] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)304test # [ 0.003928] rcu: Hierarchical SRCU implementation.305second # [ 0.110072] FPGA manager framework306test # [ 0.003934] rcu: Max phase no-delay instances is 1000.307test # [ 0.004946] fsl-mc MSI: its@8080000 domain created308test # [ 0.005094] EFI services will not be available.309test # [ 0.005189] smp: Bringing up secondary CPUs ...310test # [ 0.005220] smp: Brought up 1 node, 1 CPU311test # [ 0.005223] SMP: Total of 1 processors activated.312test # [ 0.005225] CPU: All CPU(s) started at EL1313test # [ 0.005236] CPU features: detected: 32-bit EL0 Support314test # [ 0.005240] CPU features: detected: Data cache clean to the PoU not required for I/D coherence315test # [ 0.005242] CPU features: detected: Common not Private translations316test # [ 0.005245] CPU features: detected: CRC32 instructions317test # [ 0.005246] CPU features: detected: Data cache clean to Point of Persistence318test # [ 0.005249] CPU features: detected: Enhanced Virtualization Traps319test # [ 0.005259] CPU features: detected: RCpc load-acquire (LDAPR)320test # [ 0.005261] CPU features: detected: LSE atomic instructions321test # [ 0.005263] CPU features: detected: Privileged Access Never322test # [ 0.005264] CPU features: detected: PMUv3323test # [ 0.005266] CPU features: detected: RAS Extension Support324second # [ 0.117954] vgaarb: loaded325test # [ 0.005269] CPU features: detected: Speculative Store Bypassing Safe (SSBS)326second # [ 0.118429] clocksource: Switched to clocksource arch_sys_counter327test # [ 0.005289] alternatives: applying system-wide alternatives328test # [ 0.006669] CPU features: detected: Hardware dirty bit management on CPU0329test # [ 0.006845] Memory: 893912K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113420K reserved, 32768K cma-reserved)330test # [ 0.007246] devtmpfs: initialized331test # [ 0.008691] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)332test # [ 0.008712] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).333second # [ 0.122175] VFS: Disk quotas dquot_6.6.0334test # [ 0.008833] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL335test # [ 0.008836] 0 pages in range for non-PLT usage336second # [ 0.122207] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)337test # [ 0.008837] 508336 pages in range for PLT usage338test # [ 0.008963] pinctrl core: initialized pinctrl subsystem339test # [ 0.009713] DMI not present or invalid.340test # [ 0.012790] NET: Registered PF_NETLINK/PF_ROUTE protocol family341second # [ 0.124579] netfs: FS-Cache loaded342test # [ 0.015028] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations343second # [ 0.124697] pnp: PnP ACPI: disabled344test # [ 0.015181] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations345test # [ 0.015352] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations346test # [ 0.015368] audit: initializing netlink subsys (disabled)347test # [ 0.015925] thermal_sys: Registered thermal governor 'fair_share'348test # [ 0.015927] thermal_sys: Registered thermal governor 'bang_bang'349test # [ 0.015930] thermal_sys: Registered thermal governor 'step_wise'350test # [ 0.015933] thermal_sys: Registered thermal governor 'user_space'351test # [ 0.015934] thermal_sys: Registered thermal governor 'power_allocator'352test # [ 0.015962] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1353test # [ 0.015976] cpuidle: using governor ladder354test # [ 0.015980] cpuidle: using governor menu355second # [ 0.127053] NET: Registered PF_INET protocol family356test # [ 0.016115] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.357test # [ 0.016135] ASID allocator initialised with 32768 entries358second # [ 0.127233] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)359test # [ 0.016767] Serial: AMBA PL011 UART driver360test # [ 0.019816] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1361test # [ 0.019975] printk: console [ttyAMA0] enabled362test # [ 0.094982] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages363test # [ 0.094997] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page364test # [ 0.095002] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages365test # [ 0.095008] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page366test # [ 0.095011] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages367test # [ 0.095014] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page368test # [ 0.095017] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages369test # [ 0.095020] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page370test # [ 0.100148] fbcon: Taking over console371test # [ 0.100167] ACPI: Interpreter disabled.372test # [ 0.101424] iommu: Default domain type: Translated373test # [ 0.101434] iommu: DMA domain TLB invalidation policy: strict mode374test # [ 0.102614] SCSI subsystem initialized375test # [ 0.103222] usbcore: registered new interface driver usbfs376test # [ 0.103256] usbcore: registered new interface driver hub377test # [ 0.103266] usbcore: registered new device driver usb378test # [ 0.103461] pps_core: LinuxPPS API ver. 1 registered379test # [ 0.103467] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>380test # [ 0.103473] PTP clock support registered381test # [ 0.103503] EDAC MC: Ver: 3.0.0382test # [ 0.111139] scmi_core: SCMI protocol bus registered383test # [ 0.111900] FPGA manager framework384test # [ 0.112942] vgaarb: loaded385test # [ 0.113370] clocksource: Switched to clocksource arch_sys_counter386second # [ 0.151074] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)387second # [ 0.151105] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)388second # [ 0.151119] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)389second # [ 0.151158] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)390second # [ 0.151226] TCP: Hash tables configured (established 8192 bind 8192)391second # [ 0.151327] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)392second # [ 0.151396] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)393second # [ 0.151445] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)394second # [ 0.151578] NET: Registered PF_UNIX/PF_LOCAL protocol family395second # [ 0.151619] NET: Registered PF_XDP protocol family396second # [ 0.151640] PCI: CLS 0 bytes, default 64397second # [ 0.151847] Trying to unpack rootfs image as initramfs...398test # [ 0.123082] VFS: Disk quotas dquot_6.6.0399test # [ 0.123122] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)400test # [ 0.125476] netfs: FS-Cache loaded401test # [ 0.125623] pnp: PnP ACPI: disabled402second # [ 0.164807] kvm [1]: HYP mode not available403test # [ 0.128004] NET: Registered PF_INET protocol family404test # [ 0.128196] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)405test # [ 0.151890] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)406test # [ 0.151922] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)407test # [ 0.151936] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)408test # [ 0.151972] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)409test # [ 0.152040] TCP: Hash tables configured (established 8192 bind 8192)410test # [ 0.152131] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)411test # [ 0.152160] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)412test # [ 0.152214] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.152354] NET: Registered PF_UNIX/PF_LOCAL protocol family414test # [ 0.152441] NET: Registered PF_XDP protocol family415test # [ 0.152464] PCI: CLS 0 bytes, default 64416test # [ 0.152677] Trying to unpack rootfs image as initramfs...417test # [ 0.163867] kvm [1]: HYP mode not available418second # [ 0.230678] Initialise system trusted keyrings419second # [ 0.238491] workingset: timestamp_bits=42 max_order=18 bucket_order=0420second # [ 0.239523] squashfs: version 4.0 (2009/01/31) Phillip Lougher421second # [ 0.240054] 9p: Installing v9fs 9p2000 file system support422second # [ 0.259419] Key type asymmetric registered423second # [ 0.259451] Asymmetric key parser 'x509' registered424second # [ 0.259524] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)425second # [ 0.260958] io scheduler mq-deadline registered426second # [ 0.260969] io scheduler kyber registered427second # [ 0.266571] pl061_gpio 9030000.pl061: PL061 GPIO chip registered428second # [ 0.267415] ledtrig-cpu: registered to indicate activity on CPUs429second # [ 0.267742] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:430second # [ 0.267764] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000431second # [ 0.267779] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000432second # [ 0.267785] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000433second # [ 0.267814] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits434second # [ 0.267838] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]435second # [ 0.267918] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00436second # [ 0.267936] pci_bus 0000:00: root bus resource [bus 00-ff]437second # [ 0.267942] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]438second # [ 0.267946] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]439second # [ 0.267949] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]440second # [ 0.268020] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint441second # [ 0.268452] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint442second # [ 0.268567] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]443second # [ 0.268578] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]444second # [ 0.268598] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]445second # [ 0.268609] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]446second # [ 0.268894] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint447test # [ 0.237776] Initialise system trusted keyrings448second # [ 0.269019] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]449second # [ 0.269029] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]450test # [ 0.238291] workingset: timestamp_bits=42 max_order=18 bucket_order=0451second # [ 0.269050] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]452test # [ 0.239287] squashfs: version 4.0 (2009/01/31) Phillip Lougher453second # [ 0.269329] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint454second # [ 0.269440] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]455second # [ 0.269451] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]456second # [ 0.269471] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]457test # [ 0.239796] 9p: Installing v9fs 9p2000 file system support458second # [ 0.269747] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint459second # [ 0.269858] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]460second # [ 0.269868] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]461second # [ 0.269888] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]462second # [ 0.270170] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint463second # [ 0.270281] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]464second # [ 0.270292] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]465second # [ 0.270311] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]466second # [ 0.294692] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint467second # [ 0.294823] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]468second # [ 0.294835] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]469second # [ 0.294855] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]470second # [ 0.295139] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint471second # [ 0.295252] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]472second # [ 0.295262] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]473second # [ 0.295282] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]474test # [ 0.259114] Key type asymmetric registered475second # [ 0.295297] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]476test # [ 0.259140] Asymmetric key parser 'x509' registered477second # [ 0.295580] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint478test # [ 0.259203] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)479second # [ 0.295694] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]480second # [ 0.295713] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]481second # [ 0.295984] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint482second # [ 0.296098] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]483second # [ 0.296117] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484second # [ 0.296363] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint485second # [ 0.296473] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]486second # [ 0.296634] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint487second # [ 0.296849] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]488second # [ 0.296863] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]489test # [ 0.265432] io scheduler mq-deadline registered490test # [ 0.265449] io scheduler kyber registered491second # [ 0.296884] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492second # [ 0.297188] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint493second # [ 0.297302] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]494second # [ 0.297313] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.297332] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]496second # [ 0.297819] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned497test # [ 0.268365] pl061_gpio 9030000.pl061: PL061 GPIO chip registered498second # [ 0.297831] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned499test # [ 0.269134] ledtrig-cpu: registered to indicate activity on CPUs500second # [ 0.297836] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned501second # [ 0.297868] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned502second # [ 0.297900] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned503second # [ 0.297934] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned504second # [ 0.297964] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned505second # [ 0.297995] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned506test # [ 0.269959] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:507second # [ 0.298025] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned508test # [ 0.269984] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000509second # [ 0.298055] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned510test # [ 0.270015] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000511second # [ 0.298085] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned512test # [ 0.270021] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000513second # [ 0.298114] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned514test # [ 0.270047] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits515second # [ 0.298192] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned516second # [ 0.298222] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned517test # [ 0.270068] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]518second # [ 0.298236] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned519test # [ 0.270149] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00520second # [ 0.298250] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned521test # [ 0.270167] pci_bus 0000:00: root bus resource [bus 00-ff]522second # [ 0.298264] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned523test # [ 0.270172] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]524second # [ 0.298278] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned525test # [ 0.270176] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]526second # [ 0.298292] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned527test # [ 0.270179] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]528second # [ 0.298307] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned529test # [ 0.270247] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint530second # [ 0.298321] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned531test # [ 0.270687] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint532second # [ 0.298335] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned533test # [ 0.270804] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]534second # [ 0.298350] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned535test # [ 0.270815] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]536second # [ 0.298364] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned537test # [ 0.270836] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.298378] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned539test # [ 0.270847] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]540second # [ 0.298392] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned541test # [ 0.271133] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint542second # [ 0.298406] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned543test # [ 0.271247] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]544second # [ 0.298420] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned545test # [ 0.271258] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]546test # [ 0.271277] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]547test # [ 0.271556] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint548test # [ 0.271669] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]549test # [ 0.271679] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]550test # [ 0.271699] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551test # [ 0.271974] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint552test # [ 0.272086] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]553test # [ 0.272097] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]554test # [ 0.272116] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]555test # [ 0.272395] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint556test # [ 0.272507] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]557test # [ 0.272517] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]558test # [ 0.272538] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559test # [ 0.272821] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint560test # [ 0.272933] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]561test # [ 0.272944] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]562second # [ 0.334494] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned563test # [ 0.272964] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]564second # [ 0.334529] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned565test # [ 0.273250] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint566second # [ 0.334545] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned567test # [ 0.273364] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]568second # [ 0.334560] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned569second # [ 0.334576] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned570second # [ 0.334591] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned571second # [ 0.334619] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]572second # [ 0.334625] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]573second # [ 0.334629] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]574second # [ 0.335365] pci 0000:00:0a.0: enabling device (0000 -> 0002)575second # [ 0.346248] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)576test # [ 0.305441] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]577test # [ 0.305471] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]578test # [ 0.305487] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]579test # [ 0.305924] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint580test # [ 0.306046] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]581test # [ 0.306065] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]582test # [ 0.306352] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint583test # [ 0.306466] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]584test # [ 0.306486] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]585second # [ 0.355606] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)586test # [ 0.306733] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint587second # [ 0.358234] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)588test # [ 0.306842] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]589test # [ 0.307006] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint590test # [ 0.307235] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]591test # [ 0.307248] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]592test # [ 0.307268] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]593test # [ 0.307551] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint594test # [ 0.307663] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]595test # [ 0.307673] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]596test # [ 0.307693] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]597test # [ 0.308098] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned598test # [ 0.308109] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned599test # [ 0.308114] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned600test # [ 0.308147] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned601test # [ 0.308179] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned602test # [ 0.308210] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned603test # [ 0.308239] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned604test # [ 0.308269] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned605test # [ 0.308299] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned606test # [ 0.308330] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned607test # [ 0.308360] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned608second # [ 0.367477] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)609test # [ 0.308389] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned610second # [ 0.369587] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)611test # [ 0.308451] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned612test # [ 0.308481] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned613test # [ 0.308496] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned614test # [ 0.308510] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned615test # [ 0.308524] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned616test # [ 0.308538] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned617second # [ 0.372948] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)618test # [ 0.308553] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned619test # [ 0.308567] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned620test # [ 0.308581] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned621test # [ 0.308595] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned622test # [ 0.308609] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned623test # [ 0.308623] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned624test # [ 0.308637] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned625test # [ 0.308651] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned626test # [ 0.308665] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned627test # [ 0.308679] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned628test # [ 0.308693] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned629test # [ 0.308712] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned630test # [ 0.308725] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned631test # [ 0.308739] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned632test # [ 0.308753] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned633test # [ 0.308767] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned634second # [ 0.378852] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)635test # [ 0.308785] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]636second # [ 0.381607] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)637test # [ 0.308791] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]638test # [ 0.308795] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]639test # [ 0.309488] pci 0000:00:0a.0: enabling device (0000 -> 0002)640test # [ 0.346635] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)641test # [ 0.348646] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)642second # [ 0.391521] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)643second # [ 0.394110] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)644test # [ 0.359322] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)645test # [ 0.361069] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)646second # [ 0.403613] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)647second # [ 0.415193] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled648test # [ 0.371401] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)649test # [ 0.373247] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)650second # [ 0.416729] msm_serial: driver initialized651second # [ 0.416813] SuperH (H)SCI(F) driver initialized652second # [ 0.416847] STM32 USART driver initialized653test # [ 0.382885] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)654test # [ 0.386247] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)655test # [ 0.387906] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)656second # [ 0.442620] loop: module loaded657second # [ 0.442752] virtio_blk virtio5: 1/0/0 default/read/poll queues658second # [ 0.443412] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)659test # [ 0.397532] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)660test # [ 0.399682] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)661second # [ 0.446816] megasas: 07.734.00.00-rc1662second # [ 0.447291] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]663test # [ 0.411783] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled664test # [ 0.413320] msm_serial: driver initialized665test # [ 0.413810] SuperH (H)SCI(F) driver initialized666test # [ 0.413855] STM32 USART driver initialized667second # [ 0.465833] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000668second # [ 0.465911] Intel/Sharp Extended Query Table at 0x0031669second # [ 0.468935] Using buffer write method670second # [ 0.469015] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]671second # [ 0.474451] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000672second # [ 0.474471] Intel/Sharp Extended Query Table at 0x0031673second # [ 0.477594] Using buffer write method674second # [ 0.477630] Concatenating MTD devices:675second # [ 0.477634] (0): "0.flash"676second # [ 0.477637] (1): "0.flash"677second # [ 0.477640] into device "0.flash"678test # [ 0.438130] loop: module loaded679test # [ 0.438269] virtio_blk virtio5: 1/0/0 default/read/poll queues680test # [ 0.438880] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)681test # [ 0.445801] megasas: 07.734.00.00-rc1682test # [ 0.446320] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]683test # [ 0.451298] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000684test # [ 0.451321] Intel/Sharp Extended Query Table at 0x0031685test # [ 0.453158] Using buffer write method686test # [ 0.453226] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]687test # [ 0.458535] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688test # [ 0.458622] Intel/Sharp Extended Query Table at 0x0031689test # [ 0.460687] Using buffer write method690test # [ 0.460720] Concatenating MTD devices:691test # [ 0.460725] (0): "0.flash"692test # [ 0.460728] (1): "0.flash"693test # [ 0.460730] into device "0.flash"694second # [ 0.658416] Freeing initrd memory: 24820K695second # [ 0.665190] tun: Universal TUN/TAP device driver, 1.6696second # [ 0.667822] thunder_xcv, ver 1.0697second # [ 0.667852] thunder_bgx, ver 1.0698second # [ 0.667865] nicpf, ver 1.0699second # [ 0.668147] e1000: Intel(R) PRO/1000 Network Driver700second # [ 0.668154] e1000: Copyright (c) 1999-2006 Intel Corporation.701second # [ 0.668170] e1000e: Intel(R) PRO/1000 Network Driver702second # [ 0.668177] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.703second # [ 0.668190] igb: Intel(R) Gigabit Ethernet Network Driver704second # [ 0.668194] igb: Copyright (c) 2007-2014 Intel Corporation.705second # [ 0.668206] igbvf: Intel(R) Gigabit Virtual Function Network Driver706second # [ 0.668209] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.707second # [ 0.668270] sky2: driver version 1.30708second # [ 0.669055] usbcore: registered new interface driver usb-storage709second # [ 0.669102] usbcore: registered new interface driver usbserial_generic710second # [ 0.669110] usbserial: USB Serial support registered for generic711second # [ 0.669379] hv_vmbus: registering driver hyperv_keyboard712second # [ 0.676816] ehci-pci 0000:00:0a.0: EHCI Host Controller713second # [ 0.676879] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1714second # [ 0.677124] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000715second # [ 0.678831] rtc-pl031 9010000.pl031: registered as rtc0716second # [ 0.678871] rtc-pl031 9010000.pl031: setting system clock to 2026-09-18T20:03:47 UTC (1789761827)717second # [ 0.679033] i2c_dev: i2c /dev entries driver718second # [ 0.681868] sdhci: Secure Digital Host Controller Interface driver719second # [ 0.681886] sdhci: Copyright(c) Pierre Ossman720second # [ 0.682015] Synopsys Designware Multimedia Card Interface Driver721second # [ 0.682174] sdhci-pltfm: SDHCI platform and OF driver helper722test # [ 0.656812] Freeing initrd memory: 24812K723test # [ 0.663716] tun: Universal TUN/TAP device driver, 1.6724test # [ 0.666421] thunder_xcv, ver 1.0725test # [ 0.666450] thunder_bgx, ver 1.0726test # [ 0.666462] nicpf, ver 1.0727test # [ 0.666776] e1000: Intel(R) PRO/1000 Network Driver728test # [ 0.666784] e1000: Copyright (c) 1999-2006 Intel Corporation.729test # [ 0.666798] e1000e: Intel(R) PRO/1000 Network Driver730test # [ 0.666805] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.731test # [ 0.666818] igb: Intel(R) Gigabit Ethernet Network Driver732test # [ 0.666821] igb: Copyright (c) 2007-2014 Intel Corporation.733test # [ 0.666832] igbvf: Intel(R) Gigabit Virtual Function Network Driver734test # [ 0.666835] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.735test # [ 0.666900] sky2: driver version 1.30736test # [ 0.667707] usbcore: registered new interface driver usb-storage737test # [ 0.667791] usbcore: registered new interface driver usbserial_generic738test # [ 0.667799] usbserial: USB Serial support registered for generic739test # [ 0.668087] hv_vmbus: registering driver hyperv_keyboard740test # [ 0.669066] ehci-pci 0000:00:0a.0: EHCI Host Controller741test # [ 0.669096] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1742test # [ 0.669337] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000743test # [ 0.677572] rtc-pl031 9010000.pl031: registered as rtc0744test # [ 0.677607] rtc-pl031 9010000.pl031: setting system clock to 2026-09-18T20:03:48 UTC (1789761828)745test # [ 0.677798] i2c_dev: i2c /dev entries driver746test # [ 0.680725] sdhci: Secure Digital Host Controller Interface driver747test # [ 0.680743] sdhci: Copyright(c) Pierre Ossman748test # [ 0.680884] Synopsys Designware Multimedia Card Interface Driver749test # [ 0.681052] sdhci-pltfm: SDHCI platform and OF driver helper750test # [ 0.681415] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00751test # [ 0.681789] hub 1-0:1.0: USB hub found752test # [ 0.681819] hub 1-0:1.0: 6 ports detected753test # [ 0.685457] hid: raw HID events driver (C) Jiri Kosina754test # [ 0.685606] usbcore: registered new interface driver usbhid755second # [ 0.684769] hid: raw HID events driver (C) Jiri Kosina756test # [ 0.685612] usbhid: USB HID core driver757test # [ 0.694812] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available758test # [ 0.695626] drop_monitor: Initializing network drop monitor service759second # [ 0.684921] usbcore: registered new interface driver usbhid760second # [ 0.684926] usbhid: USB HID core driver761test # [ 0.695745] NET: Registered PF_INET6 protocol family762second # [ 0.737111] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00763second # [ 0.737500] hub 1-0:1.0: USB hub found764second # [ 0.739371] hub 1-0:1.0: 6 ports detected765second # [ 0.739459] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available766test # [ 0.698064] Segment Routing with IPv6767test # [ 0.698103] In-situ OAM (IOAM) with IPv6768test # [ 0.698172] NET: Registered PF_PACKET protocol family769second # [ 0.740257] drop_monitor: Initializing network drop monitor service770second # [ 0.740382] NET: Registered PF_INET6 protocol family771second # [ 0.743340] Segment Routing with IPv6772second # [ 0.743378] In-situ OAM (IOAM) with IPv6773second # [ 0.743431] NET: Registered PF_PACKET protocol family774test # [ 0.699335] 9pnet: Installing 9P2000 support775test # [ 0.700895] Key type dns_resolver registered776test # [ 0.704454] registered taskstats version 1777test # [ 0.704622] Loading compiled-in X.509 certificates778second # [ 0.744569] 9pnet: Installing 9P2000 support779second # [ 0.746120] Key type dns_resolver registered780second # [ 0.749580] registered taskstats version 1781second # [ 0.749752] Loading compiled-in X.509 certificates782test # [ 0.710431] Demotion targets for Node 0: null783test # [ 0.710513] Key type .fscrypt registered784test # [ 0.710519] Key type fscrypt-provisioning registered785test # [ 0.710620] ima: No TPM chip found, activating TPM-bypass!786test # [ 0.710632] ima: Allocated hash algorithm: sha1787test # [ 0.710684] ima: No architecture policies found788second # [ 0.755214] Demotion targets for Node 0: null789second # [ 0.755294] Key type .fscrypt registered790second # [ 0.755300] Key type fscrypt-provisioning registered791second # [ 0.755400] ima: No TPM chip found, activating TPM-bypass!792test # [ 0.713388] input: gpio-keys as /devices/platform/gpio-keys/input/input0793second # [ 0.755412] ima: Allocated hash algorithm: sha1794second # [ 0.755461] ima: No architecture policies found795second # [ 0.758178] input: gpio-keys as /devices/platform/gpio-keys/input/input0796test # [ 0.723500] clk: Disabling unused clocks797test # [ 0.723521] PM: genpd: Disabling unused power domains798second # [ 0.768292] clk: Disabling unused clocks799second # [ 0.768312] PM: genpd: Disabling unused power domains800test # [ 0.726763] Freeing unused kernel memory: 4736K801test # [ 0.726854] Run /init as init process802second # [ 0.771563] Freeing unused kernel memory: 4736K803second # [ 0.771657] Run /init as init process804test # [ 0.747873] systemd[1]: Successfully made /usr/ read-only.805second # [ 0.792917] systemd[1]: Successfully made /usr/ read-only.806test # [ 0.929415] usb 1-1: new high-speed USB device number 2 using ehci-pci807second # [ 0.986484] usb 1-1: new high-speed USB device number 2 using ehci-pci808test # [ 1.081524] 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/input1809second # [ 1.127606] 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)810second # [ 1.134699] systemd[1]: Detected virtualization qemu.811second # [ 1.136249] systemd[1]: Detected architecture arm64.812test # [ 1.085459] 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)813test # [ 1.085503] systemd[1]: Detected virtualization qemu.814second # [ 1.137512] systemd[1]: Running in initrd.815test # [ 1.085571] systemd[1]: Detected architecture arm64.816second # [ 1.140024] systemd[1]: Initializing machine ID from random generator.817test # [ 1.085589] systemd[1]: Running in initrd.818test # [ 1.086508] systemd[1]: Initializing machine ID from random generator.819second # [ 1.142117] systemd[1]: Hostname set to <second>.820test # [ 1.086810] systemd[1]: Hostname set to <test>.821second # [ 1.148865] 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/input1822test # [ 1.169656] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0823second # [ 1.234651] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0824test # [ 1.201844] systemd[1]: Queued start job for default target Initrd Default Target.825second # [ 1.254485] systemd[1]: Queued start job for default target Initrd Default Target.826test # [ 1.210891] systemd[1]: Created slice Slice /system/modprobe.827test # [ 1.211746] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.828test # [ 1.212521] systemd[1]: Expecting device /dev/disk/by-label/nixos...829test # [ 1.213121] systemd[1]: Reached target Path Units.830test # [ 1.213666] systemd[1]: Reached target Slice Units.831test # [ 1.214187] systemd[1]: Reached target Swaps.832test # [ 1.214651] systemd[1]: Reached target Timer Units.833test # [ 1.215316] systemd[1]: Listening on D-Bus System Message Bus Socket.834test # [ 1.216137] systemd[1]: Listening on Journal Socket (/dev/log).835test # [ 1.216899] systemd[1]: Listening on Journal Sockets.836test # [ 1.217595] systemd[1]: Listening on udev Control Socket.837test # [ 1.218396] systemd[1]: Listening on udev Kernel Socket.838test # [ 1.218971] systemd[1]: Reached target Socket Units.839test # [ 1.220719] systemd[1]: Starting Create List of Static Device Nodes...840second # [ 1.264199] systemd[1]: Created slice Slice /system/modprobe.841second # [ 1.265196] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.842second # [ 1.266593] systemd[1]: Expecting device /dev/disk/by-label/nixos...843second # [ 1.267433] systemd[1]: Reached target Path Units.844second # [ 1.268076] systemd[1]: Reached target Slice Units.845second # [ 1.268735] systemd[1]: Reached target Swaps.846test # [ 1.226129] systemd[1]: Starting Load Kernel Module 9pnet_virtio...847second # [ 1.269360] systemd[1]: Reached target Timer Units.848test # [ 1.226846] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs849second # [ 1.270211] systemd[1]: Listening on D-Bus System Message Bus Socket.850second # [ 1.271342] systemd[1]: Listening on Journal Socket (/dev/log).851second # [ 1.272302] systemd[1]: Listening on Journal Sockets.852second # [ 1.273145] systemd[1]: Listening on udev Control Socket.853second # [ 1.274013] systemd[1]: Listening on udev Kernel Socket.854test # [ 1.231878] systemd[1]: Mounting Kernel Configuration File System...855second # [ 1.274803] systemd[1]: Reached target Socket Units.856second # [ 1.276855] systemd[1]: Starting Create List of Static Device Nodes...857test # [ 1.240913] systemd[1]: Starting Journal Service...858second # [ 1.285695] systemd[1]: Starting Load Kernel Module 9pnet_virtio...859second # [ 1.286623] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs860second # [ 1.289179] systemd[1]: Mounting Kernel Configuration File System...861second # [ 1.300549] systemd[1]: Starting Journal Service...862test # [ 1.258022] systemd[1]: Starting Load Kernel Modules...863test # [ 1.258088] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki864second # [ 1.311076] systemd[1]: Starting Load Kernel Modules...865second # [ 1.311139] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki866test # [ 1.272139] systemd[1]: Starting Coldplug All udev Devices...867test # [ 1.277707] systemd[1]: Finished Create List of Static Device Nodes.868test # [ 1.278566] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.869test # [ 1.278743] systemd[1]: Finished Load Kernel Module 9pnet_virtio.870test # [ 1.278925] systemd[1]: Mounted Kernel Configuration File System.871second # [ 1.325132] systemd[1]: Starting Coldplug All udev Devices...872test # [ 1.288503] systemd-journald[73]: Collecting audit messages is disabled.873second # [ 1.330556] systemd[1]: Finished Create List of Static Device Nodes.874second # [ 1.331520] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.875second # [ 1.331700] systemd[1]: Finished Load Kernel Module 9pnet_virtio.876test # [ 1.289923] usb 1-2: new high-speed USB device number 3 using ehci-pci877test # [ 1.290871] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...878second # [ 1.347275] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...879second # [ 1.348315] systemd[1]: Mounted Kernel Configuration File System.880second # [ 1.351332] systemd-journald[73]: Collecting audit messages is disabled.881second # [ 1.354537] usb 1-2: new high-speed USB device number 3 using ehci-pci882test # [ 1.317901] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883test # [ 1.321656] systemd[1]: Starting Create Static Device Nodes in /dev...884second # [ 1.378999] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.885second # [ 1.380638] systemd[1]: Starting Create Static Device Nodes in /dev...886test # [ 1.337838] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.887test # [ 1.345459] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev888second # [ 1.395898] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.889test # [ 1.357736] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0890test # [ 1.357974] [drm] features: -virgl +edid -resource_blob -host_visible891test # [ 1.357980] [drm] features: -context_init892test # [ 1.358442] [drm] number of scanouts: 1893test # [ 1.358457] [drm] number of cap sets: 0894second # [ 1.406510] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev895second # [ 1.410862] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0896second # [ 1.411071] [drm] features: -virgl +edid -resource_blob -host_visible897second # [ 1.411077] [drm] features: -context_init898second # [ 1.411523] [drm] number of scanouts: 1899second # [ 1.411538] [drm] number of cap sets: 0900test # [ 1.371025] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic901test # [ 1.371040] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0902test # [ 1.375049] systemd[1]: Finished Create Static Device Nodes in /dev.903test # [ 1.375388] systemd[1]: Reached target Preparation for Local File Systems.904test # [ 1.375422] systemd[1]: Reached target Local File Systems.905test # [ 1.378292] systemd[1]: Starting Rule-based Manager for Device Events and Files...906second # [ 1.426835] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic907second # [ 1.426851] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0908test # [ 1.391301] systemd[1]: Started Journal Service.909test # [ 1.383472] systemd-modules-load[74]: Inserted module 'dm_mod'910test # [ 1.384358] systemd-modules-load[74]: Module 'virtio_balloon' is built in911test # [ 1.385075] systemd-modules-load[74]: Module 'virtio_console' is built in912second # [ 1.438881] systemd[1]: Finished Create Static Device Nodes in /dev.913second # [ 1.439061] systemd[1]: Reached target Preparation for Local File Systems.914second # [ 1.439092] systemd[1]: Reached target Local File Systems.915second # [ 1.441733] systemd[1]: Starting Rule-based Manager for Device Events and Files...916second # [ 1.445220] systemd[1]: Started Journal Service.917test # [ 1.397778] Console: switching to colour frame buffer device 160x50918second # [ 1.442835] systemd-modules-load[74]: Inserted module 'dm_mod'919second # [ 1.443697] systemd-modules-load[74]: Module 'virtio_balloon' is built in920test # [ 1.401178] systemd[1]: Starting Create System Files and Directories...921test # [ 1.414070] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device922second # [ 1.458812] Console: switching to colour frame buffer device 160x50923second # [ 1.464137] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device924second # [ 1.459742] systemd-modules-load[74]: Module 'virtio_console' is built in925test # [ 1.414874] systemd-modules-load[74]: Inserted module 'virtio_gpu'926test # [ 1.415492] systemd-modules-load[74]: Module 'virtio_rng' is built in927second # [ 1.465011] systemd[1]: Starting Create System Files and Directories...928test # [ 1.421179] systemd[1]: Finished Load Kernel Modules.929second # [ 1.468534] systemd-modules-load[74]: Inserted module 'virtio_gpu'930second # [ 1.472146] systemd-modules-load[74]: Module 'virtio_rng' is built in931test # [ 1.437085] systemd[1]: Starting Apply Kernel Variables...932second # [ 1.483835] systemd[1]: Finished Load Kernel Modules.933test # [ 1.459458] 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/input2934test # [ 1.461709] 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.500856] systemd[1]: Starting Apply Kernel Variables...936test # [ 1.469175] systemd[1]: Finished Apply Kernel Variables.937second # [ 1.516837] systemd[1]: Finished Create System Files and Directories.938test # [ 1.472209] systemd[1]: Finished Create System Files and Directories.939test # [ 1.473030] systemd-udevd[81]: Using default interface naming scheme 'v260'.940second # [ 1.526677] 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/input2941second # [ 1.528654] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0942second # [ 1.531553] systemd[1]: Finished Apply Kernel Variables.943second # [ 1.533221] systemd-udevd[81]: Using default interface naming scheme 'v260'.944test # [ 1.496115] systemd[1]: Started Rule-based Manager for Device Events and Files.945second # [ 1.556720] systemd[1]: Started Rule-based Manager for Device Events and Files.946test # [ 1.548994] systemd[1]: Starting Virtual Console Setup...947second # [ 1.609036] systemd[1]: Starting Virtual Console Setup...948test # [ 1.596649] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.949test # [ 1.598743] systemd[1]: Finished Virtual Console Setup.950second # [ 1.652985] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951second # [ 1.654691] systemd[1]: Finished Virtual Console Setup.952second # [ 1.917641] systemd[1]: Finished Coldplug All udev Devices.953second # [ 1.918212] systemd[1]: Reached target System Initialization.954second # [ 1.918721] systemd[1]: Reached target Basic System.955test # [ 1.877454] systemd[1]: Finished Coldplug All udev Devices.956test # [ 1.878068] systemd[1]: Reached target System Initialization.957test # [ 1.878569] systemd[1]: Reached target Basic System.958second # [ 2.011340] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.959test # [ 1.978568] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.960second # [ 2.046930] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.003824] (udev-worker)[103]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.079482] systemd[1]: Found device /dev/disk/by-label/nixos.963second # [ 2.080538] systemd[1]: Reached target Initrd Root Device.964second # [ 2.082588] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...965test # [ 2.037557] systemd[1]: Found device /dev/disk/by-label/nixos.966test # [ 2.039822] systemd[1]: Reached target Initrd Root Device.967test # [ 2.042164] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...968second # [ 2.116832] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks969test # [ 2.077369] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks970second # [ 2.148963] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.971test # [ 2.104499] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972test # [ 2.265859] systemd[1]: Mounting /sysroot...973second # [ 2.321977] systemd[1]: Mounting /sysroot...974test # [ 2.300225] EXT4-fs (vda): mounted filesystem c20ff810-82c8-40ee-9446-981f605f127a r/w with ordered data mode. Quota mode: none.975test # [ 2.295862] systemd[1]: Mounted /sysroot.976test # [ 2.297651] systemd[1]: Reached target Initrd Root File System.977test # [ 2.301294] systemd[1]: Starting Mountpoints Configured in the Real Root...978second # [ 2.354700] EXT4-fs (vda): mounted filesystem 6903694c-da93-4331-adf0-eac2de76668b r/w with ordered data mode. Quota mode: none.979second # [ 2.352902] systemd[1]: Mounted /sysroot.980second # [ 2.355788] systemd[1]: Reached target Initrd Root File System.981second # [ 2.362475] systemd[1]: Starting Mountpoints Configured in the Real Root...982test # [ 2.323003] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.983test # [ 2.329832] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...984test # [ 2.331945] systemd[1]: Reloading...985second # [ 2.378947] systemd-sysroot-fstab-check[121]: /sysroot should be mounted in the initrd, will request daemon-reload.986second # [ 2.383831] systemd[1]: Reload requested from client PID 121 ('systemd-sysroot') (unit initrd-parse-etc.service)...987second # [ 2.387386] systemd[1]: Reloading...988test # [ 2.510689] systemd[1]: Reloading finished in 181 ms.989test # [ 2.520153] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...990test # [ 2.523859] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...991test # [ 2.525623] systemd[1]: Starting Load Kernel Module 9pnet_virtio...992test # [ 2.531275] systemd[1]: initrd-parse-etc.service: Deactivated successfully.993test # [ 2.534476] systemd[1]: Finished Mountpoints Configured in the Real Root.994second # [ 2.581364] systemd[1]: Reloading finished in 194 ms.995test # [ 2.535116] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.996second # [ 2.591917] systemd-sysroot-fstab-check[121]: Requesting initrd-fs.target/start/replace...997second # [ 2.594653] systemd-sysroot-fstab-check[121]: Requesting swap.target/start/replace...998test # [ 2.552210] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.999test # [ 2.552912] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1000second # [ 2.607308] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1001second # [ 2.608678] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1002second # [ 2.610131] systemd[1]: Finished Mountpoints Configured in the Real Root.1003second # [ 2.611981] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1004second # [ 2.624467] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1005second # [ 2.625150] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1006second # [ 2.912299] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1007second # [ 2.915478] (udev-worker)[99]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-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 directory1008test # [ 2.883703] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1009second # [ 2.931409] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1010second # [ 2.933268] systemd[1]: Stopped Virtual Console Setup.1011second # [ 2.934156] systemd[1]: Stopping Virtual Console Setup...1012test # [ 2.888233] (udev-worker)[95]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-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 directory1013second # [ 2.937235] systemd[1]: Starting Virtual Console Setup...1014test # [ 2.901992] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1015test # [ 2.903145] systemd[1]: Stopped Virtual Console Setup.1016test # [ 2.905795] systemd[1]: Stopping Virtual Console Setup...1017test # [ 2.906466] systemd[1]: Starting Virtual Console Setup...1018second # [ 2.958395] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1019second # [ 2.960912] systemd[1]: Finished Virtual Console Setup.1020test # [ 2.925573] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1021test # [ 2.928191] systemd[1]: Stopped Virtual Console Setup.1022test # [ 2.928711] systemd[1]: Starting Virtual Console Setup...1023test # [ 2.941411] systemd-vconsole-setup[144]: Configuration of first virtual console was skipped, ignoring remaining ones.1024test # [ 2.943447] systemd[1]: Finished Virtual Console Setup.1025test # [ 3.269136] systemd[1]: Mounting /sysroot/nix/.ro-store...1026test # [ 3.276330] systemd[1]: Mounting /sysroot/nix/.rw-store...1027second # [ 3.325581] systemd[1]: Mounting /sysroot/nix/.ro-store...1028test # [ 3.288128] systemd[1]: Mounting /sysroot/run...1029second # [ 3.337771] systemd[1]: Mounting /sysroot/nix/.rw-store...1030second # [ 3.340123] systemd[1]: Mounting /sysroot/run...1031test # [ 3.296783] systemd[1]: Mounting /sysroot/tmp/shared...1032second # [ 3.350834] systemd[1]: Mounting /sysroot/tmp/shared...1033test # [ 3.307729] systemd[1]: Mounting /sysroot/tmp/xchg...1034second # [ 3.365254] systemd[1]: Mounting /sysroot/tmp/xchg...1035test # [ 3.345584] systemd[1]: Mounted /sysroot/nix/.rw-store.1036second # [ 3.397110] systemd[1]: Mounted /sysroot/nix/.ro-store.1037test # [ 3.361788] systemd[1]: Mounted /sysroot/nix/.ro-store.1038second # [ 3.408761] systemd[1]: Mounted /sysroot/nix/.rw-store.1039second # [ 3.410627] systemd[1]: Mounted /sysroot/run.1040test # [ 3.364698] systemd[1]: Mounted /sysroot/run.1041second # [ 3.411859] systemd[1]: Mounted /sysroot/tmp/shared.1042test # [ 3.367197] systemd[1]: Mounted /sysroot/tmp/shared.1043test # [ 3.368973] systemd[1]: Mounted /sysroot/tmp/xchg.1044second # [ 3.416954] systemd[1]: Mounted /sysroot/tmp/xchg.1045test # [ 3.373112] systemd[1]: Starting rw-sysroot-nix-store.service...1046second # [ 3.423954] systemd[1]: Starting rw-sysroot-nix-store.service...1047test # [ 3.390037] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1048second # [ 3.437200] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1049test # [ 3.392192] systemd[1]: Finished rw-sysroot-nix-store.service.1050second # [ 3.439366] systemd[1]: Finished rw-sysroot-nix-store.service.1051test # [ 3.393250] systemd[1]: Mounting /sysroot/nix/store...1052second # [ 3.440284] systemd[1]: Mounting /sysroot/nix/store...1053test # [ 3.426511] systemd[1]: Mounted /sysroot/nix/store.1054test # [ 3.427574] systemd[1]: Reached target Initrd File Systems.1055second # [ 3.474465] systemd[1]: Mounted /sysroot/nix/store.1056second # [ 3.475686] systemd[1]: Reached target Initrd File Systems.1057test # [ 3.430567] systemd[1]: Starting Find NixOS closure...1058second # [ 3.477832] systemd[1]: Starting Find NixOS closure...1059test # [ 3.440728] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1060second # [ 3.488209] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1061test # [ 3.461467] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1062second # [ 3.510675] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1063test # [ 3.474931] systemd[1]: Finished Find NixOS closure.1064test # [ 3.477319] systemd[1]: Reached target Initrd Default Target.1065second # [ 3.524668] systemd[1]: Finished Find NixOS closure.1066test # [ 3.478357] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1067second # [ 3.526655] systemd[1]: Reached target Initrd Default Target.1068second # [ 3.527766] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1069test # [ 3.504310] systemd[1]: Stopped target Initrd Default Target.1070test # [ 3.505336] systemd[1]: Stopped target Basic System.1071second # [ 3.552353] systemd[1]: Stopped target Initrd Default Target.1072test # [ 3.506214] systemd[1]: Stopped target Initrd Root Device.1073second # [ 3.553377] systemd[1]: Stopped target Basic System.1074second # [ 3.554270] systemd[1]: Stopped target Initrd Root Device.1075test # [ 3.508214] systemd[1]: Stopped target Path Units.1076test # [ 3.508840] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1077second # [ 3.556106] systemd[1]: Stopped target Path Units.1078second # [ 3.556878] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1079test # [ 3.511460] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1080test # [ 3.512950] systemd[1]: Stopped target Slice Units.1081second # [ 3.559980] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1082second # [ 3.560957] systemd[1]: Stopped target Slice Units.1083test # [ 3.516246] systemd[1]: Stopped target Socket Units.1084test # [ 3.516852] systemd[1]: Stopped target System Initialization.1085test # [ 3.517516] systemd[1]: Stopped target Swaps.1086second # [ 3.564111] systemd[1]: Stopped target Socket Units.1087test # [ 3.518013] systemd[1]: Stopped target Timer Units.1088second # [ 3.564733] systemd[1]: Stopped target System Initialization.1089test # [ 3.518569] systemd[1]: dbus.socket: Deactivated successfully.1090second # [ 3.565396] systemd[1]: Stopped target Swaps.1091test # [ 3.519222] systemd[1]: Closed D-Bus System Message Bus Socket.1092test # [ 3.519868] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1093second # [ 3.567050] systemd[1]: Stopped target Timer Units.1094second # [ 3.567638] systemd[1]: dbus.socket: Deactivated successfully.1095second # [ 3.569142] systemd[1]: Closed D-Bus System Message Bus Socket.1096test # [ 3.524408] systemd[1]: Stopped Find NixOS closure.1097test # [ 3.525511] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1098second # [ 3.572131] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1099second # [ 3.572956] systemd[1]: Stopped Find NixOS closure.1100test # [ 3.527046] systemd[1]: Starting rw-sysroot-nix-store.service...1101second # [ 3.573585] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1102second # [ 3.575382] systemd[1]: Starting rw-sysroot-nix-store.service...1103test # [ 3.529579] systemd[1]: systemd-sysctl.service: Deactivated successfully.1104test # [ 3.530302] systemd[1]: Stopped Apply Kernel Variables.1105second # [ 3.577908] systemd[1]: systemd-sysctl.service: Deactivated successfully.1106test # [ 3.531484] systemd[1]: systemd-modules-load.service: Deactivated successfully.1107second # [ 3.578770] systemd[1]: Stopped Apply Kernel Variables.1108second # [ 3.579658] systemd[1]: systemd-modules-load.service: Deactivated successfully.1109second # [ 3.583322] systemd[1]: Stopped Load Kernel Modules.1110test # [ 3.537321] systemd[1]: Stopped Load Kernel Modules.1111test # [ 3.537813] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1112test # [ 3.538610] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1113test # [ 3.539332] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1114second # [ 3.585620] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1115second # [ 3.588049] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1116test # [ 3.539978] systemd[1]: Stopped Create System Files and Directories.1117test # [ 3.545428] systemd[1]: Stopped target Local File Systems.1118second # [ 3.592333] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1119second # [ 3.593391] systemd[1]: Stopped Create System Files and Directories.1120test # [ 3.547096] systemd[1]: Stopped target Preparation for Local File Systems.1121second # [ 3.594060] systemd[1]: Stopped target Local File Systems.1122second # [ 3.595264] systemd[1]: Stopped target Preparation for Local File Systems.1123test # [ 3.549267] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1124test # [ 3.550187] systemd[1]: Stopped Coldplug All udev Devices.1125test # [ 3.550686] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1126second # [ 3.597380] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1127test # [ 3.551310] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1128test # [ 3.551926] systemd[1]: Stopped Virtual Console Setup.1129second # [ 3.599349] systemd[1]: Stopped Coldplug All udev Devices.1130second # [ 3.599863] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1131second # [ 3.600652] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1132second # [ 3.601269] systemd[1]: Stopped Virtual Console Setup.1133second # [ 3.601722] systemd[1]: systemd-udevd.service: Deactivated successfully.1134test # [ 3.559384] systemd[1]: initrd-cleanup.service: Deactivated successfully.1135test # [ 3.559998] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1136test # [ 3.561658] systemd[1]: systemd-udevd.service: Deactivated successfully.1137test # [ 3.562889] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1138second # [ 3.610444] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1139second # [ 3.611093] systemd[1]: initrd-cleanup.service: Deactivated successfully.1140second # [ 3.611665] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1141test # [ 3.565273] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1142test # [ 3.565920] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1143second # [ 3.613107] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1144second # [ 3.613769] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1145test # [ 3.567655] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1146test # [ 3.568602] systemd[1]: Finished rw-sysroot-nix-store.service.1147second # [ 3.615254] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1148test # [ 3.569693] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1149second # [ 3.617402] systemd[1]: Finished rw-sysroot-nix-store.service.1150test # [ 3.572141] systemd[1]: Closed udev Control Socket.1151second # [ 3.618626] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1152test # [ 3.572630] systemd[1]: Starting Cleanup udev Database...1153test # [ 3.573097] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1154second # [ 3.619971] systemd[1]: Closed udev Control Socket.1155second # [ 3.621165] systemd[1]: Starting Cleanup udev Database...1156test # [ 3.575259] systemd[1]: Stopped Create Static Device Nodes in /dev.1157test # [ 3.575818] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1158test # [ 3.577184] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1159test # [ 3.577864] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1160second # [ 3.624254] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1161test # [ 3.578461] systemd[1]: Stopped Create List of Static Device Nodes.1162second # [ 3.624930] systemd[1]: Stopped Create Static Device Nodes in /dev.1163second # [ 3.625467] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1164second # [ 3.626143] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1165second # [ 3.627989] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1166second # [ 3.628761] systemd[1]: Stopped Create List of Static Device Nodes.1167test # [ 3.599774] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1168test # [ 3.602195] systemd[1]: Finished Cleanup udev Database.1169test # [ 3.602672] systemd[1]: Reached target Switch Root.1170test # [ 3.604822] systemd[1]: Starting NixOS Activation...1171second # [ 3.651981] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1172second # [ 3.653436] systemd[1]: Finished Cleanup udev Database.1173second # [ 3.655418] systemd[1]: Reached target Switch Root.1174second # [ 3.660801] systemd[1]: Starting NixOS Activation...1175second # [ 3.778743] initrd-nixos-activation-start[179]: booting system configuration /nix/store/j1v4crk8h06q6z20jhdz651agnyqifwh-nixos-system-second-test1176second # [ 3.824874] initrd-nixos-activation-start[179]: running activation script...1177test # [ 3.791978] initrd-nixos-activation-start[181]: booting system configuration /nix/store/cxqrz5vkhg22v52q8wapgmk904vn4zwv-nixos-system-test-test1178test # [ 3.863150] initrd-nixos-activation-start[181]: running activation script...1179second # [ 4.223429] initrd-nixos-activation-start[202]: setting up /etc...1180test # [ 4.338240] initrd-nixos-activation-start[204]: setting up /etc...1181second # [ 4.493951] initrd-nixos-activation-start[202]: setting up age secrets...1182test # [ 4.606433] initrd-nixos-activation-start[204]: setting up age secrets...1183second # [ 4.726466] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1184second # [ 4.728121] systemd[1]: Finished NixOS Activation.1185second # [ 4.728860] systemd[1]: Starting Switch Root...1186second # [ 4.748069] systemd[1]: Switching root.1187second # [ 4.802734] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1188test # [ 4.828478] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1189test # [ 4.830161] systemd[1]: Finished NixOS Activation.1190test # [ 4.832136] systemd[1]: Starting Switch Root...1191test # [ 4.847955] systemd[1]: Switching root.1192test # [ 4.905477] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1193second # [ 5.404392] 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)1194second # [ 5.411637] systemd[1]: Detected virtualization qemu.1195second # [ 5.413388] systemd[1]: Detected architecture arm64.1196second # [ 5.415232] systemd[1]: Detected first boot.1197second # [ 5.421074] systemd[1]: Initializing machine ID from random generator.1198test # [ 5.501010] 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)1199test # [ 5.508451] systemd[1]: Detected virtualization qemu.1200test # [ 5.510309] systemd[1]: Detected architecture arm64.1201test # [ 5.512545] systemd[1]: Detected first boot.1202test # [ 5.518183] systemd[1]: Initializing machine ID from random generator.1203second # [ 5.694931] systemd[1]: bpf-restrict-fs: LSM BPF program attached1204test # [ 5.805731] systemd[1]: bpf-restrict-fs: LSM BPF program attached1205second # [ 5.870335] systemd[1]: Applying preset policy.1206test # [ 5.986665] systemd[1]: Applying preset policy.1207second # [ 6.445025] systemd[1]: Populated /etc with preset unit settings.1208test # [ 6.546793] systemd[1]: Populated /etc with preset unit settings.1209second # [ 6.999759] systemd[1]: initrd-switch-root.service: Deactivated successfully.1210second # [ 7.000633] systemd[1]: Stopped initrd-switch-root.service.1211second # [ 7.002752] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1212second # [ 7.004278] systemd[1]: Created slice Slice /system/getty.1213second # [ 7.005855] systemd[1]: Created slice User and Session Slice.1214second # [ 7.007293] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1215second # [ 7.008070] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1216second # [ 7.009240] systemd[1]: Expecting device /dev/hvc0...1217second # [ 7.010215] systemd[1]: Expecting device /dev/ttyAMA0...1218second # [ 7.011311] systemd[1]: Reached target Local Encrypted Volumes.1219second # [ 7.012390] systemd[1]: Stopped target initrd-fs.target.1220second # [ 7.013356] systemd[1]: Stopped target initrd-root-fs.target.1221second # [ 7.014342] systemd[1]: Stopped target initrd-switch-root.target.1222second # [ 7.015952] systemd[1]: Reached target Virtual Machines and Containers.1223second # [ 7.017495] systemd[1]: Reached target Path Units.1224second # [ 7.018974] systemd[1]: Reached target Remote File Systems.1225second # [ 7.019868] systemd[1]: Reached target Slice Units.1226second # [ 7.021429] systemd[1]: Reached target Swaps.1227second # [ 7.026168] systemd[1]: Listening on Process Core Dump Socket.1228second # [ 7.029929] systemd[1]: Listening on Credential Encryption/Decryption.1229second # [ 7.034783] systemd[1]: Starting Journal Log Access Socket...1230second # [ 7.036510] systemd[1]: Listening on Journal Audit Socket.1231second # [ 7.037580] systemd[1]: Listening on Network Management Metrics Varlink Socket.1232second # [ 7.038794] systemd[1]: Listening on Network Management Netlink Socket.1233second # [ 7.039956] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1234second # [ 7.041063] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1235second # [ 7.042419] systemd[1]: Listening on udev Control Socket.1236second # [ 7.046721] systemd[1]: Mounting Huge Pages File System...1237second # [ 7.050586] systemd[1]: Mounting POSIX Message Queue File System...1238second # [ 7.057141] systemd[1]: Mounting Kernel Debug File System...1239second # [ 7.060637] systemd[1]: Mounting Kernel Trace File System...1240second # [ 7.067955] systemd[1]: Starting Create List of Static Device Nodes...1241second # [ 7.080819] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1242second # [ 7.083119] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1243second # [ 7.093861] systemd[1]: Mounting Kernel Configuration File System...1244second # [ 7.095528] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1245second # [ 7.099145] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1246second # [ 7.106537] systemd[1]: Starting Load Kernel Module fuse...1247second # [ 7.108641] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671248test # [ 7.086840] systemd[1]: initrd-switch-root.service: Deactivated successfully.1249test # [ 7.087747] systemd[1]: Stopped initrd-switch-root.service.1250test # [ 7.090048] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1251test # [ 7.092043] systemd[1]: Created slice Slice /system/getty.1252test # [ 7.093699] systemd[1]: Created slice User and Session Slice.1253test # [ 7.094915] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1254test # [ 7.096283] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1255test # [ 7.097800] systemd[1]: Expecting device /dev/hvc0...1256test # [ 7.098943] systemd[1]: Expecting device /dev/ttyAMA0...1257test # [ 7.100011] systemd[1]: Reached target Local Encrypted Volumes.1258test # [ 7.101556] systemd[1]: Stopped target initrd-fs.target.1259test # [ 7.103013] systemd[1]: Stopped target initrd-root-fs.target.1260test # [ 7.104488] systemd[1]: Stopped target initrd-switch-root.target.1261test # [ 7.106082] systemd[1]: Reached target Virtual Machines and Containers.1262test # [ 7.107630] systemd[1]: Reached target Path Units.1263test # [ 7.109027] systemd[1]: Reached target Remote File Systems.1264test # [ 7.110495] systemd[1]: Reached target Slice Units.1265second # [ 7.155014] systemd[1]: Starting Journal Service...1266test # [ 7.111892] systemd[1]: Reached target Swaps.1267test # [ 7.116539] systemd[1]: Listening on Process Core Dump Socket.1268test # [ 7.120435] systemd[1]: Listening on Credential Encryption/Decryption.1269test # [ 7.125284] systemd[1]: Starting Journal Log Access Socket...1270test # [ 7.127513] systemd[1]: Listening on Journal Audit Socket.1271test # [ 7.129095] systemd[1]: Listening on Network Management Metrics Varlink Socket.1272test # [ 7.131090] systemd[1]: Listening on Network Management Netlink Socket.1273test # [ 7.132867] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1274test # [ 7.134673] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1275second # [ 7.178906] systemd[1]: Starting Load Kernel Modules...1276test # [ 7.137133] systemd[1]: Listening on udev Control Socket.1277test # [ 7.141026] systemd[1]: Mounting Huge Pages File System...1278test # [ 7.144923] systemd[1]: Mounting POSIX Message Queue File System...1279test # [ 7.149820] systemd[1]: Mounting Kernel Debug File System...1280test # [ 7.158213] systemd[1]: Mounting Kernel Trace File System...1281second # [ 7.204689] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1282test # [ 7.166166] systemd[1]: Starting Create List of Static Device Nodes...1283test # [ 7.180379] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1284second # [ 7.225347] systemd[1]: Starting Remount Root and Kernel File Systems...1285test # [ 7.182081] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1286second # [ 7.227372] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1287test # [ 7.189178] systemd[1]: Mounting Kernel Configuration File System...1288second # [ 7.234068] systemd[1]: Starting Coldplug All udev Devices...1289test # [ 7.191104] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1290test # [ 7.194199] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1291test # [ 7.209343] systemd[1]: Starting Load Kernel Module fuse...1292second # [ 7.254810] systemd[1]: Listening on Journal Log Access Socket.1293test # [ 7.210948] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671294second # [ 7.267860] systemd[1]: Mounted Huge Pages File System.1295second # [ 7.268370] systemd[1]: Mounted POSIX Message Queue File System.1296second # [ 7.268747] systemd[1]: Mounted Kernel Debug File System.1297second # [ 7.269127] systemd[1]: Mounted Kernel Trace File System.1298second # [ 7.280915] systemd[1]: Finished Create List of Static Device Nodes.1299second # [ 7.286587] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1300second # [ 7.294819] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1301second # [ 7.295397] systemd[1]: Mounted Kernel Configuration File System.1302second # [ 7.300615] fuse: init (API version 7.45)1303second # [ 7.304801] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1304test # [ 7.274219] systemd[1]: Starting Journal Service...1305second # [ 7.323491] systemd-journald[302]: Collecting audit messages is enabled.1306second # [ 7.325039] systemd[1]: modprobe@fuse.service: Deactivated successfully.1307second # [ 7.330494] systemd[1]: Finished Load Kernel Module fuse.1308second # [ 7.333689] systemd[1]: Queued start job for default target Multi-User System.1309second # [ 7.341777] systemd[1]: Finished Load Kernel Modules.1310second # [ 7.343156] systemd[1]: Started Journal Service.1311test # [ 7.300971] systemd[1]: Starting Load Kernel Modules...1312second # [ 7.338467] systemd[1]: systemd-journald.service: Deactivated successfully.1313second # [ 7.346396] systemd-modules-load[303]: Module 'atkbd' is built in1314second # [ 7.347048] systemd-modules-load[303]: Module 'ctr' is built in1315second # [ 7.347654] systemd-modules-load[303]: Module 'loop' is built in1316second # [ 7.354885] systemd[1]: Mounting FUSE Control File System...1317test # [ 7.320608] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1318second # [ 7.360298] systemd[1]: Starting Firewall...1319second # [ 7.368949] systemd[1]: Starting Apply Kernel Variables...1320second # [ 7.382565] EXT4-fs (vda): re-mounted 6903694c-da93-4331-adf0-eac2de76668b.1321test # [ 7.344262] systemd[1]: Starting Remount Root and Kernel File Systems...1322test # [ 7.346080] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1323test # [ 7.354832] systemd[1]: Starting Coldplug All udev Devices...1324test # [ 7.370134] systemd[1]: Listening on Journal Log Access Socket.1325second # [ 7.408333] systemd-oomd[304]: No swap; memory pressure usage will be degraded1326second # [ 7.410896] systemd[1]: Finished Remount Root and Kernel File Systems.1327second # [ 7.422005] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1328test # [ 7.399923] systemd[1]: Mounted Huge Pages File System.1329test # [ 7.400448] systemd[1]: Mounted POSIX Message Queue File System.1330test # [ 7.400835] systemd[1]: Mounted Kernel Debug File System.1331test # [ 7.401201] systemd[1]: Mounted Kernel Trace File System.1332test # [ 7.409647] fuse: init (API version 7.45)1333test # [ 7.415482] systemd[1]: Finished Create List of Static Device Nodes.1334test # [ 7.418873] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1335test # [ 7.422263] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1336test # [ 7.422820] systemd[1]: Mounted Kernel Configuration File System.1337second # [ 7.468147] systemd[1]: Starting Flush Journal to Persistent Storage...1338test # [ 7.435147] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1339test # [ 7.437796] systemd[1]: modprobe@fuse.service: Deactivated successfully.1340second # [ 7.476279] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1341second # [ 7.482667] systemd[1]: Starting Load/Save OS Random Seed...1342test # [ 7.445142] systemd[1]: Finished Load Kernel Module fuse.1343second # [ 7.484575] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1344test # [ 7.450179] systemd[1]: Finished Load Kernel Modules.1345test # [ 7.458021] systemd-journald[325]: Collecting audit messages is enabled.1346second # [ 7.502659] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1347test # [ 7.460169] systemd[1]: Queued start job for default target Multi-User System.1348test # [ 7.463651] systemd[1]: systemd-journald.service: Deactivated successfully.1349test # [ 7.468586] systemd-modules-load[326]: Module 'atkbd' is built in1350second # [ 7.515046] systemd[1]: Starting Create Static Device Nodes in /dev...1351test # [ 7.479863] systemd[1]: Mounting FUSE Control File System...1352test # [ 7.473553] systemd-modules-load[326]: Module 'ctr' is built in1353test # [ 7.477539] systemd-modules-load[326]: Module 'loop' is built in1354test # [ 7.505537] EXT4-fs (vda): re-mounted c20ff810-82c8-40ee-9446-981f605f127a.1355second # [ 7.544790] systemd[1]: Mounted FUSE Control File System.1356test # [ 7.508632] systemd[1]: Starting Firewall...1357second # [ 7.548186] systemd[1]: Finished Apply Kernel Variables.1358test # [ 7.504902] systemd-oomd[327]: No swap; memory pressure usage will be degraded1359test # [ 7.521275] systemd[1]: Starting Apply Kernel Variables...1360second # [ 7.560319] systemd[1]: Starting Network Name Resolution...1361test # [ 7.530047] systemd[1]: Started Journal Service.1362test # [ 7.528512] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1363test # [ 7.540938] systemd[1]: Finished Remount Root and Kernel File Systems.1364second # [ 7.617951] systemd-journald[302]: Received client request to flush runtime journal.1365test # [ 7.594688] systemd[1]: Mounted FUSE Control File System.1366test # [ 7.602155] systemd[1]: Starting Flush Journal to Persistent Storage...1367test # [ 7.603555] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1368test # [ 7.611319] systemd[1]: Starting Load/Save OS Random Seed...1369test # [ 7.613109] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1370test # [ 7.636938] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1371test # [ 7.647463] systemd[1]: Starting Create Static Device Nodes in /dev...1372second # [ 7.707326] systemd[1]: Finished Load/Save OS Random Seed.1373second # [ 7.713993] systemd[1]: Reached target First Boot Complete.1374second # [ 7.721579] systemd[1]: Finished Create Static Device Nodes in /dev.1375second # [ 7.722177] systemd[1]: Reached target Preparation for Local File Systems.1376second # [ 7.722767] systemd[1]: Starting Rule-based Manager for Device Events and Files...1377second # [ 7.726782] systemd[1]: Finished Flush Journal to Persistent Storage.1378test # [ 7.685129] systemd[1]: Finished Apply Kernel Variables.1379test # [ 7.701769] systemd[1]: Starting Network Name Resolution...1380test # [ 7.714251] systemd-journald[325]: Received client request to flush runtime journal.1381second # [ 7.769082] systemd-resolved[333]: Positive Trust Anchors:1382second # [ 7.771101] systemd-resolved[333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1383second # [ 7.775069] systemd-resolved[333]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161384second # [ 7.780490] systemd-resolved[333]: 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 test1385test # [ 7.785130] systemd[1]: Finished Load/Save OS Random Seed.1386test # [ 7.787225] systemd[1]: Reached target First Boot Complete.1387test # [ 7.794233] systemd[1]: Finished Flush Journal to Persistent Storage.1388second # [ 7.849742] systemd-udevd[339]: Using default interface naming scheme 'v260'.1389test # [ 7.821713] systemd[1]: Finished Create Static Device Nodes in /dev.1390test # [ 7.822372] systemd[1]: Reached target Preparation for Local File Systems.1391test # [ 7.829693] systemd[1]: Starting Rule-based Manager for Device Events and Files...1392test # [ 7.881914] systemd-resolved[356]: Positive Trust Anchors:1393test # [ 7.888211] systemd-resolved[356]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1394test # [ 7.890365] systemd-resolved[356]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161395test # [ 7.893072] 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 test1396second # [ 7.984424] systemd-resolved[333]: Using system hostname 'second'.1397second # [ 7.993533] systemd[1]: Started Network Name Resolution.1398second # [ 8.001655] systemd[1]: Mounting /run/wrappers...1399test # [ 7.963440] systemd-udevd[363]: Using default interface naming scheme 'v260'.1400second # [ 8.049275] systemd[1]: Mounted /run/wrappers.1401second # [ 8.052872] systemd[1]: Reached target Local File Systems.1402second # [ 8.056446] systemd[1]: Listening on Boot Loader Control Service Socket.1403second # [ 8.066617] systemd[1]: Starting register-nix-paths.service...1404second # [ 8.070174] systemd[1]: Starting Create SUID/SGID Wrappers...1405second # [ 8.071030] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1406second # [ 8.079041] systemd[1]: Starting Save Transient machine-id to Disk...1407second # [ 8.082126] systemd[1]: Starting Create System Files and Directories...1408test # [ 8.086161] systemd[1]: Mounting /run/wrappers...1409second # [ 8.133025] systemd[1]: Started Rule-based Manager for Device Events and Files.1410test # [ 8.124616] systemd-resolved[356]: Using system hostname 'test'.1411test # [ 8.134115] systemd[1]: Started Network Name Resolution.1412test # [ 8.172595] systemd[1]: Mounted /run/wrappers.1413test # [ 8.173216] systemd[1]: Reached target Local File Systems.1414test # [ 8.177589] systemd[1]: Listening on Boot Loader Control Service Socket.1415test # [ 8.180986] systemd[1]: Starting register-nix-paths.service...1416test # [ 8.184531] systemd[1]: Starting Create SUID/SGID Wrappers...1417second # [ 8.234688] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1418test # [ 8.188231] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419test # [ 8.196761] systemd[1]: Starting Save Transient machine-id to Disk...1420second # [ 8.243467] systemd[1]: Finished Save Transient machine-id to Disk.1421test # [ 8.228784] systemd[1]: Starting Create System Files and Directories...1422second # [ 8.286751] systemd[1]: Finished Create System Files and Directories.1423second # [ 8.295858] systemd[1]: Starting Rebuild Journal Catalog...1424second # [ 8.304748] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1425test # [ 8.281137] systemd[1]: Started Rule-based Manager for Device Events and Files.1426test # [ 8.338684] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1427test # [ 8.349902] systemd[1]: Finished Save Transient machine-id to Disk.1428second # [ 8.428347] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1429test # [ 8.402751] systemd[1]: Finished Create System Files and Directories.1430test # [ 8.413543] systemd[1]: Starting Rebuild Journal Catalog...1431test # [ 8.424270] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432second # [ 8.535118] systemd[1]: Finished Rebuild Journal Catalog.1433second # [ 8.546507] systemd[1]: Starting Update is Completed...1434test # [ 8.580999] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1435second # [ 8.628226] systemd[1]: Finished Update is Completed.1436second # [ 8.677696] systemd[1]: Finished Coldplug All udev Devices.1437test # [ 8.631205] systemd[1]: Finished Rebuild Journal Catalog.1438test # [ 8.642058] systemd[1]: Starting Update is Completed...1439test # [ 8.724939] systemd[1]: Finished Update is Completed.1440second # [ 8.795929] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1441test # [ 8.788523] systemd[1]: Finished Coldplug All udev Devices.1442test # [ 8.912680] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1443second # [ 9.071964] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1444second # [ 9.151443] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1445second # [ 9.160134] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1446second # [ 9.162573] systemd[1]: Finished Create SUID/SGID Wrappers.1447test # [ 9.193803] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1448test # [ 9.289325] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1449test # [ 9.293147] systemd[1]: Finished Create SUID/SGID Wrappers.1450second # [ 9.344581] systemd[1]: Finished Firewall.1451second # [ 9.345586] systemd[1]: Reached target Preparation for Network.1452second # [ 9.352512] systemd[1]: Starting Network Management...1453test # [ 9.315855] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1454second # [ 9.400466] systemd[1]: Finished register-nix-paths.service.1455second # [ 9.401180] systemd[1]: Reached target System Initialization.1456second # [ 9.402627] systemd[1]: Started Discard unused filesystem blocks once a week.1457second # [ 9.405478] systemd[1]: Started Daily Cleanup of Temporary Directories.1458second # [ 9.409712] systemd[1]: Reached target Timer Units.1459second # [ 9.411085] systemd[1]: Listening on D-Bus System Message Bus Socket.1460second # [ 9.416199] systemd[1]: Listening on Nix Daemon Socket.1461second # [ 9.422415] systemd[1]: Listening on Authorization Manager Agent Helper.1462second # [ 9.423055] systemd[1]: Listening on Hostname Service Socket.1463second # [ 9.423572] systemd[1]: Reached target Socket Units.1464second # [ 9.426300] systemd[1]: Starting D-Bus System Message Bus...1465second # [ 9.480725] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.1466second # [ 9.490551] (udev-worker)[461]: Network interface NamePolicy= disabled on kernel command line.1467test # [ 9.498600] systemd[1]: Finished Firewall.1468test # [ 9.499434] systemd[1]: Reached target Preparation for Network.1469test # [ 9.504427] systemd[1]: Starting Network Management...1470test # [ 9.517564] systemd[1]: Finished register-nix-paths.service.1471test # [ 9.518157] systemd[1]: Reached target System Initialization.1472test # [ 9.518676] systemd[1]: Started Discard unused filesystem blocks once a week.1473test # [ 9.519283] systemd[1]: Started Daily Cleanup of Temporary Directories.1474test # [ 9.519847] systemd[1]: Reached target Timer Units.1475test # [ 9.525558] systemd[1]: Listening on D-Bus System Message Bus Socket.1476test # [ 9.526690] systemd[1]: Listening on Nix Daemon Socket.1477test # [ 9.536103] systemd[1]: Listening on Authorization Manager Agent Helper.1478test # [ 9.536760] systemd[1]: Listening on Hostname Service Socket.1479test # [ 9.537925] systemd[1]: Reached target Socket Units.1480test # [ 9.542696] systemd[1]: Starting D-Bus System Message Bus...1481second # [ 9.609020] cfg80211: Loading compiled-in X.509 certificates for regulatory database1482second # [ 9.625740] dbus-broker-launch[537]: Looking up NSS user entry for 'systemd-timesync'...1483second # [ 9.642643] dbus-broker-launch[537]: NSS returned no entry for 'systemd-timesync'1484test # [ 9.599804] (udev-worker)[401]: Network interface NamePolicy= disabled on kernel command line.1485second # [ 9.643905] dbus-broker-launch[537]: Invalid user-name in /nix/store/b0p4pf8py0z3xfadb6kr73d6p57pahad-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"[ 9.653903] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1486second # [ 9.654421] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1487second # 1488test # [ 9.617043] (udev-worker)[400]: Network interface NamePolicy= disabled on kernel command line.1489second # [ 9.707799] mousedev: PS/2 mouse device common for all mice1490second # [ 9.703409] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1491second # [ 9.709030] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1492second # [ 9.716725] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1493second # [ 9.724376] dbus-broker-launch[537]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1494second # [ 9.731382] systemd[1]: Started D-Bus System Message Bus.1495second # [ 9.734739] systemd-networkd[534]: /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.1496second # [ 9.739420] systemd-networkd[534]: /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.1497second # [ 9.747842] systemd-networkd[534]: lo: Link UP1498second # [ 9.749099] systemd-networkd[534]: lo: Gained carrier1499second # [ 9.749881] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1500second # [ 9.755175] systemd-networkd[534]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1501second # [ 9.760708] systemd-networkd[534]: eth1: Link UP1502second # [ 9.761147] systemd-networkd[534]: eth1: Gained carrier1503second # [ 9.761612] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1504second # [ 9.783052] dbus-broker-launch[537]: Ready1505second # [ 9.785454] systemd[1]: Started Network Management.1506test # [ 9.747742] cfg80211: Loading compiled-in X.509 certificates for regulatory database1507second # [ 9.787256] systemd[1]: Reached target Basic System.1508second # [ 9.791682] systemd[1]: Starting Network Manager Script Dispatcher Service...1509second # [ 9.797416] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1510test # [ 9.755640] dbus-broker-launch[559]: Looking up NSS user entry for 'systemd-timesync'...1511second # [ 9.808351] systemd[1]: Starting Network Manager...1512test # [ 9.771178] dbus-broker-launch[559]: NSS returned no entry for 'systemd-timesync'1513test # [ 9.772352] dbus-broker-launch[559]: Invalid user-name in /nix/store/b0p4pf8py0z3xfadb6kr73d6p57pahad-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1514second # [ 9.823439] systemd[1]: Started backdoor.service.1515test # [ 9.789601] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1516test # [ 9.790114] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1517second # [ 9.847917] systemd[1]: Starting Import lastlog data into lastlog2 database...1518test # [ 9.815238] mousedev: PS/2 mouse device common for all mice1519second # [ 9.871282] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1520test # [ 9.830855] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1521test # [ 9.833468] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1522test # [ 9.839021] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1523test # [ 9.847250] dbus-broker-launch[559]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1524test # [ 9.856665] systemd-networkd[556]: /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.1525second # [ 9.904846] systemd[1]: Starting Post-Boot Actions...1526test # [ 9.863034] systemd[1]: Started D-Bus System Message Bus.1527test # [ 9.866378] systemd-networkd[556]: /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.1528test # [ 9.872475] systemd-networkd[556]: lo: Link UP1529test # [ 9.875349] systemd-networkd[556]: lo: Gained carrier1530test # [ 9.876149] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1531second # [ 9.928798] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[547]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1532test # [ 9.879582] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1533test # [ 9.884219] systemd-networkd[556]: eth1: Link UP1534test # [ 9.884985] systemd-networkd[556]: eth1: Gained carrier1535test # [ 9.892571] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1536second # [ 9.943241] systemd[1]: Started Reset console on configuration changes.1537test # [ 9.911745] dbus-broker-launch[559]: Ready1538test # [ 9.913943] systemd[1]: Started Network Management.1539test # [ 9.915595] systemd[1]: Reached target Basic System.1540test # [ 9.919433] systemd[1]: Starting Network Manager Script Dispatcher Service...1541test # [ 9.926427] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1542test # [ 9.931787] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1543second # [ 9.988837] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1544test # [ 9.948294] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545second # [ 10.016521] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1546test # [ 9.981777] systemd[1]: Starting Network Manager...1547second # connecting to host...1548second # [ 10.048795] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1549test # [ 10.006408] systemd[1]: Started backdoor.service.1550test # [ 10.037869] systemd[1]: Starting Import lastlog data into lastlog2 database...1551test # [ 10.062817] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1552second # [ 10.123491] systemd[1]: Finished Post-Boot Actions.1553second # [ 10.132632] systemd[1]: Started Name Service Cache Daemon (nsncd).1554second # [ 10.136418] nsncd[551]: Sep 18 20:03:56.961 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1555test # [ 10.092246] ispsphlnyb2js715g0mg6bn3157dmskk-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1556second # [ 10.145311] systemd[1]: Reached target Host and Network Name Lookups.1557second # [ 10.145948] systemd[1]: Reached target User and Group Name Lookups.1558test # [ 10.102344] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1559second # [ 10.151767] systemd[1]: Starting User Login Management...1560second # [ 10.153839] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1561test # [ 10.109956] vz5k34ilhns09vh81dvgw89nvkf1plwv-wifi-secrets[571]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1562test # [ 10.119603] systemd[1]: Starting Post-Boot Actions...1563second # [ 10.191440] systemd[1]: Finished Import lastlog data into lastlog2 database.1564test # [ 10.157989] systemd[1]: Started Reset console on configuration changes.1565second # [ 10.206528] systemd[1]: Started Network Manager Script Dispatcher Service.1566test # [ 10.202499] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1567test # connecting to host...1568second # [ 10.302187] systemd[1]: Starting Hostname Service...1569test # [ 10.258023] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1570second # [ 10.305382] systemd-logind[581]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1571second # [ 10.310942] systemd-logind[581]: Watching system buttons on /dev/input/event0 (gpio-keys)1572second # [ 10.311633] systemd-logind[581]: New seat seat0.1573second # [ 10.314734] systemd[1]: Started User Login Management.1574test # [ 10.268483] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1575test # [ 10.269212] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1576second # [ 10.323407] systemd[1]: Starting linger-users.service...1577test # [ 10.279466] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1578test # [ 10.285320] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1579test # [ 10.293863] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1580test: Guest shell says: b'Spawning backdoor root shell...\n'1581test: connected to guest root shell1582test: (connecting took 10.59 seconds)1583test: (finished: waiting for the VM to finish booting, in 10.59 seconds)1584test # [ 10.302689] systemd[1]: Finished Post-Boot Actions.1585second # [ 10.401301] systemd[1]: linger-users.service: Deactivated successfully.1586second # [ 10.402002] systemd[1]: Finished linger-users.service.1587test # [ 10.368931] systemd[1]: Started Network Manager Script Dispatcher Service.1588test # [ 10.372761] systemd[1]: Started Name Service Cache Daemon (nsncd).1589test # [ 10.377566] nsncd[582]: Sep 18 20:03:58.199 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1590test # [ 10.378809] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1591test # [ 10.388599] systemd[1]: Reached target Host and Network Name Lookups.1592test # [ 10.392397] systemd[1]: Reached target User and Group Name Lookups.1593test # [ 10.398072] systemd[1]: Starting User Login Management...1594second # [ 10.447212] systemd[1]: Started Hostname Service.1595test # [ 10.437619] systemd[1]: Finished Import lastlog data into lastlog2 database.1596second # [ 10.545631] systemd-networkd[534]: eth0: Link UP1597second # [ 10.546132] systemd-networkd[534]: eth0: Gained carrier1598test # [ 10.519598] systemd[1]: Starting Hostname Service...1599second # [ 10.566711] systemd[1]: Started Network Manager.1600second # [ 10.568846] systemd[1]: Reached target Network.1601second # [ 10.574409] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1602second # [ 10.580636] systemd[1]: Starting Permit User Sessions...1603test # [ 10.545816] systemd-logind[625]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1604test # [ 10.549938] systemd-logind[625]: Watching system buttons on /dev/input/event0 (gpio-keys)1605test # [ 10.553832] systemd-logind[625]: New seat seat0.1606test # [ 10.554996] systemd[1]: Started User Login Management.1607test # [ 10.557770] systemd[1]: Starting linger-users.service...1608test # [ 10.636375] systemd[1]: linger-users.service: Deactivated successfully.1609test # [ 10.638147] systemd[1]: Finished linger-users.service.1610test # [ 10.654910] systemd[1]: Started Hostname Service.1611second # [ 10.744277] systemd[1]: Finished Permit User Sessions.1612second # [ 10.755710] systemd[1]: Started Getty on tty1.1613second # [ 10.759278] systemd[1]: Reached target Login Prompts.1614test # [ 10.753395] systemd-networkd[556]: eth0: Link UP1615test # [ 10.753885] systemd-networkd[556]: eth0: Gained carrier1616test # [ 10.773324] systemd[1]: Started Network Manager.1617test # [ 10.775357] systemd[1]: Reached target Network.1618test # [ 10.781064] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1619second # [ 10.833245] systemd-resolved[333]: eth0: Bus client set default route setting: yes1620test # [ 10.787334] systemd[1]: Starting Permit User Sessions...1621second # [ 10.838076] systemd-resolved[333]: eth0: Bus client set DNS server list to: 10.0.2.31622second # [ 10.881300] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1623second # [ 10.884296] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1624test # [ 10.912581] systemd-networkd[556]: eth1: Gained IPv6LL1625second # [ 10.977689] systemd-networkd[534]: eth1: Gained IPv6LL1626test # [ 10.950820] systemd[1]: Finished Permit User Sessions.1627test # [ 10.969517] systemd[1]: Started Getty on tty1.1628test # [ 10.972404] systemd[1]: Reached target Login Prompts.1629test # [ 10.977762] systemd-resolved[356]: eth0: Bus client set default route setting: yes1630second # [ 11.028392] systemd[1]: Reached target Multi-User System.1631test # [ 10.982750] systemd-resolved[356]: eth0: Bus client set DNS server list to: 10.0.2.31632second # [ 11.032068] systemd[1]: Startup finished in 772ms (kernel) + 4.132s (initrd) + 6.124s (userspace) = 11.030s.1633test # [ 11.022028] NetworkManager[572]: <warn> [1789761838.8523] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1634test # [ 11.076397] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1635test # [ 11.078012] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1636test # [ 11.218078] systemd[1]: Reached target Multi-User System.1637test # [ 11.222601] systemd[1]: Startup finished in 724ms (kernel) + 4.283s (initrd) + 6.213s (userspace) = 11.221s.1638second # [ 11.365713] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31639second # [ 11.609271] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1640test: (finished: waiting for unit NetworkManager.service, in 11.85 seconds)1641test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1642second # [ 11.648674] systemd[1]: Starting Virtual Console Setup...1643second # [ 11.670751] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1644second # [ 11.671479] systemd[1]: Stopped Virtual Console Setup.1645second # [ 11.675937] systemd[1]: Starting Virtual Console Setup...1646second # [ 11.685818] systemd-logind[581]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1647test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1648test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1649test # [ 11.678897] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31650test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1651test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1652test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.05 seconds)1653(finished: run the VM test script, in 12.44 seconds)1654test script finished in 12.51s1655cleanup1656kill QemuMachine (pid 15)1657second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1658kill QemuMachine (pid 14)1659test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1660(finished: cleanup, in 0.27 seconds)