vm-test-run-wifi
aarch64-linux.wifi
· build #132
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13second # Disk image does not exist, creating the virtualisation disk image...14second # Formatting '/build/vm-state-second/tmp.9fg4Z9jIx0', fmt=raw size=107374182415second: QEMU running (pid 47)16second # mke2fs 1.47.4 (6-Mar-2025)17test: QEMU running (pid 48)18second # Discarding device blocks: 0/262144 done19test # Disk image does not exist, creating the virtualisation disk image...20second # Creating filesystem with 262144 4k blocks and 65536 inodes21test # Formatting '/build/vm-state-test/tmp.2xKdXrtFOf', fmt=raw size=107374182422second # Filesystem UUID: 76eb3747-16b5-488f-93cc-511006339cf123test # mke2fs 1.47.4 (6-Mar-2025)24second # Superblock backups stored on blocks:25test # Discarding device blocks: 0/262144 done26second # 32768, 98304, 163840, 22937627test # Creating filesystem with 262144 4k blocks and 65536 inodes28second # 29test # Filesystem UUID: e4b92456-3df9-414d-b5aa-ad5a662d1ac130second # Allocating group tables: 0/8 done31test # Superblock backups stored on blocks:32second # Writing inode tables: 0/8 done33test # 32768, 98304, 163840, 22937634second # Creating journal (8192 blocks): done35test # 36second # Writing superblocks and filesystem accounting information: 0/8 done37test # Allocating group tables: 0/8 done38second # 39test # Writing inode tables: 0/8 done40second # Virtualisation disk image created.41test # Creating journal (8192 blocks): done42(finished: start all VMs, in 0.45 seconds)43test # Writing superblocks and filesystem accounting information: 0/8 done44test: waiting for unit NetworkManager.service45test # 46test: waiting for the VM to finish booting47test # Virtualisation disk image created.48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]50second # [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Jul 24 14:17:26 UTC 202651test # [ 0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Jul 24 14:17:26 UTC 202652test # [ 0.000000] KASLR enabled53test # [ 0.000000] random: crng init done54second # [ 0.000000] KASLR enabled55second # [ 0.000000] random: crng init done56test # [ 0.000000] Machine model: linux,dummy-virt57second # [ 0.000000] Machine model: linux,dummy-virt58test # [ 0.000000] efi: UEFI not found.59second # [ 0.000000] efi: UEFI not found.60test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT61second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT62test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]63second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]64test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]65second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]66test # [ 0.000000] Zone ranges:67second # [ 0.000000] Zone ranges:68second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]69test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]70second # [ 0.000000] DMA32 empty71test # [ 0.000000] DMA32 empty72second # [ 0.000000] Normal empty73test # [ 0.000000] Normal empty74second # [ 0.000000] Device empty75second # [ 0.000000] Movable zone start for each node76second # [ 0.000000] Early memory node ranges77test # [ 0.000000] Device empty78test # [ 0.000000] Movable zone start for each node79test # [ 0.000000] Early memory node ranges80second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]81test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]82test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]83test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000084test # [ 0.000000] psci: probing for conduit method from DT.85test # [ 0.000000] psci: PSCIv1.3 detected in firmware.86test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs87test # [ 0.000000] psci: Trusted OS migration not required88test # [ 0.000000] psci: SMC Calling Convention v1.189second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]90test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)91second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000092second # [ 0.000000] psci: probing for conduit method from DT.93second # [ 0.000000] psci: PSCIv1.3 detected in firmware.94second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs95second # [ 0.000000] psci: Trusted OS migration not required96second # [ 0.000000] psci: SMC Calling Convention v1.197second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)98second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129699second # [ 0.000000] Detected PIPT I-cache on CPU0100test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296101test # [ 0.000000] Detected PIPT I-cache on CPU0102second # [ 0.000000] CPU features: detected: GICv3 CPU interface103test # [ 0.000000] CPU features: detected: GICv3 CPU interface104second # [ 0.000000] CPU features: detected: Spectre-v4105second # [ 0.000000] CPU features: detected: Spectre-BHB106test # [ 0.000000] CPU features: detected: Spectre-v4107test # [ 0.000000] CPU features: detected: Spectre-BHB108second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR109test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR110second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)111second # [ 0.000000] CPU features: detected: ARM erratum 1418040112test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)113test # [ 0.000000] CPU features: detected: ARM erratum 1418040114second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion115test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion116second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing117second # [ 0.000000] alternatives: applying boot alternatives118test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing119test # [ 0.000000] alternatives: applying boot alternatives120second # [ 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/sig1b3m5b75sija5blqd6yz3zhfcg0iw-nixos-system-second-test/init regInfo=/nix/store/j285cn5i5k102zf66kqs3c4vw4k58apq-closure-info/registration console=ttyAMA0,115200n8 console=tty0121test # [ 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/1yld3c5hx0gbc576caic5a44pr6ffiwf-nixos-system-test-test/init regInfo=/nix/store/3vm6bjgby7n08qmf7nsr1qgxjhf9swxx-closure-info/registration console=ttyAMA0,115200n8 console=tty0122second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/j285cn5i5k102zf66kqs3c4vw4k58apq-closure-info/registration", will be passed to user space.123test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/3vm6bjgby7n08qmf7nsr1qgxjhf9swxx-closure-info/registration", will be passed to user space.124second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes125test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes126second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)127test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)128second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)129second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB130test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)131second # [ 0.000000] software IO TLB: area num 1.132test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB133test # [ 0.000000] software IO TLB: area num 1.134second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)135second # [ 0.000000] Fallback order for Node 0: 0136test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)137second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144138test # [ 0.000000] Fallback order for Node 0: 0139second # [ 0.000000] Policy zone: DMA140test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144141second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off142test # [ 0.000000] Policy zone: DMA143second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1144second # [ 0.000000] allocated 2097152 bytes of page_ext145second # [ 0.000000] ftrace: allocating 74754 entries in 294 pages146test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off147test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1148second # [ 0.000000] ftrace: allocated 294 pages with 4 groups149test # [ 0.000000] allocated 2097152 bytes of page_ext150second # [ 0.000000] rcu: Hierarchical RCU implementation.151second # [ 0.000000] rcu: RCU event tracing is enabled.152test # [ 0.000000] ftrace: allocating 74754 entries in 294 pages153test # [ 0.000000] ftrace: allocated 294 pages with 4 groups154second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.155test # [ 0.000000] rcu: Hierarchical RCU implementation.156second # [ 0.000000] Trampoline variant of Tasks RCU enabled.157test # [ 0.000000] rcu: RCU event tracing is enabled.158second # [ 0.000000] Rude variant of Tasks RCU enabled.159second # [ 0.000000] Tracing variant of Tasks RCU enabled.160test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.161test # [ 0.000000] Trampoline variant of Tasks RCU enabled.162second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.163test # [ 0.000000] Rude variant of Tasks RCU enabled.164second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1165test # [ 0.000000] Tracing variant of Tasks RCU enabled.166second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.168test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1169second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.171second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0173test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174second # [ 0.000000] GICv3: 256 SPIs implemented175test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.176test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0177second # [ 0.000000] GICv3: 0 Extended SPIs implemented178second # [ 0.000000] Root IRQ handler: gic_handle_irq179second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI180second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0181test # [ 0.000000] GICv3: 256 SPIs implemented182test # [ 0.000000] GICv3: 0 Extended SPIs implemented183second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000184test # [ 0.000000] Root IRQ handler: gic_handle_irq185second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]186test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI187test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0188second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)189test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000190second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)191test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]192second # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000193test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)194second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000195second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.196test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)197test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000198second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns199test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d10000200second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040201test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.202second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).203test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns204second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns205test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040206second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns207test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).208second # [ 0.000077] arm-pv: using stolen time PV209test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns210second # [ 0.000442] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)211second # [ 0.000748] Console: colour dummy device 80x25212test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns213second # [ 0.000768] printk: legacy console [tty0] enabled214test # [ 0.000071] arm-pv: using stolen time PV215second # [ 0.000981] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)216test # [ 0.000421] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)217second # [ 0.000987] pid_max: default: 32768 minimum: 301218test # [ 0.000728] Console: colour dummy device 80x25219second # [ 0.001081] LSM: initializing lsm=capability,landlock,yama,bpf,ima220test # [ 0.000747] printk: legacy console [tty0] enabled221second # [ 0.001225] landlock: Up and running.222second # [ 0.001227] Yama: becoming mindful.223second # [ 0.001722] LSM support for eBPF active224test # [ 0.000963] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)225test # [ 0.000969] pid_max: default: 32768 minimum: 301226second # [ 0.001879] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)227test # [ 0.001065] LSM: initializing lsm=capability,landlock,yama,bpf,ima228test # [ 0.001263] landlock: Up and running.229second # [ 0.001899] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)230test # [ 0.001265] Yama: becoming mindful.231second # [ 0.003950] cacheinfo: Unable to detect cache hierarchy for CPU 0232test # [ 0.001744] LSM support for eBPF active233second # [ 0.004825] rcu: Hierarchical SRCU implementation.234test # [ 0.001921] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)235test # [ 0.001941] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)236test # [ 0.004141] cacheinfo: Unable to detect cache hierarchy for CPU 0237test # [ 0.004914] rcu: Hierarchical SRCU implementation.238test # [ 0.004919] rcu: Max phase no-delay instances is 1000.239test # [ 0.005884] fsl-mc MSI: its@8080000 domain created240test # [ 0.006084] EFI services will not be available.241test # [ 0.006195] smp: Bringing up secondary CPUs ...242test # [ 0.006235] smp: Brought up 1 node, 1 CPU243test # [ 0.006239] SMP: Total of 1 processors activated.244second # [ 0.004831] rcu: Max phase no-delay instances is 1000.245second # [ 0.005787] fsl-mc MSI: its@8080000 domain created246second # [ 0.005951] EFI services will not be available.247second # [ 0.006050] smp: Bringing up secondary CPUs ...248second # [ 0.006093] smp: Brought up 1 node, 1 CPU249second # [ 0.006097] SMP: Total of 1 processors activated.250second # [ 0.006099] CPU: All CPU(s) started at EL1251second # [ 0.006112] CPU features: detected: 32-bit EL0 Support252second # [ 0.006115] CPU features: detected: Data cache clean to the PoU not required for I/D coherence253second # [ 0.006118] CPU features: detected: Common not Private translations254second # [ 0.006120] CPU features: detected: CRC32 instructions255test # [ 0.006241] CPU: All CPU(s) started at EL1256test # [ 0.006254] CPU features: detected: 32-bit EL0 Support257second # [ 0.006122] CPU features: detected: Data cache clean to Point of Persistence258second # [ 0.006125] CPU features: detected: Enhanced Virtualization Traps259test # [ 0.006256] CPU features: detected: Data cache clean to the PoU not required for I/D coherence260second # [ 0.006135] CPU features: detected: RCpc load-acquire (LDAPR)261test # [ 0.006259] CPU features: detected: Common not Private translations262second # [ 0.006137] CPU features: detected: LSE atomic instructions263test # [ 0.006262] CPU features: detected: CRC32 instructions264second # [ 0.006139] CPU features: detected: Privileged Access Never265second # [ 0.006141] CPU features: detected: PMUv3266test # [ 0.006263] CPU features: detected: Data cache clean to Point of Persistence267second # [ 0.006143] CPU features: detected: RAS Extension Support268test # [ 0.006266] CPU features: detected: Enhanced Virtualization Traps269second # [ 0.006147] CPU features: detected: Speculative Store Bypassing Safe (SSBS)270test # [ 0.006277] CPU features: detected: RCpc load-acquire (LDAPR)271second # [ 0.006175] alternatives: applying system-wide alternatives272test # [ 0.006279] CPU features: detected: LSE atomic instructions273second # [ 0.007609] CPU features: detected: Hardware dirty bit management on CPU0274test # [ 0.006281] CPU features: detected: Privileged Access Never275second # [ 0.007817] Memory: 891556K/1048576K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 115744K reserved, 32768K cma-reserved)276second # [ 0.008327] devtmpfs: initialized277second # [ 0.009891] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)278second # [ 0.009914] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).279second # [ 0.010038] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL280second # [ 0.010041] 0 pages in range for non-PLT usage281second # [ 0.010042] 508320 pages in range for PLT usage282test # [ 0.006283] CPU features: detected: PMUv3283test # [ 0.006285] CPU features: detected: RAS Extension Support284test # [ 0.006289] CPU features: detected: Speculative Store Bypassing Safe (SSBS)285test # [ 0.006311] alternatives: applying system-wide alternatives286test # [ 0.007695] CPU features: detected: Hardware dirty bit management on CPU0287test # [ 0.007936] Memory: 891552K/1048576K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 115744K reserved, 32768K cma-reserved)288test # [ 0.008429] devtmpfs: initialized289test # [ 0.010007] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)290second # [ 0.010172] pinctrl core: initialized pinctrl subsystem291second # [ 0.010975] DMI not present or invalid.292test # [ 0.010030] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).293second # [ 0.014816] NET: Registered PF_NETLINK/PF_ROUTE protocol family294second # [ 0.017210] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations295second # [ 0.017349] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations296second # [ 0.017501] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations297second # [ 0.017521] audit: initializing netlink subsys (disabled)298second # [ 0.018150] thermal_sys: Registered thermal governor 'fair_share'299test # [ 0.010174] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL300test # [ 0.010177] 0 pages in range for non-PLT usage301test # [ 0.010178] 508320 pages in range for PLT usage302test # [ 0.010307] pinctrl core: initialized pinctrl subsystem303test # [ 0.011142] DMI not present or invalid.304test # [ 0.014659] NET: Registered PF_NETLINK/PF_ROUTE protocol family305test # [ 0.017295] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations306second # [ 0.018152] thermal_sys: Registered thermal governor 'bang_bang'307test # [ 0.017433] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations308second # [ 0.018156] thermal_sys: Registered thermal governor 'step_wise'309second # [ 0.018158] thermal_sys: Registered thermal governor 'user_space'310second # [ 0.018160] thermal_sys: Registered thermal governor 'power_allocator'311second # [ 0.018201] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1312second # [ 0.018216] cpuidle: using governor ladder313second # [ 0.018221] cpuidle: using governor menu314second # [ 0.018338] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.315second # [ 0.018360] ASID allocator initialised with 32768 entries316second # [ 0.019031] Serial: AMBA PL011 UART driver317test # [ 0.017585] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations318second # [ 0.022218] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1319test # [ 0.017605] audit: initializing netlink subsys (disabled)320second # [ 0.022396] printk: console [ttyAMA0] enabled321test # [ 0.018256] thermal_sys: Registered thermal governor 'fair_share'322test # [ 0.018258] thermal_sys: Registered thermal governor 'bang_bang'323test # [ 0.018261] thermal_sys: Registered thermal governor 'step_wise'324test # [ 0.018263] thermal_sys: Registered thermal governor 'user_space'325test # [ 0.018265] thermal_sys: Registered thermal governor 'power_allocator'326test # [ 0.018308] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1327test # [ 0.018325] cpuidle: using governor ladder328test # [ 0.018330] cpuidle: using governor menu329test # [ 0.018454] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.330test # [ 0.018477] ASID allocator initialised with 32768 entries331test # [ 0.019177] Serial: AMBA PL011 UART driver332test # [ 0.022796] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333test # [ 0.022981] printk: console [ttyAMA0] enabled334second # [ 0.093599] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages335second # [ 0.093624] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page336second # [ 0.093628] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages337second # [ 0.093633] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page338test # [ 0.095959] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages339second # [ 0.093637] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages340test # [ 0.095982] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page341second # [ 0.093640] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page342test # [ 0.095986] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages343second # [ 0.093643] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages344test # [ 0.095992] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page345second # [ 0.093646] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page346test # [ 0.095995] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages347test # [ 0.095998] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page348test # [ 0.096001] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages349test # [ 0.096004] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page350second # [ 0.098432] fbcon: Taking over console351second # [ 0.098459] ACPI: Interpreter disabled.352second # [ 0.099644] iommu: Default domain type: Translated353second # [ 0.099654] iommu: DMA domain TLB invalidation policy: strict mode354test # [ 0.100822] fbcon: Taking over console355second # [ 0.100780] SCSI subsystem initialized356test # [ 0.100847] ACPI: Interpreter disabled.357test # [ 0.102099] iommu: Default domain type: Translated358second # [ 0.101325] usbcore: registered new interface driver usbfs359test # [ 0.102108] iommu: DMA domain TLB invalidation policy: strict mode360second # [ 0.101363] usbcore: registered new interface driver hub361second # [ 0.101374] usbcore: registered new device driver usb362test # [ 0.103247] SCSI subsystem initialized363second # [ 0.101574] pps_core: LinuxPPS API ver. 1 registered364test # [ 0.103803] usbcore: registered new interface driver usbfs365test # [ 0.103840] usbcore: registered new interface driver hub366test # [ 0.103850] usbcore: registered new device driver usb367test # [ 0.105298] pps_core: LinuxPPS API ver. 1 registered368test # [ 0.105308] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>369test # [ 0.105315] PTP clock support registered370test # [ 0.105361] EDAC MC: Ver: 3.0.0371second # [ 0.109602] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>372second # [ 0.109638] PTP clock support registered373test # [ 0.112082] scmi_core: SCMI protocol bus registered374second # [ 0.109682] EDAC MC: Ver: 3.0.0375second # [ 0.111053] scmi_core: SCMI protocol bus registered376second # [ 0.111715] FPGA manager framework377test # [ 0.112804] FPGA manager framework378second # [ 0.112754] vgaarb: loaded379test # [ 0.113769] vgaarb: loaded380second # [ 0.113184] clocksource: Switched to clocksource arch_sys_counter381test # [ 0.114202] clocksource: Switched to clocksource arch_sys_counter382second # [ 0.114276] VFS: Disk quotas dquot_6.6.0383test # [ 0.114616] VFS: Disk quotas dquot_6.6.0384second # [ 0.114310] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)385test # [ 0.114642] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)386second # [ 0.115307] netfs: FS-Cache loaded387test # [ 0.116226] netfs: FS-Cache loaded388second # [ 0.115419] pnp: PnP ACPI: disabled389test # [ 0.116336] pnp: PnP ACPI: disabled390second # [ 0.122472] NET: Registered PF_INET protocol family391test # [ 0.124802] NET: Registered PF_INET protocol family392second # [ 0.122654] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)393test # [ 0.124993] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394test # [ 0.149951] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395test # [ 0.149979] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.149991] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397test # [ 0.150029] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398test # [ 0.150098] TCP: Hash tables configured (established 8192 bind 8192)399test # [ 0.150188] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.153603] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)401test # [ 0.153663] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)402test # [ 0.153806] NET: Registered PF_UNIX/PF_LOCAL protocol family403test # [ 0.153842] NET: Registered PF_XDP protocol family404test # [ 0.153865] PCI: CLS 0 bytes, default 64405second # [ 0.147871] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)406second # [ 0.147900] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)407second # [ 0.147914] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)408second # [ 0.147950] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)409second # [ 0.148018] TCP: Hash tables configured (established 8192 bind 8192)410second # [ 0.148110] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)411test # [ 0.156197] Trying to unpack rootfs image as initramfs...412second # [ 0.148179] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)413second # [ 0.148230] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)414second # [ 0.148358] NET: Registered PF_UNIX/PF_LOCAL protocol family415second # [ 0.148396] NET: Registered PF_XDP protocol family416second # [ 0.148418] PCI: CLS 0 bytes, default 64417second # [ 0.148628] Trying to unpack rootfs image as initramfs...418test # [ 0.162653] kvm [1]: HYP mode not available419second # [ 0.162916] kvm [1]: HYP mode not available420second # [ 0.225571] Initialise system trusted keyrings421second # [ 0.226093] workingset: timestamp_bits=42 max_order=18 bucket_order=0422second # [ 0.227023] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.228247] Initialise system trusted keyrings424test # [ 0.228724] workingset: timestamp_bits=42 max_order=18 bucket_order=0425test # [ 0.229716] squashfs: version 4.0 (2009/01/31) Phillip Lougher426second # [ 0.227498] 9p: Installing v9fs 9p2000 file system support427test # [ 0.230187] 9p: Installing v9fs 9p2000 file system support428test # [ 0.249455] Key type asymmetric registered429test # [ 0.249482] Asymmetric key parser 'x509' registered430test # [ 0.249546] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431second # [ 0.250411] Key type asymmetric registered432second # [ 0.250438] Asymmetric key parser 'x509' registered433second # [ 0.250502] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)434second # [ 0.251795] io scheduler mq-deadline registered435second # [ 0.251805] io scheduler kyber registered436test # [ 0.254263] io scheduler mq-deadline registered437test # [ 0.254277] io scheduler kyber registered438second # [ 0.261331] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439test # [ 0.262342] pl061_gpio 9030000.pl061: PL061 GPIO chip registered440second # [ 0.261694] ledtrig-cpu: registered to indicate activity on CPUs441test # [ 0.262707] ledtrig-cpu: registered to indicate activity on CPUs442second # [ 0.262004] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:443test # [ 0.263030] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:444second # [ 0.262025] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000445test # [ 0.263052] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000446second # [ 0.262042] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000447test # [ 0.263067] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000448second # [ 0.262049] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000449test # [ 0.263073] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000450second # [ 0.262086] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits451test # [ 0.263101] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits452second # [ 0.262111] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]453test # [ 0.263125] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]454second # [ 0.262198] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00455test # [ 0.263195] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00456second # [ 0.262222] pci_bus 0000:00: root bus resource [bus 00-ff]457test # [ 0.263218] pci_bus 0000:00: root bus resource [bus 00-ff]458second # [ 0.262227] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]459test # [ 0.263224] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]460second # [ 0.262231] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]461test # [ 0.263227] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]462second # [ 0.262234] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]463test # [ 0.263231] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]464second # [ 0.262319] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint465test # [ 0.263350] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint466second # [ 0.262796] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint467test # [ 0.263822] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint468second # [ 0.262921] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]469test # [ 0.263950] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]470second # [ 0.262933] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]471test # [ 0.263962] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]472second # [ 0.262954] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]473test # [ 0.263984] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]474second # [ 0.262965] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]475test # [ 0.263995] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]476second # [ 0.263275] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint477test # [ 0.264308] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint478second # [ 0.263400] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]479test # [ 0.264433] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]480second # [ 0.263411] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]481test # [ 0.264444] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]482second # [ 0.263432] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]483test # [ 0.264465] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]484second # [ 0.263736] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint485test # [ 0.264776] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint486second # [ 0.263860] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]487test # [ 0.264901] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]488second # [ 0.263871] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]489test # [ 0.264912] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]490second # [ 0.263892] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]491test # [ 0.264932] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]492second # [ 0.264197] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint493test # [ 0.265244] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint494second # [ 0.264333] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]495test # [ 0.265368] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]496second # [ 0.264344] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]497test # [ 0.265378] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]498second # [ 0.264363] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]499test # [ 0.265399] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]500second # [ 0.264673] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint501test # [ 0.265702] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint502second # [ 0.264796] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]503test # [ 0.265825] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]504second # [ 0.264807] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]505test # [ 0.265836] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]506second # [ 0.264828] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]507test # [ 0.265855] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]508second # [ 0.265130] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint509test # [ 0.266147] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint510second # [ 0.265274] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]511test # [ 0.266286] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]512second # [ 0.265286] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]513test # [ 0.266298] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]514second # [ 0.265306] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]515test # [ 0.266317] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]516second # [ 0.265643] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint517test # [ 0.266617] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint518second # [ 0.265767] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]519test # [ 0.266740] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]520second # [ 0.265778] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]521test # [ 0.266751] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]522second # [ 0.265800] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]523test # [ 0.266771] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524second # [ 0.265815] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]525test # [ 0.266785] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]526second # [ 0.266117] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint527test # [ 0.267120] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint528second # [ 0.266251] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]529test # [ 0.267253] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]530second # [ 0.266271] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]531test # [ 0.267274] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]532second # [ 0.266584] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint533test # [ 0.267582] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint534second # [ 0.266710] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]535test # [ 0.267708] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]536second # [ 0.266731] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537test # [ 0.267728] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]538second # [ 0.266988] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint539test # [ 0.267990] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint540second # [ 0.267108] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]541test # [ 0.268108] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]542second # [ 0.267285] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint543test # [ 0.268274] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint544second # [ 0.267451] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]545test # [ 0.268468] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]546second # [ 0.267463] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]547test # [ 0.268482] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]548second # [ 0.267482] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549test # [ 0.268502] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]550second # [ 0.267899] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint551second # [ 0.268022] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]552test # [ 0.268806] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint553test # [ 0.268929] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]554second # [ 0.268034] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]555test # [ 0.268940] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]556second # [ 0.268053] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]557test # [ 0.268959] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]558second # [ 0.268470] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned559test # [ 0.269373] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned560second # [ 0.268483] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned561test # [ 0.269384] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned562second # [ 0.268488] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned563test # [ 0.269389] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned564second # [ 0.268523] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned565test # [ 0.269422] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned566second # [ 0.268555] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned567test # [ 0.269454] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned568second # [ 0.268585] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned569test # [ 0.269484] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned570second # [ 0.268616] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned571test # [ 0.269515] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned572second # [ 0.268646] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned573test # [ 0.269547] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned574second # [ 0.268676] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned575test # [ 0.269578] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned576second # [ 0.268707] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned577test # [ 0.269610] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned578second # [ 0.268737] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned579test # [ 0.269642] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned580second # [ 0.268768] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned581test # [ 0.269673] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned582second # [ 0.268830] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned583second # [ 0.268861] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned584test # [ 0.269732] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned585second # [ 0.268876] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned586test # [ 0.269763] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned587second # [ 0.268889] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned588test # [ 0.269777] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned589second # [ 0.268903] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned590test # [ 0.269791] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned591second # [ 0.268917] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned592test # [ 0.269804] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned593second # [ 0.268931] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned594test # [ 0.269817] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned595second # [ 0.268945] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned596test # [ 0.269831] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned597second # [ 0.268959] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned598test # [ 0.269845] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned599second # [ 0.268972] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned600test # [ 0.269859] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned601second # [ 0.268986] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned602test # [ 0.269872] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned603second # [ 0.269000] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned604test # [ 0.269886] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned605second # [ 0.269014] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned606test # [ 0.269900] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned607second # [ 0.269027] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned608test # [ 0.269914] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned609second # [ 0.269041] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned610test # [ 0.269927] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned611test # [ 0.269940] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned612test # [ 0.269954] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned613test # [ 0.269967] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned614test # [ 0.269980] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned615test # [ 0.269999] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned616test # [ 0.270012] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned617second # [ 0.269055] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned618test # [ 0.270025] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned619second # [ 0.269068] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned620test # [ 0.270038] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned621second # [ 0.269082] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned622test # [ 0.270062] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]623second # [ 0.269100] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned624test # [ 0.270069] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]625second # [ 0.269113] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned626test # [ 0.270073] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]627second # [ 0.269127] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned628second # [ 0.269140] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned629second # [ 0.269165] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]630second # [ 0.269172] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]631second # [ 0.269176] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]632test # [ 0.326897] pci 0000:00:0a.0: enabling device (0000 -> 0002)633second # [ 0.325973] pci 0000:00:0a.0: enabling device (0000 -> 0002)634second # [ 0.332562] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)635test # [ 0.333652] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)636second # [ 0.342362] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)637test # [ 0.343466] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)638test # [ 0.350369] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)639second # [ 0.349354] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)640test # [ 0.352104] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)641second # [ 0.351133] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)642test # [ 0.353854] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)643second # [ 0.353038] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)644test # [ 0.363488] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)645second # [ 0.362665] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)646test # [ 0.365360] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)647second # [ 0.364553] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)648test # [ 0.371279] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)649test # [ 0.373271] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)650second # [ 0.375808] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)651second # [ 0.378273] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)652second # [ 0.379856] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)653test # [ 0.383352] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)654test # [ 0.385464] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)655second # [ 0.389693] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)656second # [ 0.393848] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled657test # [ 0.394939] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled658second # [ 0.395384] msm_serial: driver initialized659test # [ 0.396484] msm_serial: driver initialized660second # [ 0.395474] SuperH (H)SCI(F) driver initialized661test # [ 0.396575] SuperH (H)SCI(F) driver initialized662second # [ 0.395509] STM32 USART driver initialized663test # [ 0.396618] STM32 USART driver initialized664second # [ 0.421666] loop: module loaded665second # [ 0.421810] virtio_blk virtio5: 1/0/0 default/read/poll queues666test # [ 0.422916] loop: module loaded667test # [ 0.423062] virtio_blk virtio5: 1/0/0 default/read/poll queues668second # [ 0.422424] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)669test # [ 0.423607] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)670second # [ 0.425592] megasas: 07.734.00.00-rc1671second # [ 0.426061] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]672test # [ 0.430655] megasas: 07.734.00.00-rc1673test # [ 0.431138] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]674test # [ 0.433448] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000675test # [ 0.433480] Intel/Sharp Extended Query Table at 0x0031676test # [ 0.435165] Using buffer write method677test # [ 0.435218] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]678test # [ 0.436942] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000679test # [ 0.436981] Intel/Sharp Extended Query Table at 0x0031680second # [ 0.437756] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000681second # [ 0.437796] Intel/Sharp Extended Query Table at 0x0031682test # [ 0.442591] Using buffer write method683second # [ 0.439485] Using buffer write method684test # [ 0.442614] Concatenating MTD devices:685test # [ 0.442617] (0): "0.flash"686test # [ 0.442620] (1): "0.flash"687second # [ 0.439524] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]688test # [ 0.442622] into device "0.flash"689second # [ 0.444178] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690second # [ 0.444200] Intel/Sharp Extended Query Table at 0x0031691second # [ 0.446730] Using buffer write method692second # [ 0.446753] Concatenating MTD devices:693second # [ 0.446756] (0): "0.flash"694second # [ 0.446759] (1): "0.flash"695second # [ 0.446762] into device "0.flash"696test # [ 0.662622] Freeing initrd memory: 27072K697test # [ 0.669547] tun: Universal TUN/TAP device driver, 1.6698second # [ 0.671736] Freeing initrd memory: 27072K699test # [ 0.672169] thunder_xcv, ver 1.0700test # [ 0.672202] thunder_bgx, ver 1.0701test # [ 0.672214] nicpf, ver 1.0702test # [ 0.672526] e1000: Intel(R) PRO/1000 Network Driver703test # [ 0.672536] e1000: Copyright (c) 1999-2006 Intel Corporation.704test # [ 0.672554] e1000e: Intel(R) PRO/1000 Network Driver705test # [ 0.672559] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.706test # [ 0.672573] igb: Intel(R) Gigabit Ethernet Network Driver707test # [ 0.672576] igb: Copyright (c) 2007-2014 Intel Corporation.708test # [ 0.672588] igbvf: Intel(R) Gigabit Virtual Function Network Driver709test # [ 0.672591] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.710test # [ 0.672658] sky2: driver version 1.30711test # [ 0.673544] usbcore: registered new interface driver usb-storage712second # [ 0.678741] tun: Universal TUN/TAP device driver, 1.6713test # [ 0.673600] usbcore: registered new interface driver usbserial_generic714test # [ 0.673625] usbserial: USB Serial support registered for generic715test # [ 0.673927] hv_vmbus: registering driver hyperv_keyboard716test # [ 0.674838] ehci-pci 0000:00:0a.0: EHCI Host Controller717test # [ 0.674877] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1718test # [ 0.675108] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000719test # [ 0.682784] rtc-pl031 9010000.pl031: registered as rtc0720test # [ 0.682820] rtc-pl031 9010000.pl031: setting system clock to 2026-07-27T00:15:05 UTC (1785111305)721second # [ 0.681464] thunder_xcv, ver 1.0722test # [ 0.683008] i2c_dev: i2c /dev entries driver723second # [ 0.681491] thunder_bgx, ver 1.0724second # [ 0.681502] nicpf, ver 1.0725second # [ 0.681796] e1000: Intel(R) PRO/1000 Network Driver726second # [ 0.681804] e1000: Copyright (c) 1999-2006 Intel Corporation.727second # [ 0.681822] e1000e: Intel(R) PRO/1000 Network Driver728second # [ 0.681827] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.729second # [ 0.681840] igb: Intel(R) Gigabit Ethernet Network Driver730test # [ 0.685780] sdhci: Secure Digital Host Controller Interface driver731second # [ 0.681844] igb: Copyright (c) 2007-2014 Intel Corporation.732test # [ 0.685795] sdhci: Copyright(c) Pierre Ossman733second # [ 0.681854] igbvf: Intel(R) Gigabit Virtual Function Network Driver734test # [ 0.685944] Synopsys Designware Multimedia Card Interface Driver735second # [ 0.681858] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.736test # [ 0.686121] sdhci-pltfm: SDHCI platform and OF driver helper737second # [ 0.681924] sky2: driver version 1.30738test # [ 0.686275] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00739second # [ 0.682714] usbcore: registered new interface driver usb-storage740test # [ 0.686613] hub 1-0:1.0: USB hub found741test # [ 0.686636] hub 1-0:1.0: 6 ports detected742second # [ 0.682800] usbcore: registered new interface driver usbserial_generic743second # [ 0.682821] usbserial: USB Serial support registered for generic744second # [ 0.683101] hv_vmbus: registering driver hyperv_keyboard745second # [ 0.690051] ehci-pci 0000:00:0a.0: EHCI Host Controller746second # [ 0.690089] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1747second # [ 0.690308] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000748second # [ 0.691832] rtc-pl031 9010000.pl031: registered as rtc0749second # [ 0.691869] rtc-pl031 9010000.pl031: setting system clock to 2026-07-27T00:15:05 UTC (1785111305)750second # [ 0.692051] i2c_dev: i2c /dev entries driver751second # [ 0.694756] sdhci: Secure Digital Host Controller Interface driver752test # [ 0.690387] hid: raw HID events driver (C) Jiri Kosina753second # [ 0.694771] sdhci: Copyright(c) Pierre Ossman754test # [ 0.690529] usbcore: registered new interface driver usbhid755test # [ 0.690534] usbhid: USB HID core driver756second # [ 0.694899] Synopsys Designware Multimedia Card Interface Driver757second # [ 0.695091] sdhci-pltfm: SDHCI platform and OF driver helper758second # [ 0.695970] hid: raw HID events driver (C) Jiri Kosina759second # [ 0.696142] usbcore: registered new interface driver usbhid760second # [ 0.696148] usbhid: USB HID core driver761test # [ 0.718588] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available762test # [ 0.719416] drop_monitor: Initializing network drop monitor service763second # [ 0.719101] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00764test # [ 0.719530] NET: Registered PF_INET6 protocol family765second # [ 0.719484] hub 1-0:1.0: USB hub found766second # [ 0.719509] hub 1-0:1.0: 6 ports detected767test # [ 0.721644] Segment Routing with IPv6768test # [ 0.721671] In-situ OAM (IOAM) with IPv6769test # [ 0.721728] NET: Registered PF_PACKET protocol family770second # [ 0.720801] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available771second # [ 0.721640] drop_monitor: Initializing network drop monitor service772second # [ 0.721791] NET: Registered PF_INET6 protocol family773second # [ 0.723937] Segment Routing with IPv6774second # [ 0.723964] In-situ OAM (IOAM) with IPv6775second # [ 0.724012] NET: Registered PF_PACKET protocol family776second # [ 0.725042] 9pnet: Installing 9P2000 support777test # [ 0.722793] 9pnet: Installing 9P2000 support778test # [ 0.724454] Key type dns_resolver registered779second # [ 0.727172] Key type dns_resolver registered780test # [ 0.728684] registered taskstats version 1781test # [ 0.728858] Loading compiled-in X.509 certificates782second # [ 0.730547] registered taskstats version 1783second # [ 0.730702] Loading compiled-in X.509 certificates784test # [ 0.734143] Demotion targets for Node 0: null785test # [ 0.734855] Key type .fscrypt registered786test # [ 0.734865] Key type fscrypt-provisioning registered787test # [ 0.734985] ima: No TPM chip found, activating TPM-bypass!788test # [ 0.734998] ima: Allocated hash algorithm: sha1789test # [ 0.735051] ima: No architecture policies found790second # [ 0.735893] Demotion targets for Node 0: null791second # [ 0.735999] Key type .fscrypt registered792test # [ 0.737292] input: gpio-keys as /devices/platform/gpio-keys/input/input0793second # [ 0.736005] Key type fscrypt-provisioning registered794second # [ 0.736121] ima: No TPM chip found, activating TPM-bypass!795second # [ 0.736134] ima: Allocated hash algorithm: sha1796second # [ 0.736189] ima: No architecture policies found797second # [ 0.739016] input: gpio-keys as /devices/platform/gpio-keys/input/input0798test # [ 0.747704] clk: Disabling unused clocks799test # [ 0.747731] PM: genpd: Disabling unused power domains800test # [ 0.750959] Freeing unused kernel memory: 4736K801test # [ 0.751050] Run /init as init process802second # [ 0.749856] clk: Disabling unused clocks803second # [ 0.749882] PM: genpd: Disabling unused power domains804second # [ 0.753215] Freeing unused kernel memory: 4736K805second # [ 0.753317] Run /init as init process806test # [ 0.772628] systemd[1]: Successfully made /usr/ read-only.807second # [ 0.775663] systemd[1]: Successfully made /usr/ read-only.808test # [ 0.938258] usb 1-1: new high-speed USB device number 2 using ehci-pci809second # [ 0.965243] usb 1-1: new high-speed USB device number 2 using ehci-pci810test # [ 1.090313] 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/input1811test # [ 1.107203] systemd[1]: systemd 261 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)812test # [ 1.113658] systemd[1]: Detected virtualization qemu.813test # [ 1.114868] systemd[1]: Detected architecture arm64.814test # [ 1.115872] systemd[1]: Running in initrd.815second # [ 1.110382] systemd[1]: systemd 261 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)816test # [ 1.117515] systemd[1]: Initializing machine ID from random generator.817second # [ 1.117274] systemd[1]: Detected virtualization qemu.818second # [ 1.118341] systemd[1]: Detected architecture arm64.819test # [ 1.119319] systemd[1]: Hostname set to <test>.820second # [ 1.119344] systemd[1]: Running in initrd.821second # [ 1.121367] systemd[1]: Initializing machine ID from random generator.822second # [ 1.123468] systemd[1]: Hostname set to <second>.823second # [ 1.128639] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1824test # [ 1.174408] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825second # [ 1.209388] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826test # [ 1.294244] usb 1-2: new high-speed USB device number 3 using ehci-pci827second # [ 1.329222] usb 1-2: new high-speed USB device number 3 using ehci-pci828test # [ 1.391195] systemd[1]: bpf-restrict-fs: LSM BPF program attached829second # [ 1.414452] systemd[1]: bpf-restrict-fs: LSM BPF program attached830test # [ 1.448210] 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/input2831test # [ 1.451390] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0832second # [ 1.471488] systemd[1]: Queued start job for default target Initrd Default Target.833second # [ 1.479644] systemd[1]: Created slice Slice /system/modprobe.834second # [ 1.480556] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.835second # [ 1.481261] systemd[1]: Expecting device /dev/disk/by-label/nixos...836second # [ 1.481775] systemd[1]: Reached target Path Units.837second # [ 1.482318] systemd[1]: Reached target Slice Units.838second # [ 1.483054] 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/input2839second # [ 1.483132] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0840second # [ 1.485251] systemd[1]: Reached target Swaps.841second # [ 1.485613] systemd[1]: Reached target Timer Units.842second # [ 1.486172] systemd[1]: Listening on D-Bus System Message Bus Socket.843second # [ 1.486794] systemd[1]: Listening on Journal Socket (/dev/log).844second # [ 1.487355] systemd[1]: Listening on Journal Sockets.845second # [ 1.487847] systemd[1]: Listening on udev Control Socket.846second # [ 1.488465] systemd[1]: Listening on udev Kernel Socket.847second # [ 1.488907] systemd[1]: Reached target Socket Units.848second # [ 1.490366] systemd[1]: Starting Create List of Static Device Nodes...849second # [ 1.496304] systemd[1]: Starting Load Kernel Module 9pnet_virtio...850second # [ 1.497286] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs851test # [ 1.499829] systemd[1]: Queued start job for default target Initrd Default Target.852test # [ 1.508200] systemd[1]: Created slice Slice /system/modprobe.853test # [ 1.509138] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.854test # [ 1.510090] systemd[1]: Expecting device /dev/disk/by-label/nixos...855test # [ 1.510939] systemd[1]: Reached target Path Units.856test # [ 1.511520] systemd[1]: Reached target Slice Units.857test # [ 1.512100] systemd[1]: Reached target Swaps.858test # [ 1.512620] systemd[1]: Reached target Timer Units.859test # [ 1.513512] systemd[1]: Listening on D-Bus System Message Bus Socket.860second # [ 1.511974] systemd[1]: Mounting Kernel Configuration File System...861test # [ 1.514454] systemd[1]: Listening on Journal Socket (/dev/log).862second # [ 1.514094] systemd[1]: Starting Journal Service...863test # [ 1.515273] systemd[1]: Listening on Journal Sockets.864test # [ 1.515974] systemd[1]: Listening on udev Control Socket.865test # [ 1.516730] systemd[1]: Listening on udev Kernel Socket.866test # [ 1.517391] systemd[1]: Reached target Socket Units.867test # [ 1.519419] systemd[1]: Starting Create List of Static Device Nodes...868second # [ 1.519798] systemd[1]: Starting Load Kernel Modules...869second # [ 1.521342] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os870second # [ 1.522732] systemd[1]: Starting Coldplug All udev Devices...871test # [ 1.526317] systemd[1]: Starting Load Kernel Module 9pnet_virtio...872test # [ 1.527102] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs873test # [ 1.536129] systemd[1]: Mounting Kernel Configuration File System...874second # [ 1.535695] systemd[1]: Finished Create List of Static Device Nodes.875second # [ 1.536687] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.876test # [ 1.546544] systemd[1]: Starting Journal Service...877second # [ 1.549376] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878second # [ 1.549807] systemd[1]: Mounted Kernel Configuration File System.879second # [ 1.556088] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.880test # [ 1.558374] systemd[1]: Starting Load Kernel Modules...881test # [ 1.559080] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os882test # [ 1.570281] systemd[1]: Starting Coldplug All udev Devices...883second # [ 1.569281] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev884second # [ 1.573843] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0885second # [ 1.574066] [drm] features: -virgl +edid -resource_blob -host_visible886second # [ 1.574070] [drm] features: -context_init887second # [ 1.574509] [drm] number of scanouts: 1888second # [ 1.574522] [drm] number of cap sets: 0889second # [ 1.577360] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...890test # [ 1.586369] systemd[1]: Finished Create List of Static Device Nodes.891second # [ 1.586943] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.892test # [ 1.587373] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.893second # [ 1.588575] systemd[1]: Starting Create Static Device Nodes in /dev...894test # [ 1.587560] systemd[1]: Finished Load Kernel Module 9pnet_virtio.895test # [ 1.587749] systemd[1]: Mounted Kernel Configuration File System.896second # [ 1.595957] systemd-journald[73]: Collecting audit messages is disabled.897second # [ 1.596825] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic898second # [ 1.596839] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0899test # [ 1.602483] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...900test # [ 1.623964] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.901second # [ 1.619070] Console: switching to colour frame buffer device 160x50902second # [ 1.625406] systemd[1]: Finished Create Static Device Nodes in /dev.903second # [ 1.625542] systemd[1]: Reached target Preparation for Local File Systems.904second # [ 1.625568] systemd[1]: Reached target Local File Systems.905test # [ 1.632100] systemd-journald[73]: Collecting audit messages is disabled.906test # [ 1.633105] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.907second # [ 1.629443] systemd[1]: Starting Rule-based Manager for Device Events and Files...908test # [ 1.634829] systemd[1]: Starting Create Static Device Nodes in /dev...909second # [ 1.646268] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device910test # [ 1.650299] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev911test # [ 1.654968] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0912test # [ 1.655184] [drm] features: -virgl +edid -resource_blob -host_visible913test # [ 1.655190] [drm] features: -context_init914test # [ 1.655634] [drm] number of scanouts: 1915test # [ 1.655651] [drm] number of cap sets: 0916second # [ 1.660818] systemd[1]: Finished Load Kernel Modules.917second # [ 1.662976] systemd[1]: Starting Apply Kernel Variables...918test # [ 1.666628] systemd[1]: Finished Create Static Device Nodes in /dev.919test # [ 1.666823] systemd[1]: Reached target Preparation for Local File Systems.920test # [ 1.666855] systemd[1]: Reached target Local File Systems.921test # [ 1.669340] systemd[1]: Starting Rule-based Manager for Device Events and Files...922test # [ 1.678619] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic923test # [ 1.678635] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0924second # [ 1.684285] systemd-modules-load[74]: Inserted module 'dm_mod'925second # [ 1.685132] systemd-modules-load[74]: Module 'virtio_balloon' is built in926second # [ 1.697444] systemd[1]: Started Journal Service.927second # [ 1.690741] systemd-modules-load[74]: Module 'virtio_console' is built in928second # [ 1.696335] systemd-modules-load[74]: Inserted module 'virtio_gpu'929test # [ 1.702616] Console: switching to colour frame buffer device 160x50930second # [ 1.700144] systemd-modules-load[74]: Module 'virtio_rng' is built in931second # [ 1.704098] systemd[1]: Starting Create System Files and Directories...932second # [ 1.704712] systemd[1]: Finished Apply Kernel Variables.933second # [ 1.714003] systemd-udevd[80]: Using default interface naming scheme 'v261'.934test # [ 1.725877] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device935test # [ 1.730423] systemd[1]: Started Journal Service.936test # [ 1.728919] systemd-modules-load[74]: Inserted module 'dm_mod'937test # [ 1.729932] systemd-modules-load[74]: Module 'virtio_balloon' is built in938test # [ 1.735719] systemd-modules-load[74]: Module 'virtio_console' is built in939second # [ 1.732316] systemd[1]: Started Rule-based Manager for Device Events and Files.940test # [ 1.740294] systemd[1]: Starting Create System Files and Directories...941test # [ 1.740963] systemd-modules-load[74]: Inserted module 'virtio_gpu'942test # [ 1.741552] systemd-modules-load[74]: Module 'virtio_rng' is built in943second # [ 1.738592] systemd[1]: Finished Create System Files and Directories.944test # [ 1.752049] systemd-udevd[80]: Using default interface naming scheme 'v261'.945test # [ 1.753756] systemd[1]: Finished Load Kernel Modules.946test # [ 1.765284] systemd[1]: Starting Apply Kernel Variables...947test # [ 1.773429] systemd[1]: Finished Create System Files and Directories.948second # [ 1.775775] systemd[1]: Starting Virtual Console Setup...949test # [ 1.787166] systemd[1]: Started Rule-based Manager for Device Events and Files.950test # [ 1.792599] systemd[1]: Finished Apply Kernel Variables.951second # [ 1.813064] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.952second # [ 1.814767] systemd[1]: Finished Virtual Console Setup.953test # [ 1.839522] systemd[1]: Starting Virtual Console Setup...954test # [ 1.872393] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.955test # [ 1.874555] systemd[1]: Finished Virtual Console Setup.956second # [ 2.041393] systemd[1]: Finished Coldplug All udev Devices.957second # [ 2.041961] systemd[1]: Reached target System Initialization.958second # [ 2.042438] systemd[1]: Reached target Basic System.959test # [ 2.094833] systemd[1]: Finished Coldplug All udev Devices.960test # [ 2.095404] systemd[1]: Reached target System Initialization.961test # [ 2.095870] systemd[1]: Reached target Basic System.962second # [ 2.139215] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.963second # [ 2.159949] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.964test # [ 2.186190] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.965second # [ 2.208194] systemd[1]: Found device /dev/disk/by-label/nixos.966second # [ 2.209556] systemd[1]: Reached target Initrd Root Device.967second # [ 2.211377] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...968test # [ 2.215953] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.969second # [ 2.255354] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks970test # [ 2.260623] systemd[1]: Found device /dev/disk/by-label/nixos.971test # [ 2.262850] systemd[1]: Reached target Initrd Root Device.972test # [ 2.265139] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...973second # [ 2.269829] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974test # [ 2.309069] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks975test # [ 2.314221] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.976second # [ 2.516147] systemd[1]: Mounting /sysroot...977second # [ 2.549962] EXT4-fs (vda): mounted filesystem 76eb3747-16b5-488f-93cc-511006339cf1 r/w with ordered data mode. Quota mode: none.978second # [ 2.546086] systemd[1]: Mounted /sysroot.979second # [ 2.547594] systemd[1]: Reached target Initrd Root File System.980second # [ 2.550822] systemd[1]: Starting Mountpoints Configured in the Real Root...981second # [ 2.569462] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.982second # [ 2.573366] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...983test # [ 2.578715] systemd[1]: Mounting /sysroot...984second # [ 2.576213] systemd[1]: Reloading...985test # [ 2.610303] EXT4-fs (vda): mounted filesystem e4b92456-3df9-414d-b5aa-ad5a662d1ac1 r/w with ordered data mode. Quota mode: none.986test # [ 2.608197] systemd[1]: Mounted /sysroot.987test # [ 2.610962] systemd[1]: Reached target Initrd Root File System.988test # [ 2.612354] systemd[1]: Starting Mountpoints Configured in the Real Root...989test # [ 2.630434] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.990test # [ 2.635292] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...991test # [ 2.638220] systemd[1]: Reloading...992second # [ 2.737242] systemd[1]: Reloading finished in 162 ms.993second # [ 2.763388] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...994second # [ 2.765568] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...995second # [ 2.770168] systemd[1]: initrd-parse-etc.service: Deactivated successfully.996second # [ 2.772095] systemd[1]: Finished Mountpoints Configured in the Real Root.997second # [ 2.773379] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.998second # [ 2.776134] systemd[1]: Starting Load Kernel Module 9pnet_virtio...999second # [ 2.793051] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1000second # [ 2.793691] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1001test # [ 2.800316] systemd[1]: Reloading finished in 163 ms.1002test # [ 2.827635] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1003test # [ 2.831107] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1004test # [ 2.836346] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1005test # [ 2.843711] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1006test # [ 2.848609] systemd[1]: Finished Mountpoints Configured in the Real Root.1007test # [ 2.849222] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1008test # [ 2.851799] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1009test # [ 2.854220] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1010second # [ 2.979500] (udev-worker)[95]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory1011second # [ 2.983262] (udev-worker)[95]: mtd0ro: /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/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 directory1012second # [ 3.002836] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1013second # [ 3.003697] systemd[1]: Stopped Virtual Console Setup.1014second # [ 3.006141] systemd[1]: Stopping Virtual Console Setup...1015second # [ 3.006769] systemd[1]: Starting Virtual Console Setup...1016second # [ 3.012680] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1017second # [ 3.013578] systemd[1]: Stopped Virtual Console Setup.1018second # [ 3.019759] systemd[1]: Starting Virtual Console Setup...1019test # [ 3.039160] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory1020second # [ 3.039427] systemd-vconsole-setup[142]: Configuration of first virtual console was skipped, ignoring remaining ones.1021second # [ 3.041591] systemd[1]: Finished Virtual Console Setup.1022test # [ 3.044203] (udev-worker)[102]: mtd0ro: /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/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 directory1023test # [ 3.051599] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1024test # [ 3.052338] systemd[1]: Stopped Virtual Console Setup.1025test # [ 3.053180] systemd[1]: Stopping Virtual Console Setup...1026test # [ 3.056165] systemd[1]: Starting Virtual Console Setup...1027test # [ 3.079064] systemd-vconsole-setup[140]: Configuration of first virtual console was skipped, ignoring remaining ones.1028test # [ 3.081310] systemd[1]: Finished Virtual Console Setup.1029second # [ 3.519645] systemd[1]: Mounting /sysroot/nix/.ro-store...1030second # [ 3.528256] systemd[1]: Mounting /sysroot/nix/.rw-store...1031second # [ 3.536240] systemd[1]: Mounting /sysroot/run...1032second # [ 3.544496] systemd[1]: Mounting /sysroot/tmp/shared...1033second # [ 3.564216] systemd[1]: Mounting /sysroot/tmp/xchg...1034test # [ 3.582202] systemd[1]: Mounting /sysroot/nix/.ro-store...1035second # [ 3.580435] systemd[1]: Mounted /sysroot/run.1036second # [ 3.583393] systemd[1]: Mounted /sysroot/nix/.ro-store.1037second # [ 3.589547] systemd[1]: Mounted /sysroot/nix/.rw-store.1038test # [ 3.593726] systemd[1]: Mounting /sysroot/nix/.rw-store...1039test # [ 3.595578] systemd[1]: Mounting /sysroot/run...1040second # [ 3.596229] systemd[1]: Starting rw-sysroot-nix-store.service...1041second # [ 3.598301] systemd[1]: Mounted /sysroot/tmp/shared.1042second # [ 3.602387] systemd[1]: Mounted /sysroot/tmp/xchg.1043test # [ 3.608272] systemd[1]: Mounting /sysroot/tmp/shared...1044second # [ 3.614896] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1045second # [ 3.616188] systemd[1]: Finished rw-sysroot-nix-store.service.1046test # [ 3.624272] systemd[1]: Mounting /sysroot/tmp/xchg...1047test # [ 3.657707] systemd[1]: Mounted /sysroot/nix/.rw-store.1048test # [ 3.668756] systemd[1]: Starting rw-sysroot-nix-store.service...1049test # [ 3.671685] systemd[1]: Mounted /sysroot/nix/.ro-store.1050test # [ 3.678288] systemd[1]: Mounted /sysroot/run.1051test # [ 3.679816] systemd[1]: Mounted /sysroot/tmp/shared.1052test # [ 3.681765] systemd[1]: Mounted /sysroot/tmp/xchg.1053test # [ 3.691648] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1054test # [ 3.692883] systemd[1]: Finished rw-sysroot-nix-store.service.1055test # [ 3.694832] systemd[1]: Mounting /sysroot/nix/store...1056test # [ 3.720615] systemd[1]: Mounted /sysroot/nix/store.1057test # [ 3.721744] systemd[1]: Reached target Initrd File Systems.1058test # [ 3.723347] systemd[1]: Starting Find NixOS closure...1059test # [ 3.731470] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1060test # [ 3.749254] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1061test # [ 3.762409] systemd[1]: Finished Find NixOS closure.1062test # [ 3.763563] systemd[1]: Reached target Initrd Default Target.1063test # [ 3.765496] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1064test # [ 3.788338] systemd[1]: initrd-cleanup.service: Deactivated successfully.1065test # [ 3.789300] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1066test # [ 3.791807] systemd[1]: Stopped target Initrd Default Target.1067test # [ 3.792688] systemd[1]: Stopped target Basic System.1068test # [ 3.793168] systemd[1]: Stopped target Initrd Root Device.1069test # [ 3.795820] systemd[1]: Stopped target Path Units.1070test # [ 3.796558] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1071test # [ 3.797320] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1072test # [ 3.798852] systemd[1]: Stopped target Slice Units.1073test # [ 3.800160] systemd[1]: Stopped target Socket Units.1074test # [ 3.803976] systemd[1]: Stopped target System Initialization.1075test # [ 3.804624] systemd[1]: Stopped target Swaps.1076test # [ 3.805111] systemd[1]: Stopped target Timer Units.1077test # [ 3.805525] systemd[1]: dbus.socket: Deactivated successfully.1078test # [ 3.806055] systemd[1]: Closed D-Bus System Message Bus Socket.1079test # [ 3.806567] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1080test # [ 3.807284] systemd[1]: Stopped Find NixOS closure.1081test # [ 3.807782] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1082test # [ 3.811520] systemd[1]: Starting rw-sysroot-nix-store.service...1083test # [ 3.814603] systemd[1]: systemd-sysctl.service: Deactivated successfully.1084test # [ 3.820149] systemd[1]: Stopped Apply Kernel Variables.1085test # [ 3.821158] systemd[1]: systemd-modules-load.service: Deactivated successfully.1086test # [ 3.825443] systemd[1]: Stopped Load Kernel Modules.1087test # [ 3.827237] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1088test # [ 3.830259] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1089test # [ 3.831622] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1090test # [ 3.832525] systemd[1]: Stopped Create System Files and Directories.1091test # [ 3.833074] systemd[1]: Stopped target Local File Systems.1092test # [ 3.833543] systemd[1]: Stopped target Preparation for Local File Systems.1093test # [ 3.834092] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1094test # [ 3.834691] systemd[1]: Stopped Coldplug All udev Devices.1095test # [ 3.835191] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1096test # [ 3.835815] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1097test # [ 3.839425] systemd[1]: Stopped Virtual Console Setup.1098test # [ 3.843214] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1099test # [ 3.843846] systemd[1]: Finished rw-sysroot-nix-store.service.1100test # [ 3.844677] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1101test # [ 3.845272] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1102test # [ 3.845763] systemd[1]: systemd-udevd.service: Deactivated successfully.1103test # [ 3.846312] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1104test # [ 3.846905] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1105test # [ 3.847495] systemd[1]: Closed udev Control Socket.1106test # [ 3.847901] systemd[1]: Starting Cleanup udev Database...1107test # [ 3.848943] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1108test # [ 3.849598] systemd[1]: Stopped Create Static Device Nodes in /dev.1109test # [ 3.850123] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1110test # [ 3.850802] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1111test # [ 3.851380] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1112test # [ 3.851946] systemd[1]: Stopped Create List of Static Device Nodes.1113test # [ 3.871816] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1114test # [ 3.874002] systemd[1]: Finished Cleanup udev Database.1115test # [ 3.874481] systemd[1]: Reached target Switch Root.1116test # [ 3.875163] systemd[1]: Starting NixOS Activation...1117test # [ 4.069411] initrd-nixos-activation-start[179]: booting system configuration /nix/store/1yld3c5hx0gbc576caic5a44pr6ffiwf-nixos-system-test-test1118test # [ 4.131997] initrd-nixos-activation-start[179]: running activation script...1119second # [ 4.517693] systemd[1]: Mounting /sysroot/nix/store...1120second # [ 4.561443] systemd[1]: Mounted /sysroot/nix/store.1121second # [ 4.563191] systemd[1]: Reached target Initrd File Systems.1122test # [ 4.568752] initrd-nixos-activation-start[202]: setting up /etc...1123second # [ 4.566408] systemd[1]: Starting Find NixOS closure...1124second # [ 4.573523] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1125second # [ 4.618117] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1126second # [ 4.623553] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1127second # [ 4.640666] systemd[1]: Finished Find NixOS closure.1128second # [ 4.643178] systemd[1]: Reached target Initrd Default Target.1129second # [ 4.648240] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1130second # [ 4.677108] systemd[1]: Stopped target Initrd Default Target.1131second # [ 4.678810] systemd[1]: Stopped target Basic System.1132second # [ 4.680274] systemd[1]: Stopped target Initrd Root Device.1133second # [ 4.681567] systemd[1]: Stopped target Path Units.1134second # [ 4.684266] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1135second # [ 4.685520] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1136second # [ 4.688213] systemd[1]: Stopped target Slice Units.1137second # [ 4.689815] systemd[1]: Stopped target Socket Units.1138second # [ 4.696110] systemd[1]: Stopped target System Initialization.1139second # [ 4.696857] systemd[1]: Stopped target Swaps.1140second # [ 4.697450] systemd[1]: Stopped target Timer Units.1141second # [ 4.698053] systemd[1]: dbus.socket: Deactivated successfully.1142second # [ 4.698742] systemd[1]: Closed D-Bus System Message Bus Socket.1143second # [ 4.699444] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1144second # [ 4.700919] systemd[1]: Stopped Find NixOS closure.1145second # [ 4.701544] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1146second # [ 4.710842] systemd[1]: Starting rw-sysroot-nix-store.service...1147second # [ 4.711883] systemd[1]: systemd-sysctl.service: Deactivated successfully.1148second # [ 4.713843] systemd[1]: Stopped Apply Kernel Variables.1149second # [ 4.714495] systemd[1]: systemd-modules-load.service: Deactivated successfully.1150second # [ 4.715354] systemd[1]: Stopped Load Kernel Modules.1151second # [ 4.715958] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1152second # [ 4.725353] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1153second # [ 4.726321] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1154second # [ 4.727367] systemd[1]: Stopped Create System Files and Directories.1155second # [ 4.732965] systemd[1]: Stopped target Local File Systems.1156second # [ 4.733633] systemd[1]: Stopped target Preparation for Local File Systems.1157second # [ 4.735323] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1158second # [ 4.738199] systemd[1]: Stopped Coldplug All udev Devices.1159second # [ 4.739500] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1160second # [ 4.745279] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1161second # [ 4.749255] systemd[1]: Stopped Virtual Console Setup.1162second # [ 4.749929] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1163second # [ 4.752185] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1164second # [ 4.754784] systemd[1]: initrd-cleanup.service: Deactivated successfully.1165second # [ 4.755856] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1166second # [ 4.757005] systemd[1]: systemd-udevd.service: Deactivated successfully.1167second # [ 4.760338] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1168second # [ 4.761029] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1169second # [ 4.761648] systemd[1]: Closed udev Control Socket.1170second # [ 4.762917] systemd[1]: Starting Cleanup udev Database...1171second # [ 4.763489] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1172second # [ 4.766000] systemd[1]: Stopped Create Static Device Nodes in /dev.1173second # [ 4.766587] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1174second # [ 4.767390] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1175second # [ 4.768154] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1176second # [ 4.768796] systemd[1]: Stopped Create List of Static Device Nodes.1177second # [ 4.769362] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1178second # [ 4.769993] systemd[1]: Finished rw-sysroot-nix-store.service.1179second # [ 4.789345] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1180second # [ 4.791568] systemd[1]: Finished Cleanup udev Database.1181second # [ 4.792868] systemd[1]: Reached target Switch Root.1182second # [ 4.797029] systemd[1]: Starting NixOS Activation...1183test # [ 4.841250] initrd-nixos-activation-start[202]: setting up age secrets...1184second # [ 4.983912] initrd-nixos-activation-start[181]: booting system configuration /nix/store/sig1b3m5b75sija5blqd6yz3zhfcg0iw-nixos-system-second-test1185second # [ 5.052526] initrd-nixos-activation-start[181]: running activation script...1186test # [ 5.136274] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1187test # [ 5.137940] systemd[1]: Finished NixOS Activation.1188test # [ 5.138665] systemd[1]: Starting Switch Root...1189test # [ 5.152635] systemd[1]: Switching root.1190test # [ 5.320698] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1191second # [ 5.519502] initrd-nixos-activation-start[204]: setting up /etc...1192second # [ 5.788154] initrd-nixos-activation-start[204]: setting up age secrets...1193test # [ 5.911629] systemd[1]: systemd 261 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)1194test # [ 5.918157] systemd[1]: Detected virtualization qemu.1195test # [ 5.919780] systemd[1]: Detected architecture arm64.1196test # [ 5.921444] systemd[1]: Detected first boot.1197test # [ 5.927068] systemd[1]: Initializing machine ID from random generator.1198second # [ 6.014929] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1199second # [ 6.016709] systemd[1]: Finished NixOS Activation.1200second # [ 6.017410] systemd[1]: Starting Switch Root...1201second # [ 6.033235] systemd[1]: Switching root.1202second # [ 6.215937] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1203test # [ 6.218763] systemd[1]: bpf-restrict-fs: LSM BPF program attached1204test # [ 6.403539] systemd[1]: Applying preset policy.1205second # [ 6.819033] systemd[1]: systemd 261 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)1206second # [ 6.822589] systemd[1]: Detected virtualization qemu.1207second # [ 6.823689] systemd[1]: Detected architecture arm64.1208second # [ 6.824800] systemd[1]: Detected first boot.1209second # [ 6.829288] systemd[1]: Initializing machine ID from random generator.1210test # [ 6.980098] systemd[1]: Populated /etc with preset unit settings.1211second # [ 7.093575] systemd[1]: bpf-restrict-fs: LSM BPF program attached1212second # [ 7.273066] systemd[1]: Applying preset policy.1213test # [ 7.547415] systemd[1]: initrd-switch-root.service: Deactivated successfully.1214test # [ 7.548257] systemd[1]: Stopped initrd-switch-root.service.1215test # [ 7.550398] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1216test # [ 7.552863] systemd[1]: Created slice Slice /system/getty.1217test # [ 7.554094] systemd[1]: Created slice User and Session Slice.1218test # [ 7.554843] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1219test # [ 7.554940] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1220test # [ 7.554989] systemd[1]: Expecting device /dev/hvc0...1221test # [ 7.555004] systemd[1]: Expecting device /dev/ttyAMA0...1222test # [ 7.555034] systemd[1]: Reached target Local Encrypted Volumes.1223test # [ 7.555065] systemd[1]: Stopped target initrd-fs.target.1224test # [ 7.555572] systemd[1]: Stopped target initrd-root-fs.target.1225test # [ 7.555885] systemd[1]: Stopped target initrd-switch-root.target.1226test # [ 7.556181] systemd[1]: Reached target Virtual Machines and Containers.1227test # [ 7.556490] systemd[1]: Reached target Path Units.1228test # [ 7.556772] systemd[1]: Reached target Remote File Systems.1229test # [ 7.557066] systemd[1]: Reached target Slice Units.1230test # [ 7.557342] systemd[1]: Reached target Swaps.1231test # [ 7.566194] systemd[1]: Listening on Query the User Interactively for a Password.1232test # [ 7.570873] systemd[1]: Listening on Process Core Dump Socket.1233test # [ 7.574570] systemd[1]: Listening on Credential Encryption/Decryption.1234test # [ 7.578753] systemd[1]: Listening on Factory Reset Management.1235test # [ 7.579754] systemd[1]: Listening on Hostname Service Socket.1236test # [ 7.584654] systemd[1]: Starting Journal Log Access Socket...1237test # [ 7.586764] systemd[1]: Listening on Journal Audit Socket.1238test # [ 7.590911] systemd[1]: Listening on Console Output Muting Service Socket.1239test # [ 7.593227] systemd[1]: Listening on Network Management Metrics Varlink Socket.1240test # [ 7.595186] systemd[1]: Listening on Network Management Varlink Socket.1241test # [ 7.596283] systemd[1]: Listening on Network Management Netlink Socket.1242test # [ 7.597462] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1243test # [ 7.598628] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1244test # [ 7.600330] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1245test # [ 7.610570] systemd[1]: Listening on Disk Repartitioning Service Socket.1246test # [ 7.611079] systemd[1]: Listening on Resolve Monitor Varlink Socket.1247test # [ 7.611436] systemd[1]: Listening on Resolve Service Varlink Socket.1248test # [ 7.611774] systemd[1]: Listening on udev Control Socket.1249test # [ 7.612121] systemd[1]: Listening on udev Varlink Socket.1250test # [ 7.617999] systemd[1]: Mounting Huge Pages File System...1251test # [ 7.621675] systemd[1]: Mounting POSIX Message Queue File System...1252test # [ 7.627044] systemd[1]: Mounting Kernel Debug File System...1253test # [ 7.635501] systemd[1]: Mounting Kernel Trace File System...1254test # [ 7.647254] systemd[1]: Starting Create List of Static Device Nodes...1255test # [ 7.657137] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1256test # [ 7.658857] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1257test # [ 7.671525] systemd[1]: Mounting Kernel Configuration File System...1258test # [ 7.674187] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1259test # [ 7.678078] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1260test # [ 7.691550] systemd[1]: Starting Load Kernel Module fuse...1261test # [ 7.693204] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671262test # [ 7.748737] systemd[1]: Starting Journal Service...1263test # [ 7.779634] systemd[1]: Starting Load Kernel Modules...1264test # [ 7.809626] fuse: init (API version 7.45)1265second # [ 7.812951] systemd[1]: Populated /etc with preset unit settings.1266test # [ 7.814397] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1267test # [ 7.831179] systemd[1]: Starting Remount Root and Kernel File Systems...1268test # [ 7.835281] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1269test # [ 7.850086] systemd[1]: Starting Coldplug All udev Devices...1270test # [ 7.880892] systemd[1]: Listening on Journal Log Access Socket.1271test # [ 7.884515] systemd-journald[324]: Collecting audit messages is enabled.1272test # [ 7.886679] systemd[1]: Mounted Huge Pages File System.1273test # [ 7.887195] systemd[1]: Mounted POSIX Message Queue File System.1274test # [ 7.887634] systemd[1]: Mounted Kernel Debug File System.1275test # [ 7.888021] systemd[1]: Mounted Kernel Trace File System.1276test # [ 7.895962] systemd[1]: Finished Create List of Static Device Nodes.1277test # [ 7.899421] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1278test # [ 7.903103] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1279test # [ 7.898869] systemd[1]: Queued start job for default target Multi-User System.1280test # [ 7.900355] systemd[1]: systemd-journald.service: Deactivated successfully.1281test # [ 7.907805] systemd[1]: Started Journal Service.1282test # [ 7.907360] systemd-modules-load[325]: Module 'atkbd' is built in1283test # [ 7.910588] systemd-modules-load[325]: Module 'ctr' is built in1284test # [ 7.914292] systemd-modules-load[325]: Module 'loop' is built in1285test # [ 7.917747] systemd[1]: Mounted Kernel Configuration File System.1286test # [ 7.922329] systemd[1]: modprobe@fuse.service: Deactivated successfully.1287test # [ 7.924314] systemd[1]: Finished Load Kernel Module fuse.1288test # [ 7.927201] systemd[1]: Finished Load Kernel Modules.1289test # [ 7.933827] systemd[1]: Mounting FUSE Control File System...1290test # [ 7.938316] systemd[1]: Starting Firewall...1291test # [ 7.941807] systemd[1]: Starting Apply Kernel Variables...1292test # [ 7.974337] EXT4-fs (vda): re-mounted e4b92456-3df9-414d-b5aa-ad5a662d1ac1.1293test # [ 7.971684] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1294test # [ 7.980971] systemd-oomd[326]: No swap; memory pressure usage will be degraded1295test # [ 8.009854] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1296test # [ 8.016086] systemd[1]: Finished Remount Root and Kernel File Systems.1297test # [ 8.037529] systemd[1]: Listening on Disk Image Download Service Socket.1298test # [ 8.046991] systemd[1]: Starting Flush Journal to Persistent Storage...1299test # [ 8.053766] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1300test # [ 8.057359] systemd[1]: Starting Load/Save OS Random Seed...1301test # [ 8.058842] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1302test # [ 8.114733] systemd[1]: Mounted FUSE Control File System.1303test # [ 8.122123] systemd[1]: Finished Apply Kernel Variables.1304test # [ 8.130226] systemd[1]: Starting Network Name Resolution...1305test # [ 8.149242] systemd-journald[324]: Received client request to flush runtime journal.1306test # [ 8.221081] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1307test # [ 8.228762] systemd[1]: Starting Create Static Device Nodes in /dev...1308test # [ 8.240292] systemd[1]: Finished Load/Save OS Random Seed.1309test # [ 8.240889] systemd[1]: Reached target First Boot Complete.1310test # [ 8.241383] systemd[1]: Finished Flush Journal to Persistent Storage.1311test # [ 8.305577] systemd-resolved[353]: Positive Trust Anchors:1312test # [ 8.308839] systemd-resolved[353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1313test # [ 8.312579] systemd-resolved[353]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161314test # [ 8.317917] systemd-resolved[353]: 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 test1315test # [ 8.345311] systemd[1]: Finished Create Static Device Nodes in /dev.1316test # [ 8.346194] systemd[1]: Reached target Preparation for Local File Systems.1317test # [ 8.347903] systemd[1]: Starting Rule-based Manager for Device Events and Files...1318second # [ 8.374363] systemd[1]: initrd-switch-root.service: Deactivated successfully.1319second # [ 8.375232] systemd[1]: Stopped initrd-switch-root.service.1320second # [ 8.377380] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1321second # [ 8.380233] systemd[1]: Created slice Slice /system/getty.1322second # [ 8.381181] systemd[1]: Created slice User and Session Slice.1323second # [ 8.381780] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1324second # [ 8.382143] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1325second # [ 8.382445] systemd[1]: Expecting device /dev/hvc0...1326second # [ 8.382719] systemd[1]: Expecting device /dev/ttyAMA0...1327second # [ 8.382995] systemd[1]: Reached target Local Encrypted Volumes.1328second # [ 8.383272] systemd[1]: Stopped target initrd-fs.target.1329second # [ 8.383545] systemd[1]: Stopped target initrd-root-fs.target.1330second # [ 8.383830] systemd[1]: Stopped target initrd-switch-root.target.1331second # [ 8.384110] systemd[1]: Reached target Virtual Machines and Containers.1332second # [ 8.384415] systemd[1]: Reached target Path Units.1333second # [ 8.384691] systemd[1]: Reached target Remote File Systems.1334second # [ 8.384954] systemd[1]: Reached target Slice Units.1335second # [ 8.390872] systemd[1]: Reached target Swaps.1336second # [ 8.394725] systemd[1]: Listening on Query the User Interactively for a Password.1337second # [ 8.399413] systemd[1]: Listening on Process Core Dump Socket.1338second # [ 8.403199] systemd[1]: Listening on Credential Encryption/Decryption.1339second # [ 8.406932] systemd[1]: Listening on Factory Reset Management.1340second # [ 8.408564] systemd[1]: Listening on Hostname Service Socket.1341second # [ 8.413681] systemd[1]: Starting Journal Log Access Socket...1342second # [ 8.415419] systemd[1]: Listening on Journal Audit Socket.1343second # [ 8.419265] systemd[1]: Listening on Console Output Muting Service Socket.1344second # [ 8.420908] systemd[1]: Listening on Network Management Metrics Varlink Socket.1345second # [ 8.422617] systemd[1]: Listening on Network Management Varlink Socket.1346second # [ 8.424430] systemd[1]: Listening on Network Management Netlink Socket.1347second # [ 8.426229] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1348second # [ 8.427882] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1349second # [ 8.430225] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1350second # [ 8.440538] systemd[1]: Listening on Disk Repartitioning Service Socket.1351test # [ 8.436847] systemd-udevd[365]: Using default interface naming scheme 'v261'.1352second # [ 8.442276] systemd[1]: Listening on Resolve Monitor Varlink Socket.1353second # [ 8.443823] systemd[1]: Listening on Resolve Service Varlink Socket.1354second # [ 8.445428] systemd[1]: Listening on udev Control Socket.1355second # [ 8.447232] systemd[1]: Listening on udev Varlink Socket.1356second # [ 8.451145] systemd[1]: Mounting Huge Pages File System...1357second # [ 8.455069] systemd[1]: Mounting POSIX Message Queue File System...1358second # [ 8.465824] systemd[1]: Mounting Kernel Debug File System...1359second # [ 8.475348] systemd[1]: Mounting Kernel Trace File System...1360second # [ 8.481769] systemd[1]: Starting Create List of Static Device Nodes...1361second # [ 8.494865] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1362second # [ 8.496345] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1363second # [ 8.505400] systemd[1]: Mounting Kernel Configuration File System...1364second # [ 8.507256] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1365second # [ 8.510662] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1366second # [ 8.523487] systemd[1]: Starting Load Kernel Module fuse...1367second # [ 8.526731] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671368test # [ 8.551723] systemd[1]: Mounting /run/wrappers...1369second # [ 8.567556] systemd[1]: Starting Journal Service...1370test # [ 8.579652] systemd-resolved[353]: Using system hostname 'test'.1371second # [ 8.591609] systemd[1]: Starting Load Kernel Modules...1372test # [ 8.590522] systemd[1]: Started Network Name Resolution.1373second # [ 8.612255] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1374test # [ 8.619817] systemd[1]: Mounted /run/wrappers.1375test # [ 8.621359] systemd[1]: Reached target Local File Systems.1376test # [ 8.626586] systemd[1]: Listening on Boot Loader Control Service Socket.1377second # [ 8.634200] fuse: init (API version 7.45)1378second # [ 8.634993] systemd[1]: Starting Remount Root and Kernel File Systems...1379test # [ 8.631919] systemd[1]: Starting register-nix-paths.service...1380second # [ 8.637427] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1381test # [ 8.635505] systemd[1]: Starting Create SUID/SGID Wrappers...1382test # [ 8.639682] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1383second # [ 8.659247] systemd[1]: Starting Coldplug All udev Devices...1384test # [ 8.668180] systemd[1]: Starting Save Transient machine-id to Disk...1385second # [ 8.674602] systemd[1]: Listening on Journal Log Access Socket.1386test # [ 8.675124] systemd[1]: Starting Create System Files and Directories...1387second # [ 8.694982] systemd[1]: Mounted Huge Pages File System.1388second # [ 8.697553] systemd[1]: Mounted POSIX Message Queue File System.1389second # [ 8.699666] systemd[1]: Mounted Kernel Debug File System.1390second # [ 8.704596] systemd[1]: Mounted Kernel Trace File System.1391second # [ 8.708097] systemd[1]: Finished Create List of Static Device Nodes.1392second # [ 8.711089] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1393second # [ 8.715013] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1394second # [ 8.719509] systemd[1]: Mounted Kernel Configuration File System.1395second # [ 8.720097] systemd[1]: modprobe@fuse.service: Deactivated successfully.1396second # [ 8.722158] systemd[1]: Finished Load Kernel Module fuse.1397second # [ 8.723599] systemd[1]: Finished Load Kernel Modules.1398second # [ 8.733941] systemd[1]: Mounting FUSE Control File System...1399second # [ 8.735641] systemd-journald[304]: Collecting audit messages is enabled.1400second # [ 8.740957] systemd[1]: Queued start job for default target Multi-User System.1401second # [ 8.751143] EXT4-fs (vda): re-mounted 76eb3747-16b5-488f-93cc-511006339cf1.1402second # [ 8.752537] systemd[1]: Starting Firewall...1403second # [ 8.745385] systemd[1]: systemd-journald.service: Deactivated successfully.1404second # [ 8.749439] systemd-modules-load[305]: Module 'atkbd' is built in1405second # [ 8.751813] systemd-modules-load[305]: Module 'ctr' is built in1406second # [ 8.757203] systemd-modules-load[305]: Module 'loop' is built in1407second # [ 8.769511] systemd[1]: Starting Apply Kernel Variables...1408test # [ 8.770717] systemd[1]: Started Rule-based Manager for Device Events and Files.1409second # [ 8.789422] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1410second # [ 8.802035] systemd[1]: Started Journal Service.1411test # [ 8.804518] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1412test # [ 8.808645] systemd[1]: Finished Save Transient machine-id to Disk.1413second # [ 8.808900] systemd-oomd[306]: No swap; memory pressure usage will be degraded1414second # [ 8.819615] systemd[1]: Finished Remount Root and Kernel File Systems.1415second # [ 8.825866] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1416second # [ 8.863255] systemd[1]: Listening on Disk Image Download Service Socket.1417second # [ 8.874786] systemd[1]: Starting Flush Journal to Persistent Storage...1418second # [ 8.878151] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1419test # [ 8.886265] systemd[1]: Finished Create System Files and Directories.1420second # [ 8.883422] systemd[1]: Starting Load/Save OS Random Seed...1421second # [ 8.889642] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1422test # [ 8.910471] systemd[1]: Starting Rebuild Journal Catalog...1423second # [ 8.906855] systemd[1]: Mounted FUSE Control File System.1424test # [ 8.913278] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1425second # [ 8.938211] systemd[1]: Finished Apply Kernel Variables.1426second # [ 8.950660] systemd[1]: Starting Network Name Resolution...1427second # [ 8.974263] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1428second # [ 8.987667] systemd-journald[304]: Received client request to flush runtime journal.1429test # [ 9.048913] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1430second # [ 9.045759] systemd[1]: Finished Load/Save OS Random Seed.1431second # [ 9.046601] systemd[1]: Reached target First Boot Complete.1432second # [ 9.058076] systemd[1]: Starting Create Static Device Nodes in /dev...1433second # [ 9.060838] systemd[1]: Finished Flush Journal to Persistent Storage.1434second # [ 9.120050] systemd[1]: Finished Create Static Device Nodes in /dev.1435second # [ 9.120704] systemd[1]: Reached target Preparation for Local File Systems.1436test # [ 9.128744] systemd[1]: Finished Rebuild Journal Catalog.1437second # [ 9.126189] systemd[1]: Starting Rule-based Manager for Device Events and Files...1438test # [ 9.136760] systemd[1]: Starting Update is Completed...1439second # [ 9.149529] systemd-resolved[333]: Positive Trust Anchors:1440second # [ 9.153356] systemd-resolved[333]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1441second # [ 9.154696] systemd-resolved[333]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161442second # [ 9.158769] 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 test1443test # [ 9.180292] systemd[1]: Finished Coldplug All udev Devices.1444test # [ 9.206469] systemd[1]: Finished Update is Completed.1445second # [ 9.261395] systemd-udevd[346]: Using default interface naming scheme 'v261'.1446test # [ 9.366161] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1447second # [ 9.380946] systemd[1]: Mounting /run/wrappers...1448second # [ 9.404306] systemd-resolved[333]: Using system hostname 'second'.1449second # [ 9.414326] systemd[1]: Started Network Name Resolution.1450second # [ 9.442215] systemd[1]: Mounted /run/wrappers.1451second # [ 9.444705] systemd[1]: Reached target Local File Systems.1452second # [ 9.449196] systemd[1]: Listening on Boot Loader Control Service Socket.1453second # [ 9.456126] systemd[1]: Starting register-nix-paths.service...1454second # [ 9.459634] systemd[1]: Starting Create SUID/SGID Wrappers...1455second # [ 9.464184] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1456second # [ 9.474710] systemd[1]: Starting Save Transient machine-id to Disk...1457second # [ 9.492377] systemd[1]: Starting Create System Files and Directories...1458second # [ 9.581208] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1459second # [ 9.590711] systemd[1]: Finished Save Transient machine-id to Disk.1460test # [ 9.598065] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1461test # [ 9.599803] systemd[1]: Finished Create SUID/SGID Wrappers.1462second # [ 9.604288] systemd[1]: Started Rule-based Manager for Device Events and Files.1463test # [ 9.638220] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1464second # [ 9.685552] systemd[1]: Finished Create System Files and Directories.1465second # [ 9.695574] systemd[1]: Starting Rebuild Journal Catalog...1466second # [ 9.698852] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1467test # [ 9.717261] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1468second # [ 9.807778] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1469second # [ 9.827871] systemd[1]: Finished Coldplug All udev Devices.1470second # [ 9.861236] systemd[1]: Finished Rebuild Journal Catalog.1471second # [ 9.868341] systemd[1]: Starting Update is Completed...1472test # [ 9.909655] systemd[1]: Finished register-nix-paths.service.1473test # [ 9.911037] systemd[1]: Reached target System Initialization.1474test # [ 9.914967] systemd[1]: Started Discard unused filesystem blocks once a week.1475test # [ 9.917301] systemd[1]: Started Daily Cleanup of Temporary Directories.1476test # [ 9.921234] systemd[1]: Reached target Timer Units.1477test # [ 9.922293] systemd[1]: Listening on D-Bus System Message Bus Socket.1478test # [ 9.924235] systemd[1]: Listening on Nix Daemon Socket.1479test # [ 9.928841] systemd[1]: Listening on Authorization Manager Agent Helper.1480test # [ 9.930865] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1481test # [ 9.933569] systemd[1]: Reached target Socket Units.1482test # [ 9.936207] systemd[1]: Starting D-Bus System Message Bus...1483second # [ 9.934379] systemd[1]: Finished Update is Completed.1484test # [ 9.957215] systemd[1]: Finished Firewall.1485test # [ 9.958993] systemd[1]: Reached target Preparation for Network.1486test # [ 9.961802] systemd[1]: Listening on Network Management Resolve Hook Socket.1487test # [ 9.966029] systemd[1]: Starting Network Management...1488test # [ 10.012084] (udev-worker)[410]: Network interface NamePolicy= disabled on kernel command line.1489test # [ 10.027254] (udev-worker)[405]: Network interface NamePolicy= disabled on kernel command line.1490second # [ 10.055824] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1491test # [ 10.136053] dbus-broker-launch[546]: Looking up NSS user entry for 'systemd-timesync'...1492test # [ 10.151659] dbus-broker-launch[546]: NSS returned no entry for 'systemd-timesync'1493test # [ 10.152515] dbus-broker-launch[546]: Invalid user-name in /nix/store/cn4zwjv26idrz8m4kyyvim8cqky73zxq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1494test # [ 10.200965] cfg80211: Loading compiled-in X.509 certificates for regulatory database1495test # [ 10.224194] dbus-broker-launch[546]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/xgs4xjlwr0cqq20q5pf50b6rh69dp7yp-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1496test # [ 10.226497] dbus-broker-launch[546]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/xgs4xjlwr0cqq20q5pf50b6rh69dp7yp-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1497test # [ 10.230294] dbus-broker-launch[546]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/q3gr23jczqdkab4ynasfyv5pk0907sly-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'[ 10.241218] mousedev: PS/2 mouse device common for all mice1498test # 1499test # [ 10.237148] dbus-broker-launch[546]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/50h0k21qqr8489rim5ww9gp74lvl1i34-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1500test # [ 10.250978] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1501test # [ 10.251525] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1502test # [ 10.249514] systemd[1]: Started D-Bus System Message Bus.1503test # [ 10.298727] dbus-broker-launch[546]: Ready1504test # [ 10.299544] systemd[1]: Reached target Basic System.1505test # [ 10.303266] systemd[1]: Starting Network Manager Script Dispatcher Service...1506test # [ 10.306224] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1507test # [ 10.317891] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1508second # [ 10.318161] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1509test # [ 10.328064] systemd-networkd[548]: /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.1510test # [ 10.333352] systemd-networkd[548]: /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.1511test # [ 10.340223] systemd-networkd[548]: lo: Link UP1512test # [ 10.340647] systemd-networkd[548]: lo: Gained carrier1513second # [ 10.347243] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1514test # [ 10.352413] systemd-networkd[548]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1515second # [ 10.350358] systemd[1]: Finished Create SUID/SGID Wrappers.1516test # [ 10.361043] systemd-networkd[548]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1517test # [ 10.365327] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1518second # [ 10.364386] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1519test # [ 10.372587] systemd-networkd[548]: eth1: Link UP1520test # [ 10.373181] systemd-networkd[548]: eth1: Gained carrier1521test # [ 10.373746] systemd-networkd[548]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1522test # [ 10.380347] systemd[1]: Starting Network Manager...1523test # [ 10.401582] systemd[1]: Started backdoor.service.1524test # [ 10.435829] systemd[1]: Starting Import lastlog data into lastlog2 database...1525test # [ 10.455887] bfrwvyg79my69yafr053q6bzx4lix24v-wifi-secrets[559]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1526test # [ 10.468867] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1527test # [ 10.502562] systemd[1]: Starting Post-Boot Actions...1528test # [ 10.508780] 6zad8qjsgcihj3d5ws39j6s93s5yrjm7-wifi-secrets[560]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1529test # [ 10.535405] systemd[1]: Started Reset console on configuration changes.1530test # [ 10.541465] systemd[1]: Started Network Management.1531test # [ 10.548380] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1532test # [ 10.558374] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1533test # [ 10.568386] vx4skfrgyqk0f8j1mp5lv936zif6nn10-wifi-secrets[561]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1534test # [ 10.581057] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1535test # [ 10.587520] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1536test # [ 10.604686] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1537test # [ 10.610013] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1538test # connecting to host...1539second # [ 10.668209] (udev-worker)[445]: Network interface NamePolicy= disabled on kernel command line.1540second # [ 10.672584] (udev-worker)[442]: Network interface NamePolicy= disabled on kernel command line.1541test # [ 10.681756] systemd[1]: Finished Post-Boot Actions.1542test: Guest shell says: b'Spawning backdoor root shell...\n'1543second # [ 10.706904] systemd[1]: Finished Firewall.1544test # [ 10.711019] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1545second # [ 10.707745] systemd[1]: Reached target Preparation for Network.1546test # [ 10.724886] nsncd[566]: Jul 27 00:15:15.547 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1547test: connected to guest root shell1548second # [ 10.710058] systemd[1]: Listening on Network Management Resolve Hook Socket.1549test: (connecting took 11.12 seconds)1550test # [ 10.731960] systemd[1]: Started Name Service Cache Daemon (nsncd).1551second # [ 10.716415] systemd[1]: Starting Network Management...1552test # [ 10.740640] systemd[1]: Finished Import lastlog data into lastlog2 database.1553second # [ 10.717712] systemd[1]: Finished register-nix-paths.service.1554test # [ 10.752318] systemd[1]: Reached target Host and Network Name Lookups.1555second # [ 10.718932] systemd[1]: Reached target System Initialization.1556test # [ 10.752988] systemd[1]: Reached target User and Group Name Lookups.1557second # [ 10.720491] systemd[1]: Started Discard unused filesystem blocks once a week.1558test: (finished: waiting for the VM to finish booting, in 11.12 seconds)1559second # [ 10.724997] systemd[1]: Started Daily Cleanup of Temporary Directories.1560second # [ 10.725553] systemd[1]: Reached target Timer Units.1561second # [ 10.726843] systemd[1]: Listening on D-Bus System Message Bus Socket.1562second # [ 10.727405] systemd[1]: Listening on Nix Daemon Socket.1563second # [ 10.739322] systemd[1]: Listening on Authorization Manager Agent Helper.1564second # [ 10.742161] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1565second # [ 10.742992] systemd[1]: Reached target Socket Units.1566second # [ 10.746325] systemd[1]: Starting D-Bus System Message Bus...1567test # [ 10.761843] systemd[1]: Starting User Login Management...1568test # [ 10.765324] systemd[1]: Started Network Manager Script Dispatcher Service.1569test # [ 10.837958] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1570second # [ 10.854986] mousedev: PS/2 mouse device common for all mice1571second # [ 10.909175] dbus-broker-launch[530]: Looking up NSS user entry for 'systemd-timesync'...1572test # [ 10.927032] systemd[1]: Starting Hostname Service...1573test # [ 10.932486] systemd-logind[607]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1574test # [ 10.933291] systemd-logind[607]: Watching system buttons on /dev/input/event0 (gpio-keys)1575test # [ 10.933943] systemd-logind[607]: New seat seat0.1576second # [ 10.941848] cfg80211: Loading compiled-in X.509 certificates for regulatory database1577test # [ 10.942173] systemd[1]: Started User Login Management.1578second # [ 10.938939] dbus-broker-launch[530]: NSS returned no entry for 'systemd-timesync'1579second # [ 10.939740] dbus-broker-launch[530]: Invalid user-name in /nix/store/cn4zwjv26idrz8m4kyyvim8cqky73zxq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1580test # [ 10.952731] systemd[1]: Starting linger-users.service...1581second # [ 10.983341] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1582second # [ 10.983848] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1583second # [ 10.996562] dbus-broker-launch[530]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/xgs4xjlwr0cqq20q5pf50b6rh69dp7yp-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1584second # [ 10.998984] dbus-broker-launch[530]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/xgs4xjlwr0cqq20q5pf50b6rh69dp7yp-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1585second # [ 11.003028] dbus-broker-launch[530]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/q3gr23jczqdkab4ynasfyv5pk0907sly-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1586second # [ 11.006789] dbus-broker-launch[530]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/50h0k21qqr8489rim5ww9gp74lvl1i34-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1587second # [ 11.015336] systemd[1]: Started D-Bus System Message Bus.1588test # [ 11.034416] systemd[1]: linger-users.service: Deactivated successfully.1589test # [ 11.040687] systemd[1]: Finished linger-users.service.1590second # [ 11.049167] systemd-networkd[528]: /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.1591test # [ 11.057466] systemd[1]: Started Hostname Service.1592second # [ 11.056050] systemd-networkd[528]: /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.1593second # [ 11.064834] systemd-networkd[528]: lo: Link UP1594second # [ 11.065461] systemd-networkd[528]: lo: Gained carrier1595second # [ 11.066870] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1596second # [ 11.072793] systemd-networkd[528]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1597second # [ 11.076392] dbus-broker-launch[530]: Ready1598second # [ 11.080200] systemd[1]: Started Network Management.1599second # [ 11.081783] systemd[1]: Reached target Basic System.1600second # [ 11.084111] systemd-networkd[528]: eth1: Link UP1601second # [ 11.085712] systemd-networkd[528]: eth1: Gained carrier1602second # [ 11.086679] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1603second # [ 11.093676] systemd[1]: Starting Network Manager Script Dispatcher Service...1604second # [ 11.094707] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1605second # [ 11.107863] systemd[1]: Starting Network Manager...1606second # [ 11.121892] systemd[1]: Started backdoor.service.1607second # [ 11.146771] systemd[1]: Starting Import lastlog data into lastlog2 database...1608test # [ 11.159129] systemd-networkd[548]: eth0: Link UP1609test # [ 11.159589] systemd-networkd[548]: eth0: Gained carrier1610second # [ 11.157873] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1611test # [ 11.179416] systemd[1]: Started Network Manager.1612second # [ 11.178010] systemd[1]: Starting Post-Boot Actions...1613test # [ 11.183181] systemd[1]: Reached target Network.1614test # [ 11.187997] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1615test # [ 11.199709] systemd[1]: Starting Permit User Sessions...1616second # [ 11.213371] systemd[1]: Started Reset console on configuration changes.1617second # [ 11.239045] bfrwvyg79my69yafr053q6bzx4lix24v-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1618second # [ 11.250138] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1619second # connecting to host...1620second # [ 11.287391] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1621second # [ 11.294407] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1622test # [ 11.343686] systemd[1]: Finished Permit User Sessions.1623test # [ 11.363186] systemd[1]: Started Getty on tty1.1624second # [ 11.363347] systemd[1]: Finished Post-Boot Actions.1625test # [ 11.370374] systemd[1]: Reached target Login Prompts.1626second # [ 11.395256] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1627test # [ 11.403821] systemd-resolved[353]: eth0: Bus client set default route setting: yes1628test # [ 11.409544] systemd-resolved[353]: eth0: Bus client set DNS server list to: 10.0.2.31629second # [ 11.408634] systemd[1]: Started Name Service Cache Daemon (nsncd).1630second # [ 11.411055] systemd[1]: Reached target Host and Network Name Lookups.1631second # [ 11.417394] nsncd[544]: Jul 27 00:15:16.227 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1632second # [ 11.418506] systemd[1]: Reached target User and Group Name Lookups.1633test # [ 11.425490] NetworkManager[562]: <warn> [1785111316.2445] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1634second # [ 11.424430] systemd[1]: Starting User Login Management...1635test # [ 11.427300] NetworkManager[562]: <warn> [1785111316.2467] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1636second # [ 11.452081] systemd[1]: Finished Import lastlog data into lastlog2 database.1637test # [ 11.483188] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1638test # [ 11.484774] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1639second # [ 11.479635] systemd[1]: Started Network Manager Script Dispatcher Service.1640test # [ 11.493698] systemd[1]: Reached target Multi-User System.1641test # [ 11.500625] systemd[1]: Startup finished in 751ms (kernel) + 4.661s (initrd) + 6.087s (userspace) = 11.500s.1642second # [ 11.569091] systemd[1]: Starting Hostname Service...1643second # [ 11.570947] systemd-logind[572]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1644second # [ 11.577653] systemd-logind[572]: Watching system buttons on /dev/input/event0 (gpio-keys)1645second # [ 11.578327] systemd-logind[572]: New seat seat0.1646second # [ 11.581131] systemd[1]: Started User Login Management.1647second # [ 11.592245] systemd[1]: Starting linger-users.service...1648second # [ 11.667052] systemd[1]: linger-users.service: Deactivated successfully.1649second # [ 11.667668] systemd[1]: Finished linger-users.service.1650second # [ 11.701939] systemd[1]: Started Hostname Service.1651test # [ 11.777620] systemd-networkd[548]: eth1: Gained IPv6LL1652second # [ 11.794207] systemd-networkd[528]: eth0: Link UP1653second # [ 11.794657] systemd-networkd[528]: eth0: Gained carrier1654second # [ 11.812204] systemd[1]: Started Network Manager.1655second # [ 11.815433] systemd[1]: Reached target Network.1656second # [ 11.822720] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1657second # [ 11.827941] systemd[1]: Starting Permit User Sessions...1658second # [ 11.981516] systemd[1]: Finished Permit User Sessions.1659test: (finished: waiting for unit NetworkManager.service, in 12.35 seconds)1660test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1661second # [ 12.001053] systemd[1]: Started Getty on tty1.1662second # [ 12.001820] systemd[1]: Reached target Login Prompts.1663test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1664cleanup1665kill QemuMachine (pid 47)1666second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/ymm5c1kb7p2r6331bgcdxijayqhcbvqy-python3-3.14.6/bin/python3.14)1667test # [ 12.079358] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31668kill QemuMachine (pid 48)1669test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/ymm5c1kb7p2r6331bgcdxijayqhcbvqy-python3-3.14.6/bin/python3.14)1670(finished: cleanup, in 0.27 seconds)1671Traceback (most recent call last):1672 File "/nix/store/p9f9jwakj2jislw4xxhxcw5sx05pqxq1-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1673 sys.exit(main())1674 ~~~~^^1675 File "/nix/store/p9f9jwakj2jislw4xxhxcw5sx05pqxq1-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/__init__.py", line 194, in main1676 driver.run_tests()1677 ~~~~~~~~~~~~~~~~^^1678 File "/nix/store/p9f9jwakj2jislw4xxhxcw5sx05pqxq1-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 434, in run_tests1679 self.test_script()1680 ~~~~~~~~~~~~~~~~^^1681 File "/nix/store/p9f9jwakj2jislw4xxhxcw5sx05pqxq1-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/driver.py", line 392, in test_script1682 exec(self.tests, symbols, None)1683 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1684 File "<string>", line 4, in <module>1685AssertionError: Expected mock password in connection file:1686[connection]1687autoconnect=true1688id=1689type=wifi16901691[wifi]1692mode=infrastructure1693ssid=16941695[wifi-security]1696key-mgmt=wpa-psk1697psk=1698