vm-test-run-wifi
checks.aarch64-linux.wifi
· build #749
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13test: QEMU running (pid 15)14second # Disk image does not exist, creating the virtualisation disk image...15second: QEMU running (pid 14)16second # Formatting '/build/vm-state-second/tmp.Ctgp3zVLPk', fmt=raw size=107374182417test # Disk image does not exist, creating the virtualisation disk image...18second # mke2fs 1.47.4 (6-Mar-2025)19test # Formatting '/build/vm-state-test/tmp.n3orCf4odb', fmt=raw size=107374182420second # Discarding device blocks: 0/262144 done21(finished: start all VMs, in 0.45 seconds)22second # Creating filesystem with 262144 4k blocks and 65536 inodes23test: waiting for unit NetworkManager.service24second # Filesystem UUID: bca3f378-901c-4a9f-b66c-920b593fb3e425test: waiting for the VM to finish booting26second # Superblock backups stored on blocks:27test # mke2fs 1.47.4 (6-Mar-2025)28second # 32768, 98304, 163840, 22937629test # Discarding device blocks: 0/262144 done30second # 31test # Creating filesystem with 262144 4k blocks and 65536 inodes32second # Allocating group tables: 0/8 done33test # Filesystem UUID: 93c9ddb1-2713-48fd-8940-9d5b86a6065534second # Writing inode tables: 0/8 done35test # Superblock backups stored on blocks:36second # Creating journal (8192 blocks): done37test # 32768, 98304, 163840, 22937638second # Writing superblocks and filesystem accounting information: 0/8 done39test # 40second # 41test # Allocating group tables: 0/8 done42second # Virtualisation disk image created.43test # Writing inode tables: 0/8 done44test # Creating journal (8192 blocks): done45test # Writing superblocks and filesystem accounting information: 0/8 done46test # 47test # Virtualisation disk image created.48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.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 KASLR79test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]80second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)81second # [ 0.000000] CPU features: detected: ARM erratum 141804082test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202683test # [ 0.000000] KASLR enabled84second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion85test # [ 0.000000] random: crng init done86second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing87test # [ 0.000000] Machine model: linux,dummy-virt88test # [ 0.000000] efi: UEFI not found.89second # [ 0.000000] alternatives: applying boot alternatives90test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT91test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]92test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]93test # [ 0.000000] Zone ranges:94test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]95test # [ 0.000000] DMA32 empty96test # [ 0.000000] Normal empty97second # [ 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/f8z2991bmvsmmq1q37nk6ir6pmwgm714-nixos-system-second-test/init regInfo=/nix/store/3rl5dpjfmn57hm5kzfkg5jb9ch52r5bg-closure-info/registration console=ttyAMA0,115200n8 console=tty098test # [ 0.000000] Device empty99test # [ 0.000000] Movable zone start for each node100test # [ 0.000000] Early memory node ranges101second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/3rl5dpjfmn57hm5kzfkg5jb9ch52r5bg-closure-info/registration", will be passed to user space.102test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]103second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes104test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]105test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca00000106second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)107test # [ 0.000000] psci: probing for conduit method from DT.108second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)109test # [ 0.000000] psci: PSCIv1.3 detected in firmware.110second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB111test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs112second # [ 0.000000] software IO TLB: area num 1.113test # [ 0.000000] psci: Trusted OS migration not required114test # [ 0.000000] psci: SMC Calling Convention v1.1115second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)116second # [ 0.000000] Fallback order for Node 0: 0117test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)118second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144119test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296120second # [ 0.000000] Policy zone: DMA121test # [ 0.000000] Detected PIPT I-cache on CPU0122second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off123test # [ 0.000000] CPU features: detected: GICv3 CPU interface124test # [ 0.000000] CPU features: detected: Spectre-v4125second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1126test # [ 0.000000] CPU features: detected: Spectre-BHB127second # [ 0.000000] allocated 2097152 bytes of page_ext128second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages129test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR130second # [ 0.000000] ftrace: allocated 294 pages with 4 groups131test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)132second # [ 0.000000] rcu: Hierarchical RCU implementation.133test # [ 0.000000] CPU features: detected: ARM erratum 1418040134second # [ 0.000000] rcu: RCU event tracing is enabled.135test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion136second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.137test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing138second # [ 0.000000] Trampoline variant of Tasks RCU enabled.139test # [ 0.000000] alternatives: applying boot alternatives140second # [ 0.000000] Rude variant of Tasks RCU enabled.141second # [ 0.000000] Tracing variant of Tasks RCU enabled.142second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.143second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1144second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.145test # [ 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/4xlf8h0kwvz1rdm88ndanzdwijzj0mfy-nixos-system-test-test/init regInfo=/nix/store/98ybqjvfxslby0fp2r30rvyn8lbi6yya-closure-info/registration console=ttyAMA0,115200n8 console=tty0146second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.147test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/98ybqjvfxslby0fp2r30rvyn8lbi6yya-closure-info/registration", will be passed to user space.148second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.149second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0150test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes151second # [ 0.000000] GICv3: 256 SPIs implemented152second # [ 0.000000] GICv3: 0 Extended SPIs implemented153test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)154second # [ 0.000000] Root IRQ handler: gic_handle_irq155test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)156second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI157test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB158second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0159test # [ 0.000000] software IO TLB: area num 1.160second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000161second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]162test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)163test # [ 0.000000] Fallback order for Node 0: 0164second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)165test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144166test # [ 0.000000] Policy zone: DMA167second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)168test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off169second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000170test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1171test # [ 0.000000] allocated 2097152 bytes of page_ext172second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000173test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages174second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.175test # [ 0.000000] ftrace: allocated 294 pages with 4 groups176test # [ 0.000000] rcu: Hierarchical RCU implementation.177second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns178test # [ 0.000000] rcu: RCU event tracing is enabled.179second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040180test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.181second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).182test # [ 0.000000] Trampoline variant of Tasks RCU enabled.183test # [ 0.000000] Rude variant of Tasks RCU enabled.184second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns185test # [ 0.000000] Tracing variant of Tasks RCU enabled.186test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.187second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns188second # [ 0.000043] arm-pv: using stolen time PV189test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1190test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.191second # [ 0.000383] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)192second # [ 0.000684] Console: colour dummy device 80x25193test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.194second # [ 0.000696] printk: legacy console [tty0] enabled195test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.196second # [ 0.000897] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)197test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0198second # [ 0.000902] pid_max: default: 32768 minimum: 301199test # [ 0.000000] GICv3: 256 SPIs implemented200second # [ 0.000973] LSM: initializing lsm=capability,landlock,yama,bpf,ima201test # [ 0.000000] GICv3: 0 Extended SPIs implemented202second # [ 0.001115] landlock: Up and running.203test # [ 0.000000] Root IRQ handler: gic_handle_irq204second # [ 0.001117] Yama: becoming mindful.205test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI206second # [ 0.001556] LSM support for eBPF active207test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0208second # [ 0.001728] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)209test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000210test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]211second # [ 0.001747] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)212second # [ 0.004423] rcu: Hierarchical SRCU implementation.213test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)214second # [ 0.004430] rcu: Max phase no-delay instances is 1000.215second # [ 0.005379] fsl-mc MSI: its@8080000 domain created216test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)217second # [ 0.005525] EFI services will not be available.218test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000219second # [ 0.005629] smp: Bringing up secondary CPUs ...220second # [ 0.005656] smp: Brought up 1 node, 1 CPU221test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000222second # [ 0.005658] SMP: Total of 1 processors activated.223test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.224second # [ 0.005660] CPU: All CPU(s) started at EL1225second # [ 0.005672] CPU features: detected: 32-bit EL0 Support226test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns227second # [ 0.005675] CPU features: detected: Data cache clean to the PoU not required for I/D coherence228test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040229second # [ 0.005678] CPU features: detected: Common not Private translations230test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).231second # [ 0.005680] CPU features: detected: CRC32 instructions232test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns233second # [ 0.005682] CPU features: detected: Data cache clean to Point of Persistence234second # [ 0.005685] CPU features: detected: Enhanced Virtualization Traps235test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns236second # [ 0.005694] CPU features: detected: RCpc load-acquire (LDAPR)237test # [ 0.000043] arm-pv: using stolen time PV238second # [ 0.005697] CPU features: detected: LSE atomic instructions239test # [ 0.000389] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)240second # [ 0.005698] CPU features: detected: Privileged Access Never241test # [ 0.000673] Console: colour dummy device 80x25242second # [ 0.005701] CPU features: detected: PMUv3243test # [ 0.000684] printk: legacy console [tty0] enabled244second # [ 0.005702] CPU features: detected: RAS Extension Support245second # [ 0.005706] CPU features: detected: Speculative Store Bypassing Safe (SSBS)246test # [ 0.000897] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)247second # [ 0.005727] alternatives: applying system-wide alternatives248test # [ 0.000902] pid_max: default: 32768 minimum: 301249test # [ 0.000967] LSM: initializing lsm=capability,landlock,yama,bpf,ima250second # [ 0.007115] CPU features: detected: Hardware dirty bit management on CPU0251test # [ 0.001129] landlock: Up and running.252test # [ 0.001132] Yama: becoming mindful.253test # [ 0.001652] LSM support for eBPF active254second # [ 0.007322] Memory: 893156K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)255second # [ 0.007793] devtmpfs: initialized256test # [ 0.001828] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)257second # [ 0.009248] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)258test # [ 0.001845] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)259test # [ 0.004531] rcu: Hierarchical SRCU implementation.260second # [ 0.009274] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).261test # [ 0.004539] rcu: Max phase no-delay instances is 1000.262second # [ 0.009399] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL263test # [ 0.005473] fsl-mc MSI: its@8080000 domain created264second # [ 0.009402] 0 pages in range for non-PLT usage265test # [ 0.005637] EFI services will not be available.266second # [ 0.009403] 508336 pages in range for PLT usage267test # [ 0.005767] smp: Bringing up secondary CPUs ...268second # [ 0.009527] pinctrl core: initialized pinctrl subsystem269test # [ 0.005794] smp: Brought up 1 node, 1 CPU270second # [ 0.010303] DMI not present or invalid.271test # [ 0.005796] SMP: Total of 1 processors activated.272test # [ 0.005798] CPU: All CPU(s) started at EL1273second # [ 0.013940] NET: Registered PF_NETLINK/PF_ROUTE protocol family274test # [ 0.005810] CPU features: detected: 32-bit EL0 Support275second # [ 0.016501] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations276test # [ 0.005813] CPU features: detected: Data cache clean to the PoU not required for I/D coherence277second # [ 0.016654] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations278test # [ 0.005816] CPU features: detected: Common not Private translations279second # [ 0.016825] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations280test # [ 0.005818] CPU features: detected: CRC32 instructions281second # [ 0.016855] audit: initializing netlink subsys (disabled)282test # [ 0.005820] CPU features: detected: Data cache clean to Point of Persistence283second # [ 0.017515] thermal_sys: Registered thermal governor 'fair_share'284test # [ 0.005823] CPU features: detected: Enhanced Virtualization Traps285second # [ 0.017518] thermal_sys: Registered thermal governor 'bang_bang'286test # [ 0.005832] CPU features: detected: RCpc load-acquire (LDAPR)287second # [ 0.017521] thermal_sys: Registered thermal governor 'step_wise'288test # [ 0.005834] CPU features: detected: LSE atomic instructions289second # [ 0.017523] thermal_sys: Registered thermal governor 'user_space'290test # [ 0.005836] CPU features: detected: Privileged Access Never291test # [ 0.005838] CPU features: detected: PMUv3292second # [ 0.017525] thermal_sys: Registered thermal governor 'power_allocator'293test # [ 0.005840] CPU features: detected: RAS Extension Support294second # [ 0.017555] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1295test # [ 0.005844] CPU features: detected: Speculative Store Bypassing Safe (SSBS)296second # [ 0.017570] cpuidle: using governor ladder297second # [ 0.017575] cpuidle: using governor menu298test # [ 0.005866] alternatives: applying system-wide alternatives299second # [ 0.017705] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.300test # [ 0.007264] CPU features: detected: Hardware dirty bit management on CPU0301second # [ 0.017727] ASID allocator initialised with 32768 entries302second # [ 0.018419] Serial: AMBA PL011 UART driver303test # [ 0.007467] Memory: 893156K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 114092K reserved, 32768K cma-reserved)304test # [ 0.007925] devtmpfs: initialized305second # [ 0.021563] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1306second # [ 0.021752] printk: console [ttyAMA0] enabled307test # [ 0.009362] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)308test # [ 0.009383] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).309test # [ 0.009516] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL310test # [ 0.009519] 0 pages in range for non-PLT usage311test # [ 0.009520] 508336 pages in range for PLT usage312test # [ 0.009648] pinctrl core: initialized pinctrl subsystem313test # [ 0.010368] DMI not present or invalid.314test # [ 0.013831] NET: Registered PF_NETLINK/PF_ROUTE protocol family315test # [ 0.016336] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations316test # [ 0.016486] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations317test # [ 0.016660] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations318test # [ 0.016686] audit: initializing netlink subsys (disabled)319test # [ 0.017241] thermal_sys: Registered thermal governor 'fair_share'320test # [ 0.017244] thermal_sys: Registered thermal governor 'bang_bang'321test # [ 0.017246] thermal_sys: Registered thermal governor 'step_wise'322second # [ 0.093969] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages323test # [ 0.017248] thermal_sys: Registered thermal governor 'user_space'324second # [ 0.093994] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page325test # [ 0.017250] thermal_sys: Registered thermal governor 'power_allocator'326second # [ 0.093999] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages327test # [ 0.017279] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1328second # [ 0.094004] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page329test # [ 0.017293] cpuidle: using governor ladder330test # [ 0.017298] cpuidle: using governor menu331second # [ 0.094008] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages332test # [ 0.017424] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.333second # [ 0.094011] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page334test # [ 0.017446] ASID allocator initialised with 32768 entries335second # [ 0.094014] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages336test # [ 0.018131] Serial: AMBA PL011 UART driver337second # [ 0.094018] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page338test # [ 0.021504] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1339test # [ 0.021665] printk: console [ttyAMA0] enabled340second # [ 0.099086] fbcon: Taking over console341second # [ 0.099107] ACPI: Interpreter disabled.342second # [ 0.100364] iommu: Default domain type: Translated343second # [ 0.100375] iommu: DMA domain TLB invalidation policy: strict mode344second # [ 0.105985] SCSI subsystem initialized345test # [ 0.094124] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages346test # [ 0.094146] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page347second # [ 0.106555] usbcore: registered new interface driver usbfs348test # [ 0.094150] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages349second # [ 0.106595] usbcore: registered new interface driver hub350second # [ 0.106605] usbcore: registered new device driver usb351test # [ 0.094156] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page352second # [ 0.106815] pps_core: LinuxPPS API ver. 1 registered353test # [ 0.094159] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages354test # [ 0.094162] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page355second # [ 0.106821] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>356second # [ 0.106828] PTP clock support registered357test # [ 0.094165] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages358second # [ 0.106858] EDAC MC: Ver: 3.0.0359test # [ 0.094168] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page360second # [ 0.109910] scmi_core: SCMI protocol bus registered361second # [ 0.110634] FPGA manager framework362second # [ 0.111813] vgaarb: loaded363test # [ 0.099312] fbcon: Taking over console364test # [ 0.099333] ACPI: Interpreter disabled.365second # [ 0.112305] clocksource: Switched to clocksource arch_sys_counter366test # [ 0.100831] iommu: Default domain type: Translated367test # [ 0.100844] iommu: DMA domain TLB invalidation policy: strict mode368test # [ 0.102019] SCSI subsystem initialized369second # [ 0.122762] VFS: Disk quotas dquot_6.6.0370test # [ 0.110675] usbcore: registered new interface driver usbfs371second # [ 0.122804] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)372test # [ 0.110716] usbcore: registered new interface driver hub373test # [ 0.110729] usbcore: registered new device driver usb374test # [ 0.110951] pps_core: LinuxPPS API ver. 1 registered375test # [ 0.110956] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>376test # [ 0.110963] PTP clock support registered377second # [ 0.125194] netfs: FS-Cache loaded378test # [ 0.110997] EDAC MC: Ver: 3.0.0379second # [ 0.125333] pnp: PnP ACPI: disabled380test # [ 0.113998] scmi_core: SCMI protocol bus registered381test # [ 0.114802] FPGA manager framework382test # [ 0.115960] vgaarb: loaded383second # [ 0.127779] NET: Registered PF_INET protocol family384test # [ 0.116438] clocksource: Switched to clocksource arch_sys_counter385second # [ 0.127968] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)386test # [ 0.121573] VFS: Disk quotas dquot_6.6.0387test # [ 0.121612] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)388test # [ 0.124025] netfs: FS-Cache loaded389test # [ 0.124146] pnp: PnP ACPI: disabled390test # [ 0.126579] NET: Registered PF_INET protocol family391test # [ 0.126770] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)392second # [ 0.152265] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)393second # [ 0.152299] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)394second # [ 0.153690] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)395second # [ 0.153738] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)396second # [ 0.153806] TCP: Hash tables configured (established 8192 bind 8192)397second # [ 0.153902] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)398second # [ 0.153966] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)399second # [ 0.154018] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)400second # [ 0.154175] NET: Registered PF_UNIX/PF_LOCAL protocol family401second # [ 0.154209] NET: Registered PF_XDP protocol family402second # [ 0.154231] PCI: CLS 0 bytes, default 64403second # [ 0.154441] Trying to unpack rootfs image as initramfs...404second # [ 0.165168] kvm [1]: HYP mode not available405test # [ 0.151295] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)406test # [ 0.151327] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)407test # [ 0.151341] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)408test # [ 0.151378] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)409test # [ 0.151446] TCP: Hash tables configured (established 8192 bind 8192)410test # [ 0.151545] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)411test # [ 0.151607] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)412test # [ 0.151656] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.151787] NET: Registered PF_UNIX/PF_LOCAL protocol family414test # [ 0.151851] NET: Registered PF_XDP protocol family415test # [ 0.151872] PCI: CLS 0 bytes, default 64416test # [ 0.152091] Trying to unpack rootfs image as initramfs...417test # [ 0.166504] kvm [1]: HYP mode not available418second # [ 0.236731] Initialise system trusted keyrings419second # [ 0.237287] workingset: timestamp_bits=42 max_order=18 bucket_order=0420second # [ 0.238295] squashfs: version 4.0 (2009/01/31) Phillip Lougher421second # [ 0.238826] 9p: Installing v9fs 9p2000 file system support422test # [ 0.233777] Initialise system trusted keyrings423test # [ 0.234305] workingset: timestamp_bits=42 max_order=18 bucket_order=0424test # [ 0.235299] squashfs: version 4.0 (2009/01/31) Phillip Lougher425test # [ 0.235830] 9p: Installing v9fs 9p2000 file system support426second # [ 0.261800] Key type asymmetric registered427second # [ 0.261833] Asymmetric key parser 'x509' registered428second # [ 0.261901] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)429second # [ 0.263292] io scheduler mq-deadline registered430second # [ 0.263304] io scheduler kyber registered431test # [ 0.258811] Key type asymmetric registered432test # [ 0.258844] Asymmetric key parser 'x509' registered433test # [ 0.258935] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)434test # [ 0.260331] io scheduler mq-deadline registered435test # [ 0.260343] io scheduler kyber registered436second # [ 0.272459] pl061_gpio 9030000.pl061: PL061 GPIO chip registered437second # [ 0.272823] ledtrig-cpu: registered to indicate activity on CPUs438test # [ 0.268579] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439second # [ 0.273133] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:440second # [ 0.273154] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000441second # [ 0.273175] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000442second # [ 0.273182] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000443second # [ 0.273226] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits444test # [ 0.269459] ledtrig-cpu: registered to indicate activity on CPUs445second # [ 0.273260] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]446test # [ 0.269804] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:447second # [ 0.273348] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00448second # [ 0.273367] pci_bus 0000:00: root bus resource [bus 00-ff]449test # [ 0.269828] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000450second # [ 0.273372] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]451test # [ 0.269842] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000452second # [ 0.273376] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]453test # [ 0.269848] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000454second # [ 0.273379] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]455test # [ 0.269884] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits456second # [ 0.273514] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint457test # [ 0.269919] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]458second # [ 0.273927] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint459second # [ 0.274042] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]460test # [ 0.269989] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00461test # [ 0.270007] pci_bus 0000:00: root bus resource [bus 00-ff]462second # [ 0.274053] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]463test # [ 0.270012] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]464second # [ 0.274074] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]465test # [ 0.270016] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]466second # [ 0.274085] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]467test # [ 0.270020] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]468second # [ 0.274366] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint469second # [ 0.274478] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]470test # [ 0.270135] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint471second # [ 0.274488] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]472test # [ 0.270582] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint473second # [ 0.274510] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]474test # [ 0.270703] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]475test # [ 0.270714] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]476second # [ 0.274778] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint477second # [ 0.274891] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]478test # [ 0.270734] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]479second # [ 0.274902] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]480test # [ 0.270746] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]481second # [ 0.274922] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]482test # [ 0.271042] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint483test # [ 0.271160] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]484second # [ 0.275193] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint485test # [ 0.271171] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]486second # [ 0.275302] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]487second # [ 0.275312] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]488test # [ 0.271191] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]489second # [ 0.275332] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]490test # [ 0.271473] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint491test # [ 0.271601] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]492second # [ 0.275624] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint493second # [ 0.275735] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]494test # [ 0.271613] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.275746] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]496test # [ 0.271632] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.275766] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]498test # [ 0.271913] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint499test # [ 0.272029] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]500second # [ 0.276038] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint501test # [ 0.272041] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]502second # [ 0.276149] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]503second # [ 0.276160] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]504test # [ 0.272062] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505second # [ 0.276179] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506test # [ 0.272343] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint507second # [ 0.276475] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint508second # [ 0.276587] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]509second # [ 0.276597] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]510second # [ 0.276617] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]511second # [ 0.276632] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]512second # [ 0.276942] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint513second # [ 0.277057] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]514test # [ 0.289036] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]515second # [ 0.277075] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516test # [ 0.289060] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]517second # [ 0.277358] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint518test # [ 0.289081] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]519second # [ 0.277473] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]520test # [ 0.289438] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint521second # [ 0.277492] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522test # [ 0.289558] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]523second # [ 0.277742] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint524test # [ 0.289569] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]525second # [ 0.277852] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]526test # [ 0.289588] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]527second # [ 0.278006] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint528test # [ 0.289862] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint529second # [ 0.278230] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]530test # [ 0.289976] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]531second # [ 0.278247] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]532test # [ 0.289987] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]533second # [ 0.278271] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534test # [ 0.290007] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535test # [ 0.290022] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]536second # [ 0.278569] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint537second # [ 0.278682] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]538test # [ 0.290356] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint539second # [ 0.278692] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]540test # [ 0.290473] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]541second # [ 0.278712] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542test # [ 0.290495] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543second # [ 0.279116] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned544test # [ 0.290775] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint545second # [ 0.279127] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned546test # [ 0.290891] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]547second # [ 0.279131] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned548test # [ 0.290911] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549second # [ 0.279164] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned550test # [ 0.291160] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint551test # [ 0.291273] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]552second # [ 0.279194] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned553test # [ 0.291438] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint554second # [ 0.279224] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned555test # [ 0.291684] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]556second # [ 0.279254] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned557test # [ 0.291699] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]558test # [ 0.291719] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]559second # [ 0.279283] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned560second # [ 0.279313] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned561test # [ 0.292018] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint562test # [ 0.292133] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]563second # [ 0.279344] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned564test # [ 0.292143] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]565second # [ 0.279375] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned566test # [ 0.292164] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]567second # [ 0.279404] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned568second # [ 0.279494] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned569second # [ 0.279536] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned570second # [ 0.279551] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned571second # [ 0.279565] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned572second # [ 0.279580] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned573second # [ 0.279595] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned574second # [ 0.279610] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned575second # [ 0.279625] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned576second # [ 0.279639] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned577second # [ 0.279654] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned578second # [ 0.279668] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned579second # [ 0.279684] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned580second # [ 0.279699] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned581second # [ 0.279713] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned582second # [ 0.279728] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned583second # [ 0.279742] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned584second # [ 0.279756] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned585test # [ 0.312645] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned586second # [ 0.279776] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned587test # [ 0.312667] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned588second # [ 0.279789] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned589test # [ 0.312673] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned590second # [ 0.279804] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned591second # [ 0.279818] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned592test # [ 0.312710] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned593second # [ 0.279832] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned594test # [ 0.312742] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned595second # [ 0.279852] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]596test # [ 0.312774] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned597second # [ 0.279858] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]598second # [ 0.279862] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]599test # [ 0.312804] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned600test # [ 0.312834] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned601test # [ 0.312866] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned602test # [ 0.312898] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned603test # [ 0.312929] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned604test # [ 0.312960] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned605test # [ 0.313048] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned606test # [ 0.313079] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned607test # [ 0.313094] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned608test # [ 0.313108] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned609test # [ 0.313122] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned610test # [ 0.313137] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned611test # [ 0.313151] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned612second # [ 0.336623] pci 0000:00:0a.0: enabling device (0000 -> 0002)613test # [ 0.313166] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned614test # [ 0.313180] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned615test # [ 0.313195] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned616test # [ 0.313209] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned617test # [ 0.313225] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned618test # [ 0.313239] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned619test # [ 0.313254] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned620test # [ 0.313268] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned621test # [ 0.313282] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned622test # [ 0.313296] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned623test # [ 0.313317] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned624test # [ 0.313332] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned625test # [ 0.313346] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned626test # [ 0.313361] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned627second # [ 0.343138] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)628test # [ 0.313376] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned629test # [ 0.313396] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]630test # [ 0.313402] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]631test # [ 0.313405] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]632test # [ 0.314103] pci 0000:00:0a.0: enabling device (0000 -> 0002)633second # [ 0.353399] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)634second # [ 0.355261] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)635test # [ 0.345574] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)636test # [ 0.347542] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)637second # [ 0.366380] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)638second # [ 0.368194] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)639second # [ 0.371124] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)640test # [ 0.358337] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)641test # [ 0.360350] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)642second # [ 0.378100] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)643test # [ 0.370066] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)644test # [ 0.372106] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)645second # [ 0.384863] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)646second # [ 0.386554] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)647test # [ 0.375065] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)648second # [ 0.392364] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)649test # [ 0.381815] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)650second # [ 0.394593] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)651test # [ 0.388637] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)652test # [ 0.390551] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)653second # [ 0.405939] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled654second # [ 0.407546] msm_serial: driver initialized655second # [ 0.407626] SuperH (H)SCI(F) driver initialized656second # [ 0.407662] STM32 USART driver initialized657test # [ 0.400716] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)658test # [ 0.408779] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled659test # [ 0.410397] msm_serial: driver initialized660test # [ 0.410478] SuperH (H)SCI(F) driver initialized661test # [ 0.410526] STM32 USART driver initialized662second # [ 0.434021] loop: module loaded663second # [ 0.434167] virtio_blk virtio5: 1/0/0 default/read/poll queues664second # [ 0.434752] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)665test # [ 0.431113] loop: module loaded666test # [ 0.431252] virtio_blk virtio5: 1/0/0 default/read/poll queues667test # [ 0.431848] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)668second # [ 0.440804] megasas: 07.734.00.00-rc1669second # [ 0.441286] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]670second # [ 0.443632] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000671second # [ 0.443655] Intel/Sharp Extended Query Table at 0x0031672second # [ 0.445458] Using buffer write method673second # [ 0.445492] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]674second # [ 0.447201] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000675second # [ 0.447219] Intel/Sharp Extended Query Table at 0x0031676second # [ 0.453227] Using buffer write method677second # [ 0.453256] Concatenating MTD devices:678second # [ 0.453259] (0): "0.flash"679second # [ 0.453262] (1): "0.flash"680second # [ 0.453264] into device "0.flash"681test # [ 0.440892] megasas: 07.734.00.00-rc1682test # [ 0.441399] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]683test # [ 0.443671] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000684test # [ 0.443694] Intel/Sharp Extended Query Table at 0x0031685test # [ 0.453461] Using buffer write method686test # [ 0.453542] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]687test # [ 0.456197] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688test # [ 0.456224] Intel/Sharp Extended Query Table at 0x0031689test # [ 0.459982] Using buffer write method690test # [ 0.460014] Concatenating MTD devices:691test # [ 0.460017] (0): "0.flash"692test # [ 0.460020] (1): "0.flash"693test # [ 0.460022] into device "0.flash"694second # [ 0.664440] Freeing initrd memory: 25484K695second # [ 0.671374] tun: Universal TUN/TAP device driver, 1.6696test # [ 0.663556] Freeing initrd memory: 25484K697second # [ 0.674138] thunder_xcv, ver 1.0698second # [ 0.674169] thunder_bgx, ver 1.0699second # [ 0.674181] nicpf, ver 1.0700second # [ 0.674496] e1000: Intel(R) PRO/1000 Network Driver701second # [ 0.674505] e1000: Copyright (c) 1999-2006 Intel Corporation.702second # [ 0.674520] e1000e: Intel(R) PRO/1000 Network Driver703second # [ 0.674527] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.704second # [ 0.674540] igb: Intel(R) Gigabit Ethernet Network Driver705second # [ 0.674544] igb: Copyright (c) 2007-2014 Intel Corporation.706second # [ 0.674554] igbvf: Intel(R) Gigabit Virtual Function Network Driver707second # [ 0.674558] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.708second # [ 0.674619] sky2: driver version 1.30709second # [ 0.675394] usbcore: registered new interface driver usb-storage710second # [ 0.675443] usbcore: registered new interface driver usbserial_generic711test # [ 0.670408] tun: Universal TUN/TAP device driver, 1.6712second # [ 0.675451] usbserial: USB Serial support registered for generic713second # [ 0.675745] hv_vmbus: registering driver hyperv_keyboard714second # [ 0.676791] ehci-pci 0000:00:0a.0: EHCI Host Controller715second # [ 0.676831] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1716second # [ 0.677039] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000717second # [ 0.685136] rtc-pl031 9010000.pl031: registered as rtc0718second # [ 0.685178] rtc-pl031 9010000.pl031: setting system clock to 2026-09-22T05:19:06 UTC (1790054346)719second # [ 0.685383] i2c_dev: i2c /dev entries driver720test # [ 0.673059] thunder_xcv, ver 1.0721test # [ 0.673115] thunder_bgx, ver 1.0722test # [ 0.673127] nicpf, ver 1.0723test # [ 0.673421] e1000: Intel(R) PRO/1000 Network Driver724test # [ 0.673429] e1000: Copyright (c) 1999-2006 Intel Corporation.725test # [ 0.673442] e1000e: Intel(R) PRO/1000 Network Driver726test # [ 0.673449] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.727test # [ 0.673462] igb: Intel(R) Gigabit Ethernet Network Driver728test # [ 0.673465] igb: Copyright (c) 2007-2014 Intel Corporation.729second # [ 0.688328] sdhci: Secure Digital Host Controller Interface driver730test # [ 0.673476] igbvf: Intel(R) Gigabit Virtual Function Network Driver731second # [ 0.688340] sdhci: Copyright(c) Pierre Ossman732test # [ 0.673480] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.733second # [ 0.688473] Synopsys Designware Multimedia Card Interface Driver734test # [ 0.673543] sky2: driver version 1.30735second # [ 0.688637] sdhci-pltfm: SDHCI platform and OF driver helper736test # [ 0.674393] usbcore: registered new interface driver usb-storage737second # [ 0.689480] hid: raw HID events driver (C) Jiri Kosina738test # [ 0.674441] usbcore: registered new interface driver usbserial_generic739second # [ 0.689624] usbcore: registered new interface driver usbhid740test # [ 0.674449] usbserial: USB Serial support registered for generic741second # [ 0.689629] usbhid: USB HID core driver742test # [ 0.674735] hv_vmbus: registering driver hyperv_keyboard743second # [ 0.693087] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00744second # [ 0.693905] hub 1-0:1.0: USB hub found745second # [ 0.694238] hub 1-0:1.0: 6 ports detected746test # [ 0.681978] ehci-pci 0000:00:0a.0: EHCI Host Controller747test # [ 0.682024] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1748test # [ 0.682264] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000749second # [ 0.694893] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available750test # [ 0.683917] rtc-pl031 9010000.pl031: registered as rtc0751second # [ 0.695671] drop_monitor: Initializing network drop monitor service752test # [ 0.683959] rtc-pl031 9010000.pl031: setting system clock to 2026-09-22T05:19:06 UTC (1790054346)753second # [ 0.695798] NET: Registered PF_INET6 protocol family754test # [ 0.684160] i2c_dev: i2c /dev entries driver755second # [ 0.698143] Segment Routing with IPv6756second # [ 0.698164] In-situ OAM (IOAM) with IPv6757second # [ 0.698209] NET: Registered PF_PACKET protocol family758second # [ 0.699270] 9pnet: Installing 9P2000 support759test # [ 0.687172] sdhci: Secure Digital Host Controller Interface driver760test # [ 0.687189] sdhci: Copyright(c) Pierre Ossman761test # [ 0.687330] Synopsys Designware Multimedia Card Interface Driver762test # [ 0.687518] sdhci-pltfm: SDHCI platform and OF driver helper763test # [ 0.688424] hid: raw HID events driver (C) Jiri Kosina764test # [ 0.690672] usbcore: registered new interface driver usbhid765test # [ 0.690681] usbhid: USB HID core driver766second # [ 0.701206] Key type dns_resolver registered767test # [ 0.692063] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available768test # [ 0.693164] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00769test # [ 0.693923] hub 1-0:1.0: USB hub found770test # [ 0.694250] hub 1-0:1.0: 6 ports detected771test # [ 0.695309] drop_monitor: Initializing network drop monitor service772test # [ 0.695440] NET: Registered PF_INET6 protocol family773second # [ 0.704389] registered taskstats version 1774second # [ 0.704547] Loading compiled-in X.509 certificates775test # [ 0.697105] Segment Routing with IPv6776test # [ 0.697127] In-situ OAM (IOAM) with IPv6777test # [ 0.697171] NET: Registered PF_PACKET protocol family778second # [ 0.709899] Demotion targets for Node 0: null779second # [ 0.709982] Key type .fscrypt registered780second # [ 0.709987] Key type fscrypt-provisioning registered781second # [ 0.710091] ima: No TPM chip found, activating TPM-bypass!782second # [ 0.710102] ima: Allocated hash algorithm: sha1783second # [ 0.710152] ima: No architecture policies found784test # [ 0.698231] 9pnet: Installing 9P2000 support785test # [ 0.699808] Key type dns_resolver registered786second # [ 0.713189] input: gpio-keys as /devices/platform/gpio-keys/input/input0787test # [ 0.703358] registered taskstats version 1788test # [ 0.703529] Loading compiled-in X.509 certificates789test # [ 0.708923] Demotion targets for Node 0: null790test # [ 0.709008] Key type .fscrypt registered791test # [ 0.709013] Key type fscrypt-provisioning registered792test # [ 0.709114] ima: No TPM chip found, activating TPM-bypass!793test # [ 0.709128] ima: Allocated hash algorithm: sha1794test # [ 0.709190] ima: No architecture policies found795test # [ 0.711868] input: gpio-keys as /devices/platform/gpio-keys/input/input0796second # [ 0.723278] clk: Disabling unused clocks797second # [ 0.723299] PM: genpd: Disabling unused power domains798second # [ 0.726457] Freeing unused kernel memory: 4736K799second # [ 0.726550] Run /init as init process800test # [ 0.722386] clk: Disabling unused clocks801test # [ 0.722410] PM: genpd: Disabling unused power domains802test # [ 0.725662] Freeing unused kernel memory: 4736K803test # [ 0.725754] Run /init as init process804second # [ 0.747025] systemd[1]: Successfully made /usr/ read-only.805test # [ 0.746910] systemd[1]: Successfully made /usr/ read-only.806second # [ 0.940360] usb 1-1: new high-speed USB device number 2 using ehci-pci807test # [ 0.940488] usb 1-1: new high-speed USB device number 2 using ehci-pci808second # [ 1.081843] systemd[1]: systemd 260.4 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)809second # [ 1.089403] systemd[1]: Detected virtualization qemu.810second # [ 1.091008] systemd[1]: Detected architecture arm64.811second # [ 1.092473] systemd[1]: Running in initrd.812second # [ 1.093593] systemd[1]: Initializing machine ID from random generator.813second # [ 1.093894] systemd[1]: Hostname set to <second>.814test # [ 1.081555] systemd[1]: systemd 260.4 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)815test # [ 1.088731] systemd[1]: Detected virtualization qemu.816test # [ 1.090310] systemd[1]: Detected architecture arm64.817test # [ 1.091683] systemd[1]: Running in initrd.818test # [ 1.094179] systemd[1]: Initializing machine ID from random generator.819test # [ 1.096402] systemd[1]: Hostname set to <test>.820second # [ 1.106724] 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/input1821test # [ 1.105063] 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/input1822second # [ 1.192542] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0823test # [ 1.192668] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0824second # [ 1.211064] systemd[1]: Queued start job for default target Initrd Default Target.825second # [ 1.222051] systemd[1]: Created slice Slice /system/modprobe.826second # [ 1.222906] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.827second # [ 1.223681] systemd[1]: Expecting device /dev/disk/by-label/nixos...828second # [ 1.224354] systemd[1]: Reached target Path Units.829second # [ 1.224887] systemd[1]: Reached target Slice Units.830second # [ 1.225393] systemd[1]: Reached target Swaps.831second # [ 1.225848] systemd[1]: Reached target Timer Units.832second # [ 1.226597] systemd[1]: Listening on D-Bus System Message Bus Socket.833second # [ 1.227521] systemd[1]: Listening on Journal Socket (/dev/log).834test # [ 1.215733] systemd[1]: Queued start job for default target Initrd Default Target.835second # [ 1.228361] systemd[1]: Listening on Journal Sockets.836second # [ 1.228999] systemd[1]: Listening on udev Control Socket.837second # [ 1.229690] systemd[1]: Listening on udev Kernel Socket.838second # [ 1.230259] systemd[1]: Reached target Socket Units.839second # [ 1.232292] systemd[1]: Starting Create List of Static Device Nodes...840test # [ 1.226108] systemd[1]: Created slice Slice /system/modprobe.841test # [ 1.227924] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.842second # [ 1.240538] systemd[1]: Starting Load Kernel Module 9pnet_virtio...843test # [ 1.229263] systemd[1]: Expecting device /dev/disk/by-label/nixos...844second # [ 1.241369] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs845test # [ 1.230229] systemd[1]: Reached target Path Units.846test # [ 1.230968] systemd[1]: Reached target Slice Units.847test # [ 1.231799] systemd[1]: Reached target Swaps.848test # [ 1.232528] systemd[1]: Reached target Timer Units.849test # [ 1.233656] systemd[1]: Listening on D-Bus System Message Bus Socket.850test # [ 1.234802] systemd[1]: Listening on Journal Socket (/dev/log).851second # [ 1.248537] systemd[1]: Mounting Kernel Configuration File System...852test # [ 1.236062] systemd[1]: Listening on Journal Sockets.853test # [ 1.237134] systemd[1]: Listening on udev Control Socket.854test # [ 1.238125] systemd[1]: Listening on udev Kernel Socket.855test # [ 1.238979] systemd[1]: Reached target Socket Units.856test # [ 1.242021] systemd[1]: Starting Create List of Static Device Nodes...857second # [ 1.256721] systemd[1]: Starting Journal Service...858test # [ 1.252713] systemd[1]: Starting Load Kernel Module 9pnet_virtio...859test # [ 1.253820] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs860second # [ 1.273938] systemd[1]: Starting Load Kernel Modules...861second # [ 1.274594] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki862test # [ 1.260544] systemd[1]: Mounting Kernel Configuration File System...863test # [ 1.273146] systemd[1]: Starting Journal Service...864second # [ 1.288743] systemd[1]: Starting Coldplug All udev Devices...865second # [ 1.290705] systemd[1]: Finished Create List of Static Device Nodes.866second # [ 1.292624] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.867test # [ 1.284266] systemd[1]: Starting Load Kernel Modules...868test # [ 1.284351] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki869second # [ 1.300700] systemd[1]: Finished Load Kernel Module 9pnet_virtio.870second # [ 1.301232] systemd[1]: Mounted Kernel Configuration File System.871second # [ 1.305156] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...872test # [ 1.300805] systemd[1]: Starting Coldplug All udev Devices...873second # [ 1.312662] usb 1-2: new high-speed USB device number 3 using ehci-pci874test # [ 1.307467] systemd[1]: Finished Create List of Static Device Nodes.875test # [ 1.309366] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.876test # [ 1.312661] usb 1-2: new high-speed USB device number 3 using ehci-pci877test # [ 1.314926] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878test # [ 1.336684] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...879test # [ 1.337403] systemd[1]: Mounted Kernel Configuration File System.880second # [ 1.348915] systemd-journald[73]: Collecting audit messages is disabled.881second # [ 1.361779] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.882second # [ 1.365086] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883second # [ 1.372807] systemd[1]: Starting Create Static Device Nodes in /dev...884second # [ 1.380510] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev885second # [ 1.386080] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0886second # [ 1.386349] [drm] features: -virgl +edid -resource_blob -host_visible887second # [ 1.386356] [drm] features: -context_init888second # [ 1.386923] [drm] number of scanouts: 1889second # [ 1.386944] [drm] number of cap sets: 0890test # [ 1.393432] systemd-journald[73]: Collecting audit messages is disabled.891second # [ 1.404741] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic892second # [ 1.404768] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0893test # [ 1.401071] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.894test # [ 1.403083] systemd[1]: Starting Create Static Device Nodes in /dev...895test # [ 1.411574] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.896test # [ 1.413075] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev897test # [ 1.418208] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0898test # [ 1.418433] [drm] features: -virgl +edid -resource_blob -host_visible899test # [ 1.418439] [drm] features: -context_init900test # [ 1.418923] [drm] number of scanouts: 1901test # [ 1.418939] [drm] number of cap sets: 0902second # [ 1.429211] Console: switching to colour frame buffer device 160x50903second # [ 1.435468] systemd[1]: Finished Create Static Device Nodes in /dev.904second # [ 1.435693] systemd[1]: Reached target Preparation for Local File Systems.905second # [ 1.435724] systemd[1]: Reached target Local File Systems.906second # [ 1.438761] systemd[1]: Starting Rule-based Manager for Device Events and Files...907test # [ 1.432909] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic908test # [ 1.432932] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0909second # [ 1.452950] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device910second # [ 1.461970] 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/input2911second # [ 1.464123] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0912second # [ 1.466165] systemd[1]: Started Journal Service.913second # [ 1.459349] systemd-modules-load[74]: Inserted module 'dm_mod'914second # [ 1.461563] systemd-modules-load[74]: Module 'virtio_balloon' is built in915second # [ 1.465300] systemd-modules-load[74]: Module 'virtio_console' is built in916second # [ 1.468498] systemd-modules-load[74]: Inserted module 'virtio_gpu'917test # [ 1.464064] Console: switching to colour frame buffer device 160x50918second # [ 1.469264] systemd-modules-load[74]: Module 'virtio_rng' is built in919test # [ 1.466313] systemd[1]: Finished Create Static Device Nodes in /dev.920test # [ 1.466508] systemd[1]: Reached target Preparation for Local File Systems.921test # [ 1.466535] systemd[1]: Reached target Local File Systems.922second # [ 1.475938] systemd[1]: Finished Load Kernel Modules.923test # [ 1.472862] systemd[1]: Starting Rule-based Manager for Device Events and Files...924test # [ 1.478284] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device925test # [ 1.481906] 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/input2926test # [ 1.482119] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0927second # [ 1.489131] systemd[1]: Starting Apply Kernel Variables...928test # [ 1.496933] systemd[1]: Finished Load Kernel Modules.929test # [ 1.492897] systemd-modules-load[74]: Inserted module 'dm_mod'930test # [ 1.500376] systemd-modules-load[74]: Module 'virtio_balloon' is built in931test # [ 1.501204] systemd-modules-load[74]: Module 'virtio_console' is built in932test # [ 1.501958] systemd-modules-load[74]: Inserted module 'virtio_gpu'933test # [ 1.502614] systemd-modules-load[74]: Module 'virtio_rng' is built in934test # [ 1.512115] systemd[1]: Starting Apply Kernel Variables...935second # [ 1.517396] systemd[1]: Starting Create System Files and Directories...936test # [ 1.512338] systemd[1]: Started Journal Service.937second # [ 1.521514] systemd-udevd[80]: Using default interface naming scheme 'v260'.938second # [ 1.540624] systemd[1]: Finished Apply Kernel Variables.939test # [ 1.537079] systemd[1]: Starting Create System Files and Directories...940second # [ 1.552284] systemd[1]: Finished Create System Files and Directories.941second # [ 1.560570] systemd[1]: Started Rule-based Manager for Device Events and Files.942test # [ 1.557275] systemd[1]: Finished Apply Kernel Variables.943test # [ 1.572484] systemd-udevd[80]: Using default interface naming scheme 'v260'.944test # [ 1.574116] systemd[1]: Finished Create System Files and Directories.945test # [ 1.594699] systemd[1]: Started Rule-based Manager for Device Events and Files.946second # [ 1.623453] systemd[1]: Starting Virtual Console Setup...947test # [ 1.660568] systemd[1]: Starting Virtual Console Setup...948second # [ 1.678026] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.949second # [ 1.679113] systemd[1]: Finished Virtual Console Setup.950test # [ 1.712556] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.715135] systemd[1]: Finished Virtual Console Setup.952second # [ 1.945463] systemd[1]: Finished Coldplug All udev Devices.953second # [ 1.946041] systemd[1]: Reached target System Initialization.954second # [ 1.946530] systemd[1]: Reached target Basic System.955test # [ 1.964922] systemd[1]: Finished Coldplug All udev Devices.956test # [ 1.965529] systemd[1]: Reached target System Initialization.957test # [ 1.966052] systemd[1]: Reached target Basic System.958second # [ 2.051577] (udev-worker)[96]: Network interface NamePolicy= disabled on kernel command line.959second # [ 2.073069] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.960test # [ 2.083745] (udev-worker)[97]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.098224] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.128743] systemd[1]: Found device /dev/disk/by-label/nixos.963second # [ 2.131854] systemd[1]: Reached target Initrd Root Device.964second # [ 2.136071] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...965test # [ 2.144814] systemd[1]: Found device /dev/disk/by-label/nixos.966test # [ 2.149057] systemd[1]: Reached target Initrd Root Device.967test # [ 2.152173] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...968second # [ 2.185242] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks969second # [ 2.189368] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.970test # [ 2.205428] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks971test # [ 2.209973] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972second # [ 2.283408] systemd[1]: Mounting /sysroot...973test # [ 2.296277] systemd[1]: Mounting /sysroot...974second # [ 2.329168] EXT4-fs (vda): mounted filesystem bca3f378-901c-4a9f-b66c-920b593fb3e4 r/w with ordered data mode. Quota mode: none.975second # [ 2.326160] systemd[1]: Mounted /sysroot.976second # [ 2.327957] systemd[1]: Reached target Initrd Root File System.977second # [ 2.331365] systemd[1]: Starting Mountpoints Configured in the Real Root...978test # [ 2.336849] EXT4-fs (vda): mounted filesystem 93c9ddb1-2713-48fd-8940-9d5b86a60655 r/w with ordered data mode. Quota mode: none.979test # [ 2.332809] systemd[1]: Mounted /sysroot.980test # [ 2.333835] systemd[1]: Reached target Initrd Root File System.981test # [ 2.337845] systemd[1]: Starting Mountpoints Configured in the Real Root...982second # [ 2.353722] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.983second # [ 2.358076] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...984second # [ 2.360443] systemd[1]: Reloading...985test # [ 2.359575] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.986test # [ 2.369642] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...987test # [ 2.371645] systemd[1]: Reloading...988second # [ 2.545911] systemd[1]: Reloading finished in 184 ms.989second # [ 2.560150] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...990second # [ 2.563011] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...991second # [ 2.568125] systemd[1]: Starting Load Kernel Module 9pnet_virtio...992second # [ 2.572628] systemd[1]: initrd-parse-etc.service: Deactivated successfully.993second # [ 2.573885] systemd[1]: Finished Mountpoints Configured in the Real Root.994second # [ 2.574639] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.995second # [ 2.592371] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.996second # [ 2.593070] systemd[1]: Finished Load Kernel Module 9pnet_virtio.997test # [ 2.584306] systemd[1]: Reloading finished in 214 ms.998test # [ 2.599480] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...999test # [ 2.602724] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1000test # [ 2.605145] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1001test # [ 2.612594] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1002test # [ 2.613866] systemd[1]: Finished Mountpoints Configured in the Real Root.1003test # [ 2.614963] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1004test # [ 2.632451] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1005test # [ 2.633522] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1006second # [ 3.001359] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1007second # [ 3.004372] (udev-worker)[99]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/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 directory1008second # [ 3.018621] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1009second # [ 3.019407] systemd[1]: Stopped Virtual Console Setup.1010second # [ 3.021635] systemd[1]: Stopping Virtual Console Setup...1011second # [ 3.027086] systemd[1]: Starting Virtual Console Setup...1012test # [ 3.036629] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/lib/udev/mtd_probe": No such file or directory1013second # [ 3.051045] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1014second # [ 3.053632] systemd[1]: Finished Virtual Console Setup.1015test # [ 3.040183] (udev-worker)[97]: mtd0ro: /nix/store/vbj8zd206qm6g50xygfw68fqzf71fmfa-systemd-260.4/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 directory1016test # [ 3.054621] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1017test # [ 3.055613] systemd[1]: Stopped Virtual Console Setup.1018test # [ 3.057974] systemd[1]: Stopping Virtual Console Setup...1019test # [ 3.058463] systemd[1]: Starting Virtual Console Setup...1020test # [ 3.082988] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1021test # [ 3.085862] systemd[1]: Finished Virtual Console Setup.1022second # [ 3.285019] systemd[1]: Mounting /sysroot/nix/.ro-store...1023second # [ 3.296306] systemd[1]: Mounting /sysroot/nix/.rw-store...1024second # [ 3.299442] systemd[1]: Mounting /sysroot/run...1025test # [ 3.297285] systemd[1]: Mounting /sysroot/nix/.ro-store...1026second # [ 3.311964] systemd[1]: Mounting /sysroot/tmp/shared...1027test # [ 3.305216] systemd[1]: Mounting /sysroot/nix/.rw-store...1028test # [ 3.313362] systemd[1]: Mounting /sysroot/run...1029second # [ 3.328310] systemd[1]: Mounting /sysroot/tmp/xchg...1030test # [ 3.320233] systemd[1]: Mounting /sysroot/tmp/shared...1031test # [ 3.332289] systemd[1]: Mounting /sysroot/tmp/xchg...1032test # [ 3.343748] systemd[1]: Mounted /sysroot/nix/.rw-store.1033second # [ 3.358456] systemd[1]: Mounted /sysroot/nix/.rw-store.1034second # [ 3.363836] systemd[1]: Mounted /sysroot/nix/.ro-store.1035test # [ 3.356628] systemd[1]: Mounted /sysroot/nix/.ro-store.1036test # [ 3.358309] systemd[1]: Mounted /sysroot/run.1037test # [ 3.362321] systemd[1]: Mounted /sysroot/tmp/shared.1038second # [ 3.377376] systemd[1]: Mounted /sysroot/run.1039second # [ 3.380606] systemd[1]: Mounted /sysroot/tmp/shared.1040test # [ 3.368737] systemd[1]: Starting rw-sysroot-nix-store.service...1041test # [ 3.374670] systemd[1]: Mounted /sysroot/tmp/xchg.1042second # [ 3.389260] systemd[1]: Starting rw-sysroot-nix-store.service...1043second # [ 3.392837] systemd[1]: Mounted /sysroot/tmp/xchg.1044test # [ 3.390224] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1045test # [ 3.391555] systemd[1]: Finished rw-sysroot-nix-store.service.1046second # [ 3.411406] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1047second # [ 3.413399] systemd[1]: Finished rw-sysroot-nix-store.service.1048second # [ 4.283639] systemd[1]: Mounting /sysroot/nix/store...1049test # [ 4.296957] systemd[1]: Mounting /sysroot/nix/store...1050second # [ 4.315643] systemd[1]: Mounted /sysroot/nix/store.1051second # [ 4.317150] systemd[1]: Reached target Initrd File Systems.1052second # [ 4.319451] systemd[1]: Starting Find NixOS closure...1053second # [ 4.328313] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1054second # [ 4.353828] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1055test # [ 4.341925] systemd[1]: Mounted /sysroot/nix/store.1056test # [ 4.344677] systemd[1]: Reached target Initrd File Systems.1057second # [ 4.356194] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1058test # [ 4.348378] systemd[1]: Starting Find NixOS closure...1059second # [ 4.369552] systemd[1]: Finished Find NixOS closure.1060second # [ 4.371749] systemd[1]: Reached target Initrd Default Target.1061test # [ 4.360367] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1062second # [ 4.376501] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1063second # [ 4.395346] systemd[1]: Stopped target Initrd Default Target.1064second # [ 4.396774] systemd[1]: Stopped target Basic System.1065second # [ 4.399763] systemd[1]: Stopped target Initrd Root Device.1066second # [ 4.400576] systemd[1]: Stopped target Path Units.1067second # [ 4.401043] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1068second # [ 4.401708] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1069second # [ 4.403013] systemd[1]: Stopped target Slice Units.1070second # [ 4.404094] systemd[1]: Stopped target Socket Units.1071second # [ 4.405213] systemd[1]: Stopped target System Initialization.1072second # [ 4.408131] systemd[1]: Stopped target Swaps.1073second # [ 4.408592] systemd[1]: Stopped target Timer Units.1074second # [ 4.409005] systemd[1]: dbus.socket: Deactivated successfully.1075second # [ 4.409492] systemd[1]: Closed D-Bus System Message Bus Socket.1076test # [ 4.396512] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1077second # [ 4.410752] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1078second # [ 4.412377] systemd[1]: Stopped Find NixOS closure.1079second # [ 4.413540] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1080test # [ 4.400319] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1081second # [ 4.420529] systemd[1]: Starting rw-sysroot-nix-store.service...1082second # [ 4.421157] systemd[1]: systemd-sysctl.service: Deactivated successfully.1083second # [ 4.421754] systemd[1]: Stopped Apply Kernel Variables.1084second # [ 4.422223] systemd[1]: systemd-modules-load.service: Deactivated successfully.1085second # [ 4.422844] systemd[1]: Stopped Load Kernel Modules.1086second # [ 4.423307] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1087test # [ 4.414807] systemd[1]: Finished Find NixOS closure.1088test # [ 4.416091] systemd[1]: Reached target Initrd Default Target.1089second # [ 4.423988] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1090second # [ 4.428831] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1091second # [ 4.429791] systemd[1]: Stopped Create System Files and Directories.1092test # [ 4.417875] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1093second # [ 4.432373] systemd[1]: Stopped target Local File Systems.1094second # [ 4.433979] systemd[1]: Stopped target Preparation for Local File Systems.1095second # [ 4.435657] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1096second # [ 4.437900] systemd[1]: Stopped Coldplug All udev Devices.1097second # [ 4.438475] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1098second # [ 4.439617] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1099second # [ 4.441351] systemd[1]: Stopped Virtual Console Setup.1100second # [ 4.441854] systemd[1]: systemd-udevd.service: Deactivated successfully.1101test # [ 4.441609] systemd[1]: Stopped target Initrd Default Target.1102second # [ 4.454363] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1103test # [ 4.442895] systemd[1]: Stopped target Basic System.1104second # [ 4.455074] systemd[1]: systemd-udevd.service: Consumed 1.002s CPU time over 3.010s wall clock time, 21.6M memory peak.1105second # [ 4.455969] systemd[1]: initrd-cleanup.service: Deactivated successfully.1106test # [ 4.444227] systemd[1]: Stopped target Initrd Root Device.1107test # [ 4.446162] systemd[1]: Stopped target Path Units.1108second # [ 4.458822] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1109test # [ 4.446668] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1110second # [ 4.459906] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1111test # [ 4.448164] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1112second # [ 4.462921] systemd[1]: Finished rw-sysroot-nix-store.service.1113second # [ 4.463495] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1114test # [ 4.452134] systemd[1]: Stopped target Slice Units.1115test # [ 4.452610] systemd[1]: Stopped target Socket Units.1116second # [ 4.464765] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1117test # [ 4.453033] systemd[1]: Stopped target System Initialization.1118test # [ 4.453529] systemd[1]: Stopped target Swaps.1119test # [ 4.453927] systemd[1]: Stopped target Timer Units.1120test # [ 4.454365] systemd[1]: dbus.socket: Deactivated successfully.1121second # [ 4.466799] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1122test # [ 4.454895] systemd[1]: Closed D-Bus System Message Bus Socket.1123second # [ 4.467493] systemd[1]: Closed udev Control Socket.1124test # [ 4.455417] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1125second # [ 4.468727] systemd[1]: Starting Cleanup udev Database...1126test # [ 4.459606] systemd[1]: Stopped Find NixOS closure.1127second # [ 4.472147] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1128test # [ 4.460461] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1129second # [ 4.472832] systemd[1]: Stopped Create Static Device Nodes in /dev.1130second # [ 4.473365] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1131second # [ 4.474042] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1132second # [ 4.474602] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1133second # [ 4.475412] systemd[1]: Stopped Create List of Static Device Nodes.1134test # [ 4.464965] systemd[1]: Starting rw-sysroot-nix-store.service...1135test # [ 4.465511] systemd[1]: systemd-sysctl.service: Deactivated successfully.1136test # [ 4.467540] systemd[1]: Stopped Apply Kernel Variables.1137test # [ 4.468305] systemd[1]: systemd-modules-load.service: Deactivated successfully.1138test # [ 4.470485] systemd[1]: Stopped Load Kernel Modules.1139test # [ 4.471450] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1140test # [ 4.473258] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1141test # [ 4.475089] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1142test # [ 4.476099] systemd[1]: Stopped Create System Files and Directories.1143test # [ 4.476822] systemd[1]: Stopped target Local File Systems.1144test # [ 4.477383] systemd[1]: Stopped target Preparation for Local File Systems.1145test # [ 4.477966] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1146test # [ 4.478556] systemd[1]: Stopped Coldplug All udev Devices.1147test # [ 4.479029] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1148test # [ 4.479638] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1149second # [ 4.497284] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1150second # [ 4.498326] systemd[1]: Finished Cleanup udev Database.1151second # [ 4.500221] systemd[1]: Reached target Switch Root.1152second # [ 4.501984] systemd[1]: Starting NixOS Activation...1153test # [ 4.491369] systemd[1]: Stopped Virtual Console Setup.1154test # [ 4.491938] systemd[1]: initrd-cleanup.service: Deactivated successfully.1155test # [ 4.493129] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1156test # [ 4.493815] systemd[1]: systemd-udevd.service: Deactivated successfully.1157test # [ 4.494416] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1158test # [ 4.496227] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1159test # [ 4.497451] systemd[1]: Closed udev Control Socket.1160test # [ 4.498400] systemd[1]: Starting Cleanup udev Database...1161test # [ 4.499486] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1162test # [ 4.501056] systemd[1]: Stopped Create Static Device Nodes in /dev.1163test # [ 4.501769] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1164test # [ 4.502471] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1165test # [ 4.503076] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1166test # [ 4.503669] systemd[1]: Stopped Create List of Static Device Nodes.1167test # [ 4.504300] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1168test # [ 4.504904] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1169test # [ 4.505412] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1170test # [ 4.506004] systemd[1]: Finished rw-sysroot-nix-store.service.1171test # [ 4.524837] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1172test # [ 4.526745] systemd[1]: Finished Cleanup udev Database.1173test # [ 4.527266] systemd[1]: Reached target Switch Root.1174test # [ 4.527957] systemd[1]: Starting NixOS Activation...1175second # [ 4.703731] initrd-nixos-activation-start[178]: booting system configuration /nix/store/f8z2991bmvsmmq1q37nk6ir6pmwgm714-nixos-system-second-test1176test # [ 4.740863] initrd-nixos-activation-start[179]: booting system configuration /nix/store/4xlf8h0kwvz1rdm88ndanzdwijzj0mfy-nixos-system-test-test1177second # [ 4.786240] initrd-nixos-activation-start[178]: running activation script...1178test # [ 4.805126] initrd-nixos-activation-start[179]: running activation script...1179second # [ 5.225393] initrd-nixos-activation-start[201]: setting up /etc...1180test # [ 5.257383] initrd-nixos-activation-start[202]: setting up /etc...1181second # [ 5.473502] initrd-nixos-activation-start[201]: setting up age secrets...1182test # [ 5.489556] initrd-nixos-activation-start[202]: setting up age secrets...1183second # [ 5.686200] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1184second # [ 5.688112] systemd[1]: Finished NixOS Activation.1185second # [ 5.688956] systemd[1]: Starting Switch Root...1186second # [ 5.709419] systemd[1]: Switching root.1187test # [ 5.733508] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1188test # [ 5.735264] systemd[1]: Finished NixOS Activation.1189test # [ 5.736151] systemd[1]: Starting Switch Root...1190second # [ 5.765556] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1191test # [ 5.753263] systemd[1]: Switching root.1192test # [ 5.809881] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1193second # [ 6.347102] systemd[1]: systemd 260.4 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 # [ 6.354335] systemd[1]: Detected virtualization qemu.1195second # [ 6.356082] systemd[1]: Detected architecture arm64.1196second # [ 6.358326] systemd[1]: Detected first boot.1197second # [ 6.364469] systemd[1]: Initializing machine ID from random generator.1198test # [ 6.387908] systemd[1]: systemd 260.4 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 # [ 6.395122] systemd[1]: Detected virtualization qemu.1200test # [ 6.396952] systemd[1]: Detected architecture arm64.1201test # [ 6.398730] systemd[1]: Detected first boot.1202test # [ 6.405961] systemd[1]: Initializing machine ID from random generator.1203second # [ 6.652500] systemd[1]: bpf-restrict-fs: LSM BPF program attached1204test # [ 6.700634] systemd[1]: bpf-restrict-fs: LSM BPF program attached1205second # [ 6.852657] systemd[1]: Applying preset policy.1206test # [ 6.896875] systemd[1]: Applying preset policy.1207second # [ 7.382246] systemd[1]: Populated /etc with preset unit settings.1208test # [ 7.439845] systemd[1]: Populated /etc with preset unit settings.1209second # [ 7.820475] systemd[1]: initrd-switch-root.service: Deactivated successfully.1210second # [ 7.821360] systemd[1]: Stopped initrd-switch-root.service.1211second # [ 7.823151] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1212second # [ 7.825078] systemd[1]: Created slice Slice /system/getty.1213second # [ 7.826118] systemd[1]: Created slice User and Session Slice.1214second # [ 7.827170] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1215second # [ 7.828553] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1216second # [ 7.829714] systemd[1]: Expecting device /dev/hvc0...1217second # [ 7.830672] systemd[1]: Expecting device /dev/ttyAMA0...1218second # [ 7.832093] systemd[1]: Reached target Local Encrypted Volumes.1219second # [ 7.833591] systemd[1]: Stopped target initrd-fs.target.1220second # [ 7.834944] systemd[1]: Stopped target initrd-root-fs.target.1221second # [ 7.836400] systemd[1]: Stopped target initrd-switch-root.target.1222second # [ 7.837800] systemd[1]: Reached target Virtual Machines and Containers.1223second # [ 7.839235] systemd[1]: Reached target Path Units.1224second # [ 7.840740] systemd[1]: Reached target Remote File Systems.1225second # [ 7.842071] systemd[1]: Reached target Slice Units.1226second # [ 7.843365] systemd[1]: Reached target Swaps.1227second # [ 7.849133] systemd[1]: Listening on Process Core Dump Socket.1228second # [ 7.852778] systemd[1]: Listening on Credential Encryption/Decryption.1229second # [ 7.857389] systemd[1]: Starting Journal Log Access Socket...1230second # [ 7.858992] systemd[1]: Listening on Journal Audit Socket.1231second # [ 7.860100] systemd[1]: Listening on Network Management Metrics Varlink Socket.1232second # [ 7.861771] systemd[1]: Listening on Network Management Netlink Socket.1233second # [ 7.863412] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1234second # [ 7.865155] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1235second # [ 7.866568] systemd[1]: Listening on udev Control Socket.1236second # [ 7.870859] systemd[1]: Mounting Huge Pages File System...1237second # [ 7.874727] systemd[1]: Mounting POSIX Message Queue File System...1238second # [ 7.881376] systemd[1]: Mounting Kernel Debug File System...1239second # [ 7.889225] systemd[1]: Mounting Kernel Trace File System...1240second # [ 7.902916] systemd[1]: Starting Create List of Static Device Nodes...1241second # [ 7.908065] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1242second # [ 7.910216] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1243second # [ 7.918861] systemd[1]: Mounting Kernel Configuration File System...1244second # [ 7.921042] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1245second # [ 7.925059] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1246second # [ 7.937892] systemd[1]: Starting Load Kernel Module fuse...1247second # [ 7.940711] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671248second # [ 7.994599] systemd[1]: Starting Journal Service...1249test # [ 7.985347] systemd[1]: initrd-switch-root.service: Deactivated successfully.1250test # [ 7.986198] systemd[1]: Stopped initrd-switch-root.service.1251test # [ 7.988080] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1252test # [ 7.990415] systemd[1]: Created slice Slice /system/getty.1253test # [ 7.992275] systemd[1]: Created slice User and Session Slice.1254test # [ 7.993805] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1255test # [ 7.995459] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1256test # [ 7.997069] systemd[1]: Expecting device /dev/hvc0...1257test # [ 7.998382] systemd[1]: Expecting device /dev/ttyAMA0...1258test # [ 7.999729] systemd[1]: Reached target Local Encrypted Volumes.1259test # [ 8.001300] systemd[1]: Stopped target initrd-fs.target.1260test # [ 8.002775] systemd[1]: Stopped target initrd-root-fs.target.1261test # [ 8.004213] systemd[1]: Stopped target initrd-switch-root.target.1262second # [ 8.017024] systemd[1]: Starting Load Kernel Modules...1263test # [ 8.005650] systemd[1]: Reached target Virtual Machines and Containers.1264test # [ 8.007129] systemd[1]: Reached target Path Units.1265test # [ 8.008422] systemd[1]: Reached target Remote File Systems.1266test # [ 8.009830] systemd[1]: Reached target Slice Units.1267test # [ 8.010682] systemd[1]: Reached target Swaps.1268test # [ 8.014940] systemd[1]: Listening on Process Core Dump Socket.1269test # [ 8.018481] systemd[1]: Listening on Credential Encryption/Decryption.1270test # [ 8.022915] systemd[1]: Starting Journal Log Access Socket...1271second # [ 8.033621] fuse: init (API version 7.45)1272test # [ 8.024488] systemd[1]: Listening on Journal Audit Socket.1273test # [ 8.025520] systemd[1]: Listening on Network Management Metrics Varlink Socket.1274second # [ 8.038357] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1275test # [ 8.026689] systemd[1]: Listening on Network Management Netlink Socket.1276test # [ 8.028353] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1277test # [ 8.030233] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1278test # [ 8.032106] systemd[1]: Listening on udev Control Socket.1279test # [ 8.036664] systemd[1]: Mounting Huge Pages File System...1280test # [ 8.040363] systemd[1]: Mounting POSIX Message Queue File System...1281second # [ 8.054926] systemd[1]: Starting Remount Root and Kernel File Systems...1282test # [ 8.044108] systemd[1]: Mounting Kernel Debug File System...1283second # [ 8.056521] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1284test # [ 8.050444] systemd[1]: Mounting Kernel Trace File System...1285test # [ 8.059070] systemd[1]: Starting Create List of Static Device Nodes...1286second # [ 8.072054] systemd[1]: Starting Coldplug All udev Devices...1287second # [ 8.077593] systemd[1]: Listening on Journal Log Access Socket.1288second # [ 8.080619] systemd[1]: Mounted Huge Pages File System.1289test # [ 8.074615] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1290test # [ 8.075693] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1291second # [ 8.088511] systemd[1]: Mounted POSIX Message Queue File System.1292test # [ 8.080683] systemd[1]: Mounting Kernel Configuration File System...1293test # [ 8.081953] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1294test # [ 8.083636] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1295second # [ 8.101379] systemd[1]: Mounted Kernel Debug File System.1296second # [ 8.101947] systemd[1]: Mounted Kernel Trace File System.1297second # [ 8.106233] systemd[1]: Finished Create List of Static Device Nodes.1298test # [ 8.096878] systemd[1]: Starting Load Kernel Module fuse...1299test # [ 8.098469] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671300second # [ 8.116715] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1301second # [ 8.118950] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1302second # [ 8.119571] systemd[1]: Mounted Kernel Configuration File System.1303second # [ 8.120045] systemd[1]: modprobe@fuse.service: Deactivated successfully.1304second # [ 8.129628] systemd[1]: Finished Load Kernel Module fuse.1305second # [ 8.135169] systemd[1]: Mounting FUSE Control File System...1306second # [ 8.140640] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1307second # [ 8.159220] EXT4-fs (vda): re-mounted bca3f378-901c-4a9f-b66c-920b593fb3e4.1308test # [ 8.156047] systemd[1]: Starting Journal Service...1309second # [ 8.173489] systemd-journald[301]: Collecting audit messages is enabled.1310second # [ 8.181707] systemd[1]: Finished Load Kernel Modules.1311second # [ 8.188730] systemd[1]: Finished Remount Root and Kernel File Systems.1312second # [ 8.182869] systemd[1]: Queued start job for default target Multi-User System.1313second # [ 8.183895] systemd[1]: systemd-journald.service: Deactivated successfully.1314test # [ 8.181188] systemd[1]: Starting Load Kernel Modules...1315second # [ 8.189787] systemd-modules-load[302]: Module 'atkbd' is built in1316second # [ 8.200767] systemd[1]: Starting Firewall...1317second # [ 8.201204] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1318second # [ 8.195639] systemd-modules-load[302]: Module 'ctr' is built in1319second # [ 8.204145] systemd-modules-load[302]: Module 'loop' is built in1320second # [ 8.213112] systemd[1]: Starting Load/Save OS Random Seed...1321test # [ 8.204285] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1322second # [ 8.212736] systemd-oomd[303]: No swap; memory pressure usage will be degraded1323test # [ 8.213272] fuse: init (API version 7.45)1324second # [ 8.241945] systemd[1]: Starting Apply Kernel Variables...1325test # [ 8.230493] systemd[1]: Starting Remount Root and Kernel File Systems...1326test # [ 8.233227] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1327second # [ 8.248536] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1328test # [ 8.253523] systemd[1]: Starting Coldplug All udev Devices...1329second # [ 8.266555] systemd[1]: Started Journal Service.1330test # [ 8.261954] systemd[1]: Listening on Journal Log Access Socket.1331second # [ 8.273501] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1332test # [ 8.271350] systemd[1]: Mounted Huge Pages File System.1333test # [ 8.277804] systemd[1]: Mounted POSIX Message Queue File System.1334second # [ 8.288562] systemd[1]: Mounted FUSE Control File System.1335test # [ 8.284636] systemd[1]: Mounted Kernel Debug File System.1336test # [ 8.289910] systemd[1]: Mounted Kernel Trace File System.1337test # [ 8.295352] systemd[1]: Finished Create List of Static Device Nodes.1338test # [ 8.299988] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1339test # [ 8.302356] systemd-journald[323]: Collecting audit messages is enabled.1340test # [ 8.308913] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1341test # [ 8.309494] systemd[1]: Mounted Kernel Configuration File System.1342test # [ 8.309965] systemd[1]: modprobe@fuse.service: Deactivated successfully.1343test # [ 8.310395] systemd[1]: Finished Load Kernel Module fuse.1344test # [ 8.310946] systemd[1]: Finished Load Kernel Modules.1345test # [ 8.309070] systemd[1]: Queued start job for default target Multi-User System.1346second # [ 8.322299] systemd[1]: Starting Flush Journal to Persistent Storage...1347test # [ 8.312742] systemd[1]: systemd-journald.service: Deactivated successfully.1348test # [ 8.324252] systemd[1]: Mounting FUSE Control File System...1349test # [ 8.319822] systemd-modules-load[324]: Module 'atkbd' is built in1350test # [ 8.325928] systemd-modules-load[324]: Module 'ctr' is built in1351test # [ 8.326581] systemd-modules-load[324]: Module 'loop' is built in1352test # [ 8.340906] systemd[1]: Starting Firewall...1353second # [ 8.350822] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1354second # [ 8.354645] systemd[1]: Finished Load/Save OS Random Seed.1355second # [ 8.360958] systemd[1]: Reached target First Boot Complete.1356second # [ 8.365014] systemd[1]: Starting Create Static Device Nodes in /dev...1357test # [ 8.364994] systemd[1]: Starting Apply Kernel Variables...1358test # [ 8.377610] EXT4-fs (vda): re-mounted 93c9ddb1-2713-48fd-8940-9d5b86a60655.1359test # [ 8.381540] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1360second # [ 8.397577] systemd-journald[301]: Received client request to flush runtime journal.1361test # [ 8.390275] systemd[1]: Started Journal Service.1362test # [ 8.387297] systemd-oomd[325]: No swap; memory pressure usage will be degraded1363test # [ 8.406413] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1364test # [ 8.414072] systemd[1]: Finished Remount Root and Kernel File Systems.1365second # [ 8.462616] systemd[1]: Finished Apply Kernel Variables.1366second # [ 8.471060] systemd[1]: Starting Network Name Resolution...1367test # [ 8.460159] systemd[1]: Starting Flush Journal to Persistent Storage...1368test # [ 8.461100] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1369second # [ 8.477246] systemd[1]: Finished Flush Journal to Persistent Storage.1370test # [ 8.466522] systemd[1]: Starting Load/Save OS Random Seed...1371test # [ 8.472415] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1372test # [ 8.476711] systemd[1]: Mounted FUSE Control File System.1373second # [ 8.514289] systemd[1]: Finished Create Static Device Nodes in /dev.1374second # [ 8.515918] systemd[1]: Reached target Preparation for Local File Systems.1375second # [ 8.519267] systemd[1]: Starting Rule-based Manager for Device Events and Files...1376test # [ 8.522623] systemd[1]: Finished Apply Kernel Variables.1377test # [ 8.534000] systemd[1]: Starting Network Name Resolution...1378test # [ 8.569054] systemd-journald[323]: Received client request to flush runtime journal.1379second # [ 8.596698] systemd-resolved[333]: Positive Trust Anchors:1380second # [ 8.597482] systemd-resolved[333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1381second # [ 8.598508] systemd-resolved[333]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161382second # [ 8.599981] 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 test1383test # [ 8.629744] systemd[1]: Finished Load/Save OS Random Seed.1384test # [ 8.636093] systemd[1]: Reached target First Boot Complete.1385second # [ 8.652346] systemd-udevd[340]: Using default interface naming scheme 'v260'.1386test # [ 8.641711] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1387test # [ 8.648315] systemd[1]: Starting Create Static Device Nodes in /dev...1388test # [ 8.648912] systemd[1]: Finished Flush Journal to Persistent Storage.1389test # [ 8.716954] systemd[1]: Finished Create Static Device Nodes in /dev.1390test # [ 8.719341] systemd[1]: Reached target Preparation for Local File Systems.1391test # [ 8.723890] systemd-resolved[354]: Positive Trust Anchors:1392test # [ 8.728542] systemd-resolved[354]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1393test # [ 8.729977] systemd-resolved[354]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161394test # [ 8.732155] systemd-resolved[354]: 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 test1395test # [ 8.763898] systemd[1]: Starting Rule-based Manager for Device Events and Files...1396second # [ 8.819153] systemd[1]: Mounting /run/wrappers...1397second # [ 8.847402] systemd-resolved[333]: Using system hostname 'second'.1398second # [ 8.858047] systemd[1]: Started Network Name Resolution.1399test # [ 8.853721] systemd-udevd[368]: Using default interface naming scheme 'v260'.1400second # [ 8.893552] systemd[1]: Mounted /run/wrappers.1401second # [ 8.897480] systemd[1]: Reached target Local File Systems.1402second # [ 8.904112] systemd[1]: Listening on Boot Loader Control Service Socket.1403second # [ 8.907698] systemd[1]: Starting register-nix-paths.service...1404second # [ 8.911556] systemd[1]: Starting Create SUID/SGID Wrappers...1405second # [ 8.913602] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1406second # [ 8.919109] systemd[1]: Starting Save Transient machine-id to Disk...1407second # [ 8.941026] systemd[1]: Starting Create System Files and Directories...1408second # [ 8.980617] systemd[1]: Started Rule-based Manager for Device Events and Files.1409test # [ 8.969832] systemd-resolved[354]: Using system hostname 'test'.1410test # [ 8.975814] systemd[1]: Started Network Name Resolution.1411test # [ 8.982487] systemd[1]: Mounting /run/wrappers...1412test # [ 9.045314] systemd[1]: Mounted /run/wrappers.1413test # [ 9.046960] systemd[1]: Reached target Local File Systems.1414test # [ 9.052670] systemd[1]: Listening on Boot Loader Control Service Socket.1415test # [ 9.058794] systemd[1]: Starting register-nix-paths.service...1416test # [ 9.067696] systemd[1]: Starting Create SUID/SGID Wrappers...1417test # [ 9.068326] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1418second # [ 9.081018] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1419test # [ 9.071220] systemd[1]: Starting Save Transient machine-id to Disk...1420second # [ 9.088157] systemd[1]: Finished Save Transient machine-id to Disk.1421test # [ 9.107678] systemd[1]: Starting Create System Files and Directories...1422second # [ 9.160656] systemd[1]: Finished Create System Files and Directories.1423test # [ 9.150299] systemd[1]: Started Rule-based Manager for Device Events and Files.1424second # [ 9.175729] systemd[1]: Starting Rebuild Journal Catalog...1425second # [ 9.179020] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1426test # [ 9.227683] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1427test # [ 9.238825] systemd[1]: Finished Save Transient machine-id to Disk.1428test # [ 9.307275] systemd[1]: Finished Create System Files and Directories.1429second # [ 9.320452] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1430test # [ 9.326428] systemd[1]: Starting Rebuild Journal Catalog...1431test # [ 9.329813] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1432second # [ 9.383594] systemd[1]: Finished Rebuild Journal Catalog.1433second # [ 9.397703] systemd[1]: Starting Update is Completed...1434test # [ 9.443750] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1435second # [ 9.456733] systemd[1]: Finished Coldplug All udev Devices.1436second # [ 9.486237] systemd[1]: Finished Update is Completed.1437test # [ 9.526030] systemd[1]: Finished Rebuild Journal Catalog.1438test # [ 9.532686] systemd[1]: Starting Update is Completed...1439second # [ 9.588926] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1440test # [ 9.598943] systemd[1]: Finished Coldplug All udev Devices.1441test # [ 9.621731] systemd[1]: Finished Update is Completed.1442test # [ 9.739851] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1443second # [ 9.854174] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1444second # [ 9.961266] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1445test # [ 10.020290] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1446second # [ 10.035662] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1447second # [ 10.037831] systemd[1]: Finished Create SUID/SGID Wrappers.1448test # [ 10.119387] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1449test # [ 10.174440] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1450test # [ 10.177112] systemd[1]: Finished Create SUID/SGID Wrappers.1451second # [ 10.222256] systemd[1]: Finished Firewall.1452second # [ 10.224707] systemd[1]: Reached target Preparation for Network.1453second # [ 10.226790] (udev-worker)[382]: Network interface NamePolicy= disabled on kernel command line.1454second # [ 10.230607] systemd[1]: Starting Network Management...1455second # [ 10.242039] (udev-worker)[383]: Network interface NamePolicy= disabled on kernel command line.1456second # [ 10.286047] systemd[1]: Finished register-nix-paths.service.1457second # [ 10.286665] systemd[1]: Reached target System Initialization.1458second # [ 10.290857] systemd[1]: Started Discard unused filesystem blocks once a week.1459second # [ 10.292573] systemd[1]: Started Daily Cleanup of Temporary Directories.1460second # [ 10.297109] systemd[1]: Reached target Timer Units.1461second # [ 10.297616] systemd[1]: Listening on D-Bus System Message Bus Socket.1462second # [ 10.299025] systemd[1]: Listening on Nix Daemon Socket.1463second # [ 10.302640] systemd[1]: Listening on Authorization Manager Agent Helper.1464second # [ 10.306623] systemd[1]: Listening on Hostname Service Socket.1465second # [ 10.307267] systemd[1]: Reached target Socket Units.1466second # [ 10.313891] systemd[1]: Starting D-Bus System Message Bus...1467test # [ 10.323407] systemd[1]: Finished Firewall.1468test # [ 10.325541] systemd[1]: Reached target Preparation for Network.1469test # [ 10.330214] systemd[1]: Starting Network Management...1470test # [ 10.375538] systemd[1]: Finished register-nix-paths.service.1471test # [ 10.378813] systemd[1]: Reached target System Initialization.1472test # [ 10.384456] systemd[1]: Started Discard unused filesystem blocks once a week.1473test # [ 10.385216] systemd[1]: Started Daily Cleanup of Temporary Directories.1474test # [ 10.388234] systemd[1]: Reached target Timer Units.1475test # [ 10.388778] systemd[1]: Listening on D-Bus System Message Bus Socket.1476test # [ 10.389326] systemd[1]: Listening on Nix Daemon Socket.1477test # [ 10.396070] systemd[1]: Listening on Authorization Manager Agent Helper.1478test # [ 10.396702] systemd[1]: Listening on Hostname Service Socket.1479test # [ 10.398228] systemd[1]: Reached target Socket Units.1480test # [ 10.400907] systemd[1]: Starting D-Bus System Message Bus...1481test # [ 10.441680] (udev-worker)[415]: Network interface NamePolicy= disabled on kernel command line.1482second # [ 10.478155] cfg80211: Loading compiled-in X.509 certificates for regulatory database1483test # [ 10.461257] (udev-worker)[411]: Network interface NamePolicy= disabled on kernel command line.1484second # [ 10.494345] mousedev: PS/2 mouse device common for all mice1485second # [ 10.507282] dbus-broker-launch[538]: Looking up NSS user entry for 'systemd-timesync'...1486second # [ 10.518396] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1487second # [ 10.518948] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1488second # [ 10.532868] dbus-broker-launch[538]: NSS returned no entry for 'systemd-timesync'1489second # [ 10.533877] dbus-broker-launch[538]: Invalid user-name in /nix/store/vrp11q0q409lcndzx1pryawi6yqrnvdy-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1490second # [ 10.578923] 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.1491second # [ 10.584714] 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.1492test # [ 10.583401] cfg80211: Loading compiled-in X.509 certificates for regulatory database1493second # [ 10.592343] systemd-networkd[534]: lo: Link UP1494second # [ 10.594515] systemd-networkd[534]: lo: Gained carrier1495second # [ 10.599338] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1496second # [ 10.609820] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1497test # [ 10.604421] dbus-broker-launch[558]: Looking up NSS user entry for 'systemd-timesync'...1498second # [ 10.618305] systemd[1]: Started Network Management.1499second # [ 10.622658] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1500test # [ 10.626677] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1501test # [ 10.627181] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1502second # [ 10.630725] dbus-broker-launch[538]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1503test # [ 10.627924] dbus-broker-launch[558]: NSS returned no entry for 'systemd-timesync'1504second # [ 10.640737] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1505second # [ 10.641392] systemd[1]: Started D-Bus System Message Bus.1506second # [ 10.641869] systemd[1]: Reached target Basic System.1507test # [ 10.628993] dbus-broker-launch[558]: Invalid user-name in /nix/store/vrp11q0q409lcndzx1pryawi6yqrnvdy-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1508second # [ 10.642311] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1509second # [ 10.643615] systemd-networkd[534]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1510second # [ 10.654600] systemd-networkd[534]: eth1: Link UP1511second # [ 10.655658] systemd-networkd[534]: eth1: Gained carrier1512second # [ 10.664186] systemd-networkd[534]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1513second # [ 10.665474] systemd[1]: Starting Network Manager Script Dispatcher Service...1514second # [ 10.666104] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1515second # [ 10.666714] systemd[1]: Starting Network Manager...1516second # [ 10.673486] systemd[1]: Started backdoor.service.1517test # [ 10.672391] mousedev: PS/2 mouse device common for all mice1518second # [ 10.693635] dbus-broker-launch[538]: Ready1519test # [ 10.682852] systemd-networkd[555]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1520test # [ 10.689335] systemd-networkd[555]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1521second # [ 10.707671] systemd[1]: Starting Import lastlog data into lastlog2 database...1522test # [ 10.697595] systemd-networkd[555]: lo: Link UP1523test # [ 10.699219] systemd-networkd[555]: lo: Gained carrier1524test # [ 10.704426] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1525test # [ 10.713967] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/1k1jiid5mf18446q3rl0g1gks4yl81qg-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1526second # [ 10.736138] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1527test # [ 10.725027] systemd[1]: Started Network Management.1528test # [ 10.727611] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/sx40vhhwgyvayv82kxrdfsb24z1wvcrg-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1529test # [ 10.736026] dbus-broker-launch[558]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/0qin0mxlbmkcw8jq0dql0shhcfa41crl-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1530second # [ 10.755356] systemd[1]: Starting Post-Boot Actions...1531test # [ 10.744414] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1532test # [ 10.745079] systemd[1]: Started D-Bus System Message Bus.1533test # [ 10.745562] systemd[1]: Reached target Basic System.1534test # [ 10.746003] systemd[1]: Starting Network Manager Script Dispatcher Service...1535test # [ 10.746597] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1536test # [ 10.747869] systemd-networkd[555]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1537test # [ 10.762085] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1538test # [ 10.763507] systemd-networkd[555]: eth1: Link UP1539second # [ 10.776589] systemd[1]: Started Reset console on configuration changes.1540test # [ 10.768630] systemd-networkd[555]: eth1: Gained carrier1541second # [ 10.782803] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1542test # [ 10.770430] systemd-networkd[555]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1543test # [ 10.776991] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1544test # [ 10.777647] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1545test # [ 10.789618] dbus-broker-launch[558]: Ready1546test # [ 10.791812] systemd[1]: Starting Network Manager...1547second # [ 10.829664] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[547]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1548test # [ 10.820472] systemd[1]: Started backdoor.service.1549test # [ 10.840514] systemd[1]: Starting Import lastlog data into lastlog2 database...1550second # connecting to host...1551second # [ 10.861704] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1552second # [ 10.870857] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1553test # [ 10.888185] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1554test # [ 10.917199] 9zw00hrl9ri87shidqszb96sxar6g9fp-wifi-secrets[571]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1555test # [ 10.925082] gihlbcfxrzdmnrhdz3bb45n3f4b0k7kd-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1556test # [ 10.936543] 4bb5snallvknp7ll9gml95rlfxqybb0y-wifi-secrets[570]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1557second # [ 10.952203] systemd[1]: Finished Post-Boot Actions.1558test # [ 10.945728] systemd[1]: Starting Post-Boot Actions...1559test # [ 10.955710] systemd[1]: Started Reset console on configuration changes.1560second # [ 10.979236] systemd[1]: Finished Import lastlog data into lastlog2 database.1561second # [ 10.982104] systemd[1]: Started Name Service Cache Daemon (nsncd).1562second # [ 10.984987] nsncd[552]: Sep 22 05:19:16.806 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1563second # [ 10.986209] systemd[1]: Reached target Host and Network Name Lookups.1564second # [ 10.992122] systemd[1]: Reached target User and Group Name Lookups.1565test # [ 10.984660] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1566second # [ 11.000361] systemd[1]: Starting User Login Management...1567test # [ 11.000297] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1568test # [ 11.019907] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1569test # [ 11.024804] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1570second # [ 11.042776] systemd[1]: Started Network Manager Script Dispatcher Service.1571test # [ 11.030661] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1572test # [ 11.037130] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1573test # [ 11.044926] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1574test # connecting to host...1575test # [ 11.091108] systemd[1]: Finished Post-Boot Actions.1576test: Guest shell says: b'Spawning backdoor root shell...\n'1577test: connected to guest root shell1578test: (connecting took 11.44 seconds)1579test: (finished: waiting for the VM to finish booting, in 11.44 seconds)1580second # [ 11.135143] systemd[1]: Starting Hostname Service...1581second # [ 11.149023] systemd-logind[582]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1582second # [ 11.149806] systemd-logind[582]: Watching system buttons on /dev/input/event0 (gpio-keys)1583second # [ 11.150548] systemd-logind[582]: New seat seat0.1584second # [ 11.159472] systemd[1]: Started User Login Management.1585second # [ 11.167265] systemd[1]: Starting linger-users.service...1586test # [ 11.161334] systemd[1]: Started Network Manager Script Dispatcher Service.1587test # [ 11.167799] systemd[1]: Started Name Service Cache Daemon (nsncd).1588test # [ 11.170212] nsncd[576]: Sep 22 05:19:16.991 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1589test # [ 11.171437] systemd[1]: Finished Import lastlog data into lastlog2 database.1590test # [ 11.178270] systemd[1]: Reached target Host and Network Name Lookups.1591test # [ 11.179989] systemd[1]: Reached target User and Group Name Lookups.1592test # [ 11.183811] systemd[1]: Starting User Login Management...1593second # [ 11.251520] systemd[1]: linger-users.service: Deactivated successfully.1594second # [ 11.252235] systemd[1]: Finished linger-users.service.1595second # [ 11.271832] systemd[1]: Started Hostname Service.1596test # [ 11.322249] systemd-logind[622]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1597test # [ 11.326788] systemd-logind[622]: Watching system buttons on /dev/input/event0 (gpio-keys)1598test # [ 11.327500] systemd-logind[622]: New seat seat0.1599test # [ 11.332745] systemd[1]: Starting Hostname Service...1600test # [ 11.333223] systemd[1]: Started User Login Management.1601test # [ 11.338399] systemd[1]: Starting linger-users.service...1602second # [ 11.367870] systemd-networkd[534]: eth0: Link UP1603second # [ 11.368956] systemd-networkd[534]: eth0: Gained carrier1604second # [ 11.388329] systemd[1]: Started Network Manager.1605second # [ 11.391138] systemd[1]: Reached target Network.1606second # [ 11.395988] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1607second # [ 11.401425] systemd[1]: Starting Permit User Sessions...1608test # [ 11.408599] systemd[1]: linger-users.service: Deactivated successfully.1609test # [ 11.410021] systemd[1]: Finished linger-users.service.1610test # [ 11.456174] systemd[1]: Started Hostname Service.1611second # [ 11.564192] systemd[1]: Finished Permit User Sessions.1612test # [ 11.554944] systemd-networkd[555]: eth0: Link UP1613test # [ 11.555469] systemd-networkd[555]: eth0: Gained carrier1614second # [ 11.581173] systemd[1]: Started Getty on tty1.1615second # [ 11.581935] systemd[1]: Reached target Login Prompts.1616test # [ 11.577066] systemd[1]: Started Network Manager.1617test # [ 11.579614] systemd[1]: Reached target Network.1618second # [ 11.592771] systemd-resolved[333]: eth0: Bus client set default route setting: yes1619test # [ 11.583574] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1620test # [ 11.590799] systemd[1]: Starting Permit User Sessions...1621second # [ 11.602840] systemd-resolved[333]: eth0: Bus client set DNS server list to: 10.0.2.31622second # [ 11.699276] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1623second # [ 11.700413] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1624test # [ 11.750761] systemd[1]: Finished Permit User Sessions.1625test # [ 11.765259] systemd[1]: Started Getty on tty1.1626second # [ 11.779714] systemd[1]: Reached target Multi-User System.1627test # [ 11.770938] systemd[1]: Reached target Login Prompts.1628second # [ 11.784125] systemd[1]: Startup finished in 724ms (kernel) + 5.128s (initrd) + 5.927s (userspace) = 11.780s.1629test # [ 11.808965] systemd-resolved[354]: eth0: Bus client set default route setting: yes1630test # [ 11.813862] systemd-resolved[354]: eth0: Bus client set DNS server list to: 10.0.2.31631test # [ 11.889572] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1632test # [ 11.892358] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1633test # [ 12.028336] systemd[1]: Reached target Multi-User System.1634test # [ 12.032069] systemd[1]: Startup finished in 723ms (kernel) + 5.170s (initrd) + 6.136s (userspace) = 12.030s.1635second # [ 12.097234] systemd-networkd[534]: eth1: Gained IPv6LL1636second # [ 12.217473] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31637test: (finished: waiting for unit NetworkManager.service, in 12.68 seconds)1638test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1639test # [ 12.446585] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:08.0/virtio7/input/input31640test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.05 seconds)1641test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1642second # [ 12.466465] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1643test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1644test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1645second # [ 12.501683] systemd[1]: Starting Virtual Console Setup...1646second # [ 12.526407] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1647second # [ 12.529961] systemd[1]: Stopped Virtual Console Setup.1648second # [ 12.530699] systemd-logind[582]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1649second # [ 12.534173] systemd[1]: Starting Virtual Console Setup...1650test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.05 seconds)1651(finished: run the VM test script, in 13.27 seconds)1652test # [ 12.578080] systemd-networkd[555]: eth1: Gained IPv6LL1653test script finished in 13.33s1654cleanup1655kill QemuMachine (pid 14)1656second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1657kill QemuMachine (pid 15)1658test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/76jq0mngg7m8zv5yjz1qgg0xspzi8x2w-python3-3.13.15/bin/python3.13)1659(finished: cleanup, in 0.23 seconds)