vm-test-run-wifi
checks.aarch64-linux.wifi
· build #87
· 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.0 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13test # Disk image does not exist, creating the virtualisation disk image...14test # Formatting '/build/vm-state-test/tmp.Gy05b1UVlp', fmt=raw size=107374182415test # mke2fs 1.47.4 (6-Mar-2025)16test # Discarding device blocks: 0/262144 done17test # Creating filesystem with 262144 4k blocks and 65536 inodes18test # Filesystem UUID: 090cfdd4-258a-4788-bd09-67bdaf4e6b6919second # Disk image does not exist, creating the virtualisation disk image...20test # Superblock backups stored on blocks:21test # 32768, 98304, 163840, 22937622second # Formatting '/build/vm-state-second/tmp.zI5YUurAX7', fmt=raw size=107374182423test # 24test: QEMU running (pid 48)25second: QEMU running (pid 47)26second # mke2fs 1.47.4 (6-Mar-2025)27test # Allocating group tables: 0/8 done28second # Discarding device blocks: 0/262144 done29test # Writing inode tables: 0/8 done30second # Creating filesystem with 262144 4k blocks and 65536 inodes31(finished: start all VMs, in 0.48 seconds)32second # Filesystem UUID: cb3e193c-7f4a-4810-ba20-cf8d89f80a5f33test: waiting for unit NetworkManager.service34second # Superblock backups stored on blocks:35test: waiting for the VM to finish booting36second # 32768, 98304, 163840, 22937637test # Creating journal (8192 blocks): done38second # 39test # Writing superblocks and filesystem accounting information: 0/8 done40second # Allocating group tables: 0/8 done41test # 42second # Writing inode tables: 0/8 done43test # Virtualisation disk image created.44second # Creating journal (8192 blocks): done45second # Writing superblocks and filesystem accounting information: 0/8 done46second # 47second # Virtualisation disk image created.48second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]50second # [ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 UTC 202651second # [ 0.000000] KASLR enabled52second # [ 0.000000] random: crng init done53test # [ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Aug 28 06:22:54 UTC 202654test # [ 0.000000] KASLR enabled55second # [ 0.000000] Machine model: linux,dummy-virt56test # [ 0.000000] random: crng init done57second # [ 0.000000] efi: UEFI not found.58test # [ 0.000000] Machine model: linux,dummy-virt59second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT60test # [ 0.000000] efi: UEFI not found.61second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]62test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT63second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]64test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]65second # [ 0.000000] Zone ranges:66second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]67test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07700-0x7fc0ae7f]68second # [ 0.000000] DMA32 empty69test # [ 0.000000] Zone ranges:70second # [ 0.000000] Normal empty71second # [ 0.000000] Device empty72second # [ 0.000000] Movable zone start for each node73test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]74test # [ 0.000000] DMA32 empty75test # [ 0.000000] Normal empty76second # [ 0.000000] Early memory node ranges77test # [ 0.000000] Device empty78second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]79test # [ 0.000000] Movable zone start for each node80test # [ 0.000000] Early memory node ranges81second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]82test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]83second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000084second # [ 0.000000] psci: probing for conduit method from DT.85test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]86second # [ 0.000000] psci: PSCIv1.3 detected in firmware.87test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000088second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs89test # [ 0.000000] psci: probing for conduit method from DT.90second # [ 0.000000] psci: Trusted OS migration not required91test # [ 0.000000] psci: PSCIv1.3 detected in firmware.92second # [ 0.000000] psci: SMC Calling Convention v1.193test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs94test # [ 0.000000] psci: Trusted OS migration not required95second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)96test # [ 0.000000] psci: SMC Calling Convention v1.197second # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129698second # [ 0.000000] Detected PIPT I-cache on CPU099test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)100second # [ 0.000000] CPU features: detected: GICv3 CPU interface101test # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u311296102second # [ 0.000000] CPU features: detected: Spectre-v4103test # [ 0.000000] Detected PIPT I-cache on CPU0104second # [ 0.000000] CPU features: detected: Spectre-BHB105test # [ 0.000000] CPU features: detected: GICv3 CPU interface106second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR107test # [ 0.000000] CPU features: detected: Spectre-v4108test # [ 0.000000] CPU features: detected: Spectre-BHB109second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)110second # [ 0.000000] CPU features: detected: ARM erratum 1418040111test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR112second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion113test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)114second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing115test # [ 0.000000] CPU features: detected: ARM erratum 1418040116second # [ 0.000000] alternatives: applying boot alternatives117test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion118test # [ 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/6mm6azwqpgwq914mxh4a6fdrwn8dx9vz-nixos-system-second-test/init regInfo=/nix/store/q470sypccrcrsy76smpaszajq8a9n4g1-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/3ji2r193lkaf461yxs3pg3xqr57zn9p6-nixos-system-test-test/init regInfo=/nix/store/3j9zrk0b35ac7inglwy4z6lg39rbjkmi-closure-info/registration console=ttyAMA0,115200n8 console=tty0122second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/q470sypccrcrsy76smpaszajq8a9n4g1-closure-info/registration", will be passed to user space.123second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes124test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/3j9zrk0b35ac7inglwy4z6lg39rbjkmi-closure-info/registration", will be passed to user space.125second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)126test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes127second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)128second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB129test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)130second # [ 0.000000] software IO TLB: area num 1.131test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)132second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)133test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB134second # [ 0.000000] Fallback order for Node 0: 0135test # [ 0.000000] software IO TLB: area num 1.136second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144137second # [ 0.000000] Policy zone: DMA138test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)139test # [ 0.000000] Fallback order for Node 0: 0140second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off141second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1142test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144143test # [ 0.000000] Policy zone: DMA144second # [ 0.000000] allocated 2097152 bytes of page_ext145test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off146second # [ 0.000000] ftrace: allocating 74886 entries in 294 pages147second # [ 0.000000] ftrace: allocated 294 pages with 4 groups148test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1149second # [ 0.000000] rcu: Hierarchical RCU implementation.150test # [ 0.000000] allocated 2097152 bytes of page_ext151second # [ 0.000000] rcu: RCU event tracing is enabled.152test # [ 0.000000] ftrace: allocating 74886 entries in 294 pages153test # [ 0.000000] ftrace: allocated 294 pages with 4 groups154test # [ 0.000000] rcu: Hierarchical RCU implementation.155second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.156test # [ 0.000000] rcu: RCU event tracing is enabled.157second # [ 0.000000] Trampoline variant of Tasks RCU enabled.158second # [ 0.000000] Rude variant of Tasks RCU enabled.159test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.160second # [ 0.000000] Tracing variant of Tasks RCU enabled.161test # [ 0.000000] Trampoline variant of Tasks RCU enabled.162test # [ 0.000000] Rude variant of Tasks RCU enabled.163second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.164test # [ 0.000000] Tracing variant of Tasks RCU enabled.165second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.167second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.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.172test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.173second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0174second # [ 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.176second # [ 0.000000] GICv3: 0 Extended SPIs implemented177second # [ 0.000000] Root IRQ handler: gic_handle_irq178test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0179test # [ 0.000000] GICv3: 256 SPIs implemented180second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI181test # [ 0.000000] GICv3: 0 Extended SPIs implemented182second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0183test # [ 0.000000] Root IRQ handler: gic_handle_irq184second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000185test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI186second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]187test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0188test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000189second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)190test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]191second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)192test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)193second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000194second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000195test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)196second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.197test # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000198test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000199second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns200test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.201second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040202second # [ 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 ns204test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040205second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns206test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).207second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns208second # [ 0.000056] arm-pv: using stolen time PV209test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns210second # [ 0.000364] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)211test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns212second # [ 0.000620] Console: colour dummy device 80x25213test # [ 0.000051] arm-pv: using stolen time PV214second # [ 0.000632] printk: legacy console [tty0] enabled215test # [ 0.000371] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)216second # [ 0.000868] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)217test # [ 0.000649] Console: colour dummy device 80x25218second # [ 0.000874] pid_max: default: 32768 minimum: 301219test # [ 0.000660] printk: legacy console [tty0] enabled220second # [ 0.000951] LSM: initializing lsm=capability,landlock,yama,bpf,ima221second # [ 0.001073] landlock: Up and running.222second # [ 0.001075] Yama: becoming mindful.223test # [ 0.000868] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)224second # [ 0.001442] LSM support for eBPF active225second # [ 0.001611] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)226test # [ 0.000873] pid_max: default: 32768 minimum: 301227second # [ 0.001629] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)228test # [ 0.000958] LSM: initializing lsm=capability,landlock,yama,bpf,ima229test # [ 0.001082] landlock: Up and running.230second # [ 0.003416] cacheinfo: Unable to detect cache hierarchy for CPU 0231test # [ 0.001085] Yama: becoming mindful.232second # [ 0.004275] rcu: Hierarchical SRCU implementation.233test # [ 0.001445] LSM support for eBPF active234second # [ 0.004279] rcu: Max phase no-delay instances is 1000.235test # [ 0.001610] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)236second # [ 0.005180] fsl-mc MSI: its@8080000 domain created237second # [ 0.005329] EFI services will not be available.238test # [ 0.001628] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)239second # [ 0.005424] smp: Bringing up secondary CPUs ...240test # [ 0.003407] cacheinfo: Unable to detect cache hierarchy for CPU 0241second # [ 0.005454] smp: Brought up 1 node, 1 CPU242test # [ 0.004196] rcu: Hierarchical SRCU implementation.243second # [ 0.005457] SMP: Total of 1 processors activated.244second # [ 0.005459] CPU: All CPU(s) started at EL1245test # [ 0.004200] rcu: Max phase no-delay instances is 1000.246second # [ 0.005469] CPU features: detected: 32-bit EL0 Support247test # [ 0.005109] fsl-mc MSI: its@8080000 domain created248test # [ 0.005270] EFI services will not be available.249second # [ 0.005472] CPU features: detected: Data cache clean to the PoU not required for I/D coherence250test # [ 0.005351] smp: Bringing up secondary CPUs ...251test # [ 0.005384] smp: Brought up 1 node, 1 CPU252second # [ 0.005475] CPU features: detected: Common not Private translations253test # [ 0.005387] SMP: Total of 1 processors activated.254second # [ 0.005477] CPU features: detected: CRC32 instructions255test # [ 0.005389] CPU: All CPU(s) started at EL1256second # [ 0.005479] CPU features: detected: Data cache clean to Point of Persistence257test # [ 0.005401] CPU features: detected: 32-bit EL0 Support258second # [ 0.005482] CPU features: detected: Enhanced Virtualization Traps259test # [ 0.005404] CPU features: detected: Data cache clean to the PoU not required for I/D coherence260second # [ 0.005493] CPU features: detected: RCpc load-acquire (LDAPR)261test # [ 0.005406] CPU features: detected: Common not Private translations262second # [ 0.005495] CPU features: detected: LSE atomic instructions263test # [ 0.005409] CPU features: detected: CRC32 instructions264second # [ 0.005497] CPU features: detected: Privileged Access Never265second # [ 0.005498] CPU features: detected: PMUv3266test # [ 0.005410] CPU features: detected: Data cache clean to Point of Persistence267second # [ 0.005500] CPU features: detected: RAS Extension Support268test # [ 0.005413] CPU features: detected: Enhanced Virtualization Traps269second # [ 0.005504] CPU features: detected: Speculative Store Bypassing Safe (SSBS)270test # [ 0.005422] CPU features: detected: RCpc load-acquire (LDAPR)271second # [ 0.005532] alternatives: applying system-wide alternatives272test # [ 0.005425] CPU features: detected: LSE atomic instructions273test # [ 0.005426] CPU features: detected: Privileged Access Never274second # [ 0.006962] CPU features: detected: Hardware dirty bit management on CPU0275test # [ 0.005428] CPU features: detected: PMUv3276test # [ 0.005430] CPU features: detected: RAS Extension Support277second # [ 0.007148] Memory: 891504K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115784K reserved, 32768K cma-reserved)278test # [ 0.005434] CPU features: detected: Speculative Store Bypassing Safe (SSBS)279second # [ 0.007598] devtmpfs: initialized280test # [ 0.005462] alternatives: applying system-wide alternatives281second # [ 0.009015] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)282test # [ 0.006831] CPU features: detected: Hardware dirty bit management on CPU0283second # [ 0.009036] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).284second # [ 0.009181] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL285test # [ 0.007040] Memory: 891444K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 115784K reserved, 32768K cma-reserved)286second # [ 0.009185] 0 pages in range for non-PLT usage287test # [ 0.007517] devtmpfs: initialized288second # [ 0.009185] 508288 pages in range for PLT usage289test # [ 0.008958] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)290second # [ 0.009315] pinctrl core: initialized pinctrl subsystem291second # [ 0.010069] DMI not present or invalid.292test # [ 0.008979] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).293second # [ 0.013213] NET: Registered PF_NETLINK/PF_ROUTE protocol family294test # [ 0.009112] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL295second # [ 0.015395] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations296test # [ 0.009116] 0 pages in range for non-PLT usage297test # [ 0.009117] 508288 pages in range for PLT usage298second # [ 0.015522] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations299test # [ 0.009243] pinctrl core: initialized pinctrl subsystem300second # [ 0.015677] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations301test # [ 0.010018] DMI not present or invalid.302second # [ 0.015692] audit: initializing netlink subsys (disabled)303test # [ 0.013203] NET: Registered PF_NETLINK/PF_ROUTE protocol family304second # [ 0.016119] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1305test # [ 0.015406] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations306second # [ 0.016232] thermal_sys: Registered thermal governor 'fair_share'307test # [ 0.015535] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations308second # [ 0.016234] thermal_sys: Registered thermal governor 'bang_bang'309test # [ 0.015686] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations310second # [ 0.016236] thermal_sys: Registered thermal governor 'step_wise'311test # [ 0.015703] audit: initializing netlink subsys (disabled)312second # [ 0.016238] thermal_sys: Registered thermal governor 'user_space'313second # [ 0.016240] thermal_sys: Registered thermal governor 'power_allocator'314test # [ 0.016120] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1315second # [ 0.016262] cpuidle: using governor ladder316test # [ 0.016278] thermal_sys: Registered thermal governor 'fair_share'317second # [ 0.016267] cpuidle: using governor menu318test # [ 0.016280] thermal_sys: Registered thermal governor 'bang_bang'319second # [ 0.016392] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.320test # [ 0.016282] thermal_sys: Registered thermal governor 'step_wise'321second # [ 0.016414] ASID allocator initialised with 32768 entries322test # [ 0.016284] thermal_sys: Registered thermal governor 'user_space'323second # [ 0.017050] Serial: AMBA PL011 UART driver324test # [ 0.016286] thermal_sys: Registered thermal governor 'power_allocator'325second # [ 0.019996] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1326test # [ 0.016309] cpuidle: using governor ladder327second # [ 0.020174] printk: console [ttyAMA0] enabled328test # [ 0.016314] cpuidle: using governor menu329test # [ 0.016448] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.330test # [ 0.016467] ASID allocator initialised with 32768 entries331test # [ 0.017125] Serial: AMBA PL011 UART driver332test # [ 0.020300] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1333test # [ 0.020463] printk: console [ttyAMA0] enabled334second # [ 0.097025] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages335second # [ 0.097041] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page336second # [ 0.097048] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages337second # [ 0.097050] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page338second # [ 0.097054] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages339test # [ 0.098789] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages340second # [ 0.097057] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page341test # [ 0.098803] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page342second # [ 0.097060] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages343test # [ 0.098811] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages344second # [ 0.097063] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page345test # [ 0.098814] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page346test # [ 0.098817] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages347test # [ 0.098820] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page348test # [ 0.098823] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages349second # [ 0.102002] fbcon: Taking over console350test # [ 0.098826] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page351second # [ 0.102019] ACPI: Interpreter disabled.352second # [ 0.103254] iommu: Default domain type: Translated353second # [ 0.103264] iommu: DMA domain TLB invalidation policy: strict mode354second # [ 0.104388] SCSI subsystem initialized355test # [ 0.103903] fbcon: Taking over console356test # [ 0.103919] ACPI: Interpreter disabled.357second # [ 0.104917] usbcore: registered new interface driver usbfs358test # [ 0.105178] iommu: Default domain type: Translated359second # [ 0.104962] usbcore: registered new interface driver hub360test # [ 0.105189] iommu: DMA domain TLB invalidation policy: strict mode361second # [ 0.104972] usbcore: registered new device driver usb362second # [ 0.106355] pps_core: LinuxPPS API ver. 1 registered363test # [ 0.106347] SCSI subsystem initialized364second # [ 0.106366] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>365test # [ 0.106910] usbcore: registered new interface driver usbfs366second # [ 0.106373] PTP clock support registered367test # [ 0.106948] usbcore: registered new interface driver hub368second # [ 0.106420] EDAC MC: Ver: 3.0.0369test # [ 0.106958] usbcore: registered new device driver usb370second # [ 0.108168] scmi_core: SCMI protocol bus registered371test # [ 0.108410] pps_core: LinuxPPS API ver. 1 registered372second # [ 0.108865] FPGA manager framework373test # [ 0.108421] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>374test # [ 0.108428] PTP clock support registered375test # [ 0.108462] EDAC MC: Ver: 3.0.0376test # [ 0.110273] scmi_core: SCMI protocol bus registered377test # [ 0.115137] FPGA manager framework378test # [ 0.116164] vgaarb: loaded379test # [ 0.116628] clocksource: Switched to clocksource arch_sys_counter380second # [ 0.117658] vgaarb: loaded381second # [ 0.118078] clocksource: Switched to clocksource arch_sys_counter382second # [ 0.122984] VFS: Disk quotas dquot_6.6.0383second # [ 0.123017] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)384second # [ 0.125228] netfs: FS-Cache loaded385second # [ 0.125343] pnp: PnP ACPI: disabled386test # [ 0.126054] VFS: Disk quotas dquot_6.6.0387test # [ 0.126088] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)388test # [ 0.128400] netfs: FS-Cache loaded389test # [ 0.128502] pnp: PnP ACPI: disabled390second # [ 0.127658] NET: Registered PF_INET protocol family391second # [ 0.127835] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)392test # [ 0.130969] NET: Registered PF_INET protocol family393test # [ 0.131151] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)394second # [ 0.151214] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)395second # [ 0.151246] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396second # [ 0.151260] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397second # [ 0.151301] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)398second # [ 0.151369] TCP: Hash tables configured (established 8192 bind 8192)399second # [ 0.151456] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)400test # [ 0.154465] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)401second # [ 0.151516] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)402test # [ 0.154496] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)403second # [ 0.151544] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)404test # [ 0.154510] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)405second # [ 0.151656] NET: Registered PF_UNIX/PF_LOCAL protocol family406test # [ 0.154553] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)407second # [ 0.151700] NET: Registered PF_XDP protocol family408second # [ 0.151722] PCI: CLS 0 bytes, default 64409test # [ 0.154621] TCP: Hash tables configured (established 8192 bind 8192)410second # [ 0.151919] Trying to unpack rootfs image as initramfs...411test # [ 0.154711] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)412test # [ 0.154777] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)413test # [ 0.154831] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)414test # [ 0.154971] NET: Registered PF_UNIX/PF_LOCAL protocol family415test # [ 0.155008] NET: Registered PF_XDP protocol family416test # [ 0.155029] PCI: CLS 0 bytes, default 64417test # [ 0.155240] Trying to unpack rootfs image as initramfs...418second # [ 0.164094] kvm [1]: HYP mode not available419test # [ 0.166672] kvm [1]: HYP mode not available420second # [ 0.228497] Initialise system trusted keyrings421second # [ 0.229004] workingset: timestamp_bits=42 max_order=18 bucket_order=0422second # [ 0.229919] squashfs: version 4.0 (2009/01/31) Phillip Lougher423test # [ 0.232465] Initialise system trusted keyrings424test # [ 0.233048] workingset: timestamp_bits=42 max_order=18 bucket_order=0425test # [ 0.233997] squashfs: version 4.0 (2009/01/31) Phillip Lougher426second # [ 0.230478] 9p: Installing v9fs 9p2000 file system support427test # [ 0.240664] 9p: Installing v9fs 9p2000 file system support428second # [ 0.249380] Key type asymmetric registered429second # [ 0.249408] Asymmetric key parser 'x509' registered430second # [ 0.249468] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)431second # [ 0.254160] io scheduler mq-deadline registered432second # [ 0.254176] io scheduler kyber registered433test # [ 0.259527] Key type asymmetric registered434test # [ 0.259553] Asymmetric key parser 'x509' registered435test # [ 0.259608] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)436test # [ 0.261024] io scheduler mq-deadline registered437test # [ 0.261036] io scheduler kyber registered438second # [ 0.262183] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439second # [ 0.262508] ledtrig-cpu: registered to indicate activity on CPUs440second # [ 0.262791] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:441second # [ 0.262811] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000442second # [ 0.262825] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000443second # [ 0.262831] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000444second # [ 0.262862] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits445second # [ 0.262885] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]446second # [ 0.262963] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00447second # [ 0.262980] pci_bus 0000:00: root bus resource [bus 00-ff]448second # [ 0.262985] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]449second # [ 0.262988] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]450second # [ 0.262992] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]451test # [ 0.268738] pl061_gpio 9030000.pl061: PL061 GPIO chip registered452test # [ 0.269113] ledtrig-cpu: registered to indicate activity on CPUs453second # [ 0.263075] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint454test # [ 0.269429] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:455second # [ 0.263509] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint456test # [ 0.269450] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000457second # [ 0.263636] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]458second # [ 0.263647] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]459test # [ 0.269464] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000460second # [ 0.263668] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]461test # [ 0.269471] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000462second # [ 0.263678] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]463test # [ 0.269503] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits464second # [ 0.263986] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint465second # [ 0.264110] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]466test # [ 0.269525] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]467second # [ 0.264121] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]468test # [ 0.269588] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00469second # [ 0.264141] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]470test # [ 0.269606] pci_bus 0000:00: root bus resource [bus 00-ff]471second # [ 0.264443] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint472test # [ 0.269611] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]473second # [ 0.264567] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]474test # [ 0.269615] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]475second # [ 0.264577] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]476test # [ 0.269618] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]477second # [ 0.264598] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]478test # [ 0.269699] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint479second # [ 0.264896] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint480second # [ 0.265020] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]481test # [ 0.270141] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint482second # [ 0.265031] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]483test # [ 0.270273] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]484test # [ 0.270285] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]485second # [ 0.265051] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]486test # [ 0.270305] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]487second # [ 0.265371] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint488test # [ 0.270316] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]489second # [ 0.265494] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]490second # [ 0.265505] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]491test # [ 0.270639] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint492test # [ 0.270767] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]493second # [ 0.265525] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]494test # [ 0.270778] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]495second # [ 0.265831] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint496test # [ 0.270798] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497second # [ 0.265953] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]498second # [ 0.265964] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]499test # [ 0.271113] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint500test # [ 0.271241] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]501second # [ 0.265984] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]502test # [ 0.271252] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]503second # [ 0.266319] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint504test # [ 0.271272] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505second # [ 0.266444] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]506second # [ 0.266455] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]507test # [ 0.271584] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint508test # [ 0.271710] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]509second # [ 0.266478] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]510test # [ 0.271721] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]511second # [ 0.266489] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]512test # [ 0.271743] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513second # [ 0.266796] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint514test # [ 0.272056] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint515second # [ 0.266922] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]516test # [ 0.272182] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]517second # [ 0.266943] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518test # [ 0.272195] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]519second # [ 0.267261] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint520test # [ 0.272215] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]521second # [ 0.267386] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]522test # [ 0.272526] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint523second # [ 0.267406] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]524test # [ 0.272672] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]525second # [ 0.267671] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint526test # [ 0.272684] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]527second # [ 0.267792] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]528test # [ 0.272704] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]529second # [ 0.267954] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint530test # [ 0.273066] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint531second # [ 0.268153] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]532test # [ 0.273196] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]533second # [ 0.268166] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]534test # [ 0.273208] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]535second # [ 0.268186] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]536test # [ 0.273232] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]537test # [ 0.273243] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]538second # [ 0.268501] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint539second # [ 0.268626] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]540test # [ 0.273562] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint541second # [ 0.268637] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]542test # [ 0.273703] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]543second # [ 0.268657] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]544test # [ 0.273723] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]545second # [ 0.269066] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned546test # [ 0.274042] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint547second # [ 0.269078] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned548test # [ 0.274174] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]549second # [ 0.269082] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned550test # [ 0.274195] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]551second # [ 0.269114] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned552test # [ 0.274472] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint553test # [ 0.274597] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]554second # [ 0.269146] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned555test # [ 0.274770] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint556second # [ 0.269189] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned557test # [ 0.275014] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]558second # [ 0.269221] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned559test # [ 0.275027] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]560second # [ 0.269251] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned561test # [ 0.275048] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]562second # [ 0.269283] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned563test # [ 0.275366] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint564test # [ 0.275494] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]565second # [ 0.269316] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned566test # [ 0.275505] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]567second # [ 0.269348] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned568test # [ 0.275526] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]569second # [ 0.269379] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned570test # [ 0.275956] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned571second # [ 0.269463] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned572test # [ 0.275967] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned573second # [ 0.269494] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned574test # [ 0.275972] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned575second # [ 0.269508] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned576test # [ 0.276005] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned577second # [ 0.269522] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned578test # [ 0.276037] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned579second # [ 0.269535] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned580second # [ 0.269549] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned581test # [ 0.276070] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned582second # [ 0.269562] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned583test # [ 0.276101] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned584second # [ 0.269576] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned585test # [ 0.276133] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned586second # [ 0.269589] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned587test # [ 0.276164] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned588second # [ 0.269603] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned589test # [ 0.276196] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned590second # [ 0.269616] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned591second # [ 0.269630] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned592test # [ 0.276226] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned593second # [ 0.269644] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned594test # [ 0.276258] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned595second # [ 0.269657] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned596test # [ 0.276330] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned597second # [ 0.269671] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned598test # [ 0.276363] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned599second # [ 0.269685] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned600test # [ 0.276377] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned601second # [ 0.269698] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned602second # [ 0.269717] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned603test # [ 0.276391] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned604second # [ 0.269730] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned605test # [ 0.276405] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned606second # [ 0.269743] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned607test # [ 0.276419] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned608second # [ 0.269757] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned609test # [ 0.276433] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned610second # [ 0.269771] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned611test # [ 0.276446] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned612second # [ 0.269790] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]613test # [ 0.276461] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned614second # [ 0.269795] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]615test # [ 0.276475] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned616second # [ 0.269799] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]617test # [ 0.276489] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned618test # [ 0.276503] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned619test # [ 0.276516] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned620test # [ 0.276530] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned621test # [ 0.276544] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned622test # [ 0.276558] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned623test # [ 0.276571] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned624test # [ 0.276589] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned625test # [ 0.276603] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned626test # [ 0.276616] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned627second # [ 0.326621] pci 0000:00:0a.0: enabling device (0000 -> 0002)628test # [ 0.332692] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned629test # [ 0.332723] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned630test # [ 0.332750] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]631test # [ 0.332756] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]632test # [ 0.332760] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]633test # [ 0.333544] pci 0000:00:0a.0: enabling device (0000 -> 0002)634second # [ 0.338856] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)635second # [ 0.340705] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)636test # [ 0.350558] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)637test # [ 0.352427] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)638second # [ 0.350351] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)639second # [ 0.352418] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)640second # [ 0.354066] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)641test # [ 0.355252] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)642second # [ 0.357434] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)643test # [ 0.361862] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)644test # [ 0.364426] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)645second # [ 0.363220] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)646second # [ 0.365200] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)647test # [ 0.374066] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)648test # [ 0.376450] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)649second # [ 0.375472] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)650second # [ 0.377130] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)651test # [ 0.386200] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)652test # [ 0.388624] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)653second # [ 0.387692] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)654second # [ 0.391527] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled655test # [ 0.391160] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)656second # [ 0.392974] msm_serial: driver initialized657second # [ 0.393066] SuperH (H)SCI(F) driver initialized658second # [ 0.393099] STM32 USART driver initialized659test # [ 0.401119] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)660test # [ 0.405052] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled661test # [ 0.406543] msm_serial: driver initialized662test # [ 0.406632] SuperH (H)SCI(F) driver initialized663second # [ 0.413340] loop: module loaded664test # [ 0.406667] STM32 USART driver initialized665second # [ 0.413479] virtio_blk virtio5: 1/0/0 default/read/poll queues666second # [ 0.418235] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)667second # [ 0.422487] megasas: 07.734.00.00-rc1668second # [ 0.422950] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]669test # [ 0.430822] loop: module loaded670test # [ 0.430977] virtio_blk virtio5: 1/0/0 default/read/poll queues671test # [ 0.431558] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)672second # [ 0.434715] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000673second # [ 0.434752] Intel/Sharp Extended Query Table at 0x0031674second # [ 0.436394] Using buffer write method675second # [ 0.436453] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]676second # [ 0.438068] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000677second # [ 0.440943] Intel/Sharp Extended Query Table at 0x0031678second # [ 0.443007] Using buffer write method679second # [ 0.443030] Concatenating MTD devices:680second # [ 0.443033] (0): "0.flash"681second # [ 0.443036] (1): "0.flash"682second # [ 0.443039] into device "0.flash"683test # [ 0.441102] megasas: 07.734.00.00-rc1684test # [ 0.441611] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]685test # [ 0.443662] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000686test # [ 0.443720] Intel/Sharp Extended Query Table at 0x0031687test # [ 0.445300] Using buffer write method688test # [ 0.445331] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]689test # [ 0.447027] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000690test # [ 0.447045] Intel/Sharp Extended Query Table at 0x0031691test # [ 0.456654] Using buffer write method692test # [ 0.456675] Concatenating MTD devices:693test # [ 0.456678] (0): "0.flash"694test # [ 0.456681] (1): "0.flash"695test # [ 0.456683] into device "0.flash"696second # [ 0.648821] Freeing initrd memory: 26980K697second # [ 0.655645] tun: Universal TUN/TAP device driver, 1.6698test # [ 0.660242] Freeing initrd memory: 26980K699second # [ 0.658292] thunder_xcv, ver 1.0700second # [ 0.658317] thunder_bgx, ver 1.0701second # [ 0.658328] nicpf, ver 1.0702second # [ 0.658611] e1000: Intel(R) PRO/1000 Network Driver703second # [ 0.658618] e1000: Copyright (c) 1999-2006 Intel Corporation.704second # [ 0.658632] e1000e: Intel(R) PRO/1000 Network Driver705second # [ 0.658638] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.706second # [ 0.658651] igb: Intel(R) Gigabit Ethernet Network Driver707second # [ 0.658654] igb: Copyright (c) 2007-2014 Intel Corporation.708second # [ 0.658664] igbvf: Intel(R) Gigabit Virtual Function Network Driver709second # [ 0.658668] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.710second # [ 0.658727] sky2: driver version 1.30711second # [ 0.659487] usbcore: registered new interface driver usb-storage712second # [ 0.659537] usbcore: registered new interface driver usbserial_generic713second # [ 0.659547] usbserial: USB Serial support registered for generic714second # [ 0.659816] hv_vmbus: registering driver hyperv_keyboard715test # [ 0.667079] tun: Universal TUN/TAP device driver, 1.6716second # [ 0.660805] ehci-pci 0000:00:0a.0: EHCI Host Controller717second # [ 0.660836] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1718second # [ 0.661089] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000719second # [ 0.668970] rtc-pl031 9010000.pl031: registered as rtc0720second # [ 0.669013] rtc-pl031 9010000.pl031: setting system clock to 2026-08-31T15:04:09 UTC (1788188649)721second # [ 0.669202] i2c_dev: i2c /dev entries driver722second # [ 0.671097] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00723test # [ 0.669682] thunder_xcv, ver 1.0724test # [ 0.669709] thunder_bgx, ver 1.0725second # [ 0.671465] hub 1-0:1.0: USB hub found726test # [ 0.669723] nicpf, ver 1.0727second # [ 0.671526] hub 1-0:1.0: 6 ports detected728test # [ 0.670025] e1000: Intel(R) PRO/1000 Network Driver729test # [ 0.670034] e1000: Copyright (c) 1999-2006 Intel Corporation.730test # [ 0.670048] e1000e: Intel(R) PRO/1000 Network Driver731test # [ 0.670053] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.732second # [ 0.673694] sdhci: Secure Digital Host Controller Interface driver733test # [ 0.670066] igb: Intel(R) Gigabit Ethernet Network Driver734second # [ 0.673708] sdhci: Copyright(c) Pierre Ossman735test # [ 0.670069] igb: Copyright (c) 2007-2014 Intel Corporation.736second # [ 0.673835] Synopsys Designware Multimedia Card Interface Driver737test # [ 0.670080] igbvf: Intel(R) Gigabit Virtual Function Network Driver738second # [ 0.673994] sdhci-pltfm: SDHCI platform and OF driver helper739test # [ 0.670083] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.740test # [ 0.670147] sky2: driver version 1.30741test # [ 0.670949] usbcore: registered new interface driver usb-storage742test # [ 0.671033] usbcore: registered new interface driver usbserial_generic743test # [ 0.671042] usbserial: USB Serial support registered for generic744test # [ 0.671334] hv_vmbus: registering driver hyperv_keyboard745test # [ 0.678644] ehci-pci 0000:00:0a.0: EHCI Host Controller746test # [ 0.678679] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1747test # [ 0.678836] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000748test # [ 0.680437] rtc-pl031 9010000.pl031: registered as rtc0749test # [ 0.680478] rtc-pl031 9010000.pl031: setting system clock to 2026-08-31T15:04:09 UTC (1788188649)750test # [ 0.681808] i2c_dev: i2c /dev entries driver751test # [ 0.683521] sdhci: Secure Digital Host Controller Interface driver752test # [ 0.683536] sdhci: Copyright(c) Pierre Ossman753test # [ 0.683675] Synopsys Designware Multimedia Card Interface Driver754test # [ 0.683843] sdhci-pltfm: SDHCI platform and OF driver helper755test # [ 0.686473] hid: raw HID events driver (C) Jiri Kosina756second # [ 0.676625] hid: raw HID events driver (C) Jiri Kosina757test # [ 0.686625] usbcore: registered new interface driver usbhid758second # [ 0.676769] usbcore: registered new interface driver usbhid759test # [ 0.686630] usbhid: USB HID core driver760second # [ 0.676773] usbhid: USB HID core driver761second # [ 0.714553] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available762test # [ 0.714172] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00763second # [ 0.715313] drop_monitor: Initializing network drop monitor service764second # [ 0.715428] NET: Registered PF_INET6 protocol family765test # [ 0.714360] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available766test # [ 0.715145] drop_monitor: Initializing network drop monitor service767second # [ 0.717683] Segment Routing with IPv6768test # [ 0.715268] NET: Registered PF_INET6 protocol family769second # [ 0.717702] In-situ OAM (IOAM) with IPv6770test # [ 0.715720] hub 1-0:1.0: USB hub found771second # [ 0.717756] NET: Registered PF_PACKET protocol family772test # [ 0.715745] hub 1-0:1.0: 6 ports detected773test # [ 0.719184] Segment Routing with IPv6774test # [ 0.719202] In-situ OAM (IOAM) with IPv6775test # [ 0.719257] NET: Registered PF_PACKET protocol family776second # [ 0.718844] 9pnet: Installing 9P2000 support777second # [ 0.720394] Key type dns_resolver registered778test # [ 0.720309] 9pnet: Installing 9P2000 support779test # [ 0.721839] Key type dns_resolver registered780second # [ 0.723869] registered taskstats version 1781second # [ 0.724015] Loading compiled-in X.509 certificates782test # [ 0.725885] registered taskstats version 1783test # [ 0.726034] Loading compiled-in X.509 certificates784second # [ 0.729296] Demotion targets for Node 0: null785second # [ 0.729360] Key type .fscrypt registered786second # [ 0.729366] Key type fscrypt-provisioning registered787second # [ 0.729462] ima: No TPM chip found, activating TPM-bypass!788second # [ 0.729474] ima: Allocated hash algorithm: sha1789second # [ 0.729523] ima: No architecture policies found790test # [ 0.731376] Demotion targets for Node 0: null791test # [ 0.731441] Key type .fscrypt registered792test # [ 0.731448] Key type fscrypt-provisioning registered793second # [ 0.732393] input: gpio-keys as /devices/platform/gpio-keys/input/input0794test # [ 0.731548] ima: No TPM chip found, activating TPM-bypass!795test # [ 0.731561] ima: Allocated hash algorithm: sha1796test # [ 0.731610] ima: No architecture policies found797test # [ 0.734533] input: gpio-keys as /devices/platform/gpio-keys/input/input0798second # [ 0.742193] clk: Disabling unused clocks799second # [ 0.742208] PM: genpd: Disabling unused power domains800second # [ 0.745348] Freeing unused kernel memory: 4736K801second # [ 0.745441] Run /init as init process802test # [ 0.744908] clk: Disabling unused clocks803test # [ 0.744935] PM: genpd: Disabling unused power domains804test # [ 0.748110] Freeing unused kernel memory: 4736K805test # [ 0.748212] Run /init as init process806second # [ 0.764403] systemd[1]: Successfully made /usr/ read-only.807test # [ 0.767104] systemd[1]: Successfully made /usr/ read-only.808second # [ 0.934133] usb 1-1: new high-speed USB device number 2 using ehci-pci809test # [ 0.960685] usb 1-1: new high-speed USB device number 2 using ehci-pci810second # [ 1.086037] 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.101561] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)812test # [ 1.104044] systemd[1]: Detected virtualization qemu.813test # [ 1.104501] systemd[1]: Detected architecture arm64.814test # [ 1.105021] systemd[1]: Running in initrd.815second # [ 1.099068] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)816test # [ 1.106121] systemd[1]: Initializing machine ID from random generator.817test # [ 1.106930] systemd[1]: Hostname set to <test>.818second # [ 1.106400] systemd[1]: Detected virtualization qemu.819second # [ 1.106462] systemd[1]: Detected architecture arm64.820second # [ 1.106473] systemd[1]: Running in initrd.821second # [ 1.107329] systemd[1]: Initializing machine ID from random generator.822second # [ 1.107617] systemd[1]: Hostname set to <second>.823test # [ 1.112814] 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/input1824second # [ 1.170279] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0825test # [ 1.192893] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0826second # [ 1.290117] usb 1-2: new high-speed USB device number 3 using ehci-pci827test # [ 1.312679] usb 1-2: new high-speed USB device number 3 using ehci-pci828test # [ 1.331917] systemd[1]: bpf-restrict-fs: LSM BPF program attached829second # [ 1.375562] systemd[1]: bpf-restrict-fs: LSM BPF program attached830test # [ 1.407369] systemd[1]: Queued start job for default target Initrd Default Target.831test # [ 1.418760] systemd[1]: Created slice Slice /system/modprobe.832test # [ 1.419621] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.833test # [ 1.420526] systemd[1]: Expecting device /dev/disk/by-label/nixos...834test # [ 1.421449] systemd[1]: Reached target Path Units.835test # [ 1.422001] systemd[1]: Reached target Slice Units.836test # [ 1.422547] systemd[1]: Reached target Swaps.837test # [ 1.423044] systemd[1]: Reached target Timer Units.838test # [ 1.423774] systemd[1]: Listening on D-Bus System Message Bus Socket.839test # [ 1.424712] systemd[1]: Listening on Journal Socket (/dev/log).840test # [ 1.425517] systemd[1]: Listening on Journal Sockets.841test # [ 1.426222] systemd[1]: Listening on udev Control Socket.842test # [ 1.426974] systemd[1]: Listening on udev Kernel Socket.843test # [ 1.428075] systemd[1]: Reached target Socket Units.844test # [ 1.430227] systemd[1]: Starting Create List of Static Device Nodes...845test # [ 1.440532] systemd[1]: Starting Load Kernel Module 9pnet_virtio...846test # [ 1.441742] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs847second # [ 1.446678] 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/input2848second # [ 1.446813] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0849test # [ 1.453903] systemd[1]: Mounting Kernel Configuration File System...850second # [ 1.465698] systemd[1]: Queued start job for default target Initrd Default Target.851test # [ 1.475624] systemd[1]: Starting Journal Service...852second # [ 1.476773] systemd[1]: Created slice Slice /system/modprobe.853second # [ 1.478051] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.854second # [ 1.479365] systemd[1]: Expecting device /dev/disk/by-label/nixos...855test # [ 1.479039] systemd[1]: Starting Load Kernel Modules...856second # [ 1.480405] systemd[1]: Reached target Path Units.857second # [ 1.481375] systemd[1]: Reached target Slice Units.858second # [ 1.482302] systemd[1]: Reached target Swaps.859test # [ 1.480539] 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/input2860second # [ 1.483053] systemd[1]: Reached target Timer Units.861test # [ 1.483076] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0862second # [ 1.484114] systemd[1]: Listening on D-Bus System Message Bus Socket.863second # [ 1.485589] systemd[1]: Listening on Journal Socket (/dev/log).864test # [ 1.485277] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os865second # [ 1.486815] systemd[1]: Listening on Journal Sockets.866second # [ 1.487794] systemd[1]: Listening on udev Control Socket.867second # [ 1.488851] systemd[1]: Listening on udev Kernel Socket.868second # [ 1.489823] systemd[1]: Reached target Socket Units.869second # [ 1.492306] systemd[1]: Starting Create List of Static Device Nodes...870test # [ 1.495183] systemd[1]: Starting Coldplug All udev Devices...871second # [ 1.502205] systemd[1]: Starting Load Kernel Module 9pnet_virtio...872second # [ 1.503310] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs873second # [ 1.505958] systemd[1]: Mounting Kernel Configuration File System...874second # [ 1.517261] systemd[1]: Starting Journal Service...875test # [ 1.520767] systemd[1]: Finished Create List of Static Device Nodes.876test # [ 1.522012] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.877test # [ 1.522243] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878second # [ 1.539028] systemd[1]: Starting Load Kernel Modules...879test # [ 1.539638] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.880second # [ 1.539132] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os881test # [ 1.552146] systemd-journald[73]: Collecting audit messages is disabled.882test # [ 1.552271] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...883second # [ 1.550278] systemd[1]: Starting Coldplug All udev Devices...884test # [ 1.558677] systemd[1]: Mounted Kernel Configuration File System.885test # [ 1.565542] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.886test # [ 1.568903] systemd[1]: Starting Create Static Device Nodes in /dev...887second # [ 1.571314] systemd-journald[73]: Collecting audit messages is disabled.888test # [ 1.572431] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev889test # [ 1.577054] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0890test # [ 1.577256] [drm] features: -virgl +edid -resource_blob -host_visible891test # [ 1.577261] [drm] features: -context_init892test # [ 1.577711] [drm] number of scanouts: 1893test # [ 1.577725] [drm] number of cap sets: 0894second # [ 1.587136] systemd[1]: Finished Create List of Static Device Nodes.895second # [ 1.588475] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.896second # [ 1.590335] systemd[1]: Finished Load Kernel Module 9pnet_virtio.897second # [ 1.591067] systemd[1]: Mounted Kernel Configuration File System.898test # [ 1.595974] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic899test # [ 1.595990] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0900second # [ 1.602395] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...901test # [ 1.609363] systemd[1]: Finished Create Static Device Nodes in /dev.902test # [ 1.609543] systemd[1]: Reached target Preparation for Local File Systems.903test # [ 1.609575] systemd[1]: Reached target Local File Systems.904test # [ 1.612165] systemd[1]: Starting Rule-based Manager for Device Events and Files...905second # [ 1.615903] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.906test # [ 1.624965] Console: switching to colour frame buffer device 160x50907test # [ 1.625485] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device908second # [ 1.639875] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev909second # [ 1.642977] systemd[1]: Started Journal Service.910test # [ 1.644046] systemd[1]: Finished Load Kernel Modules.911test # [ 1.646017] systemd[1]: Starting Apply Kernel Variables...912second # [ 1.648652] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0913second # [ 1.648861] [drm] features: -virgl +edid -resource_blob -host_visible914test # [ 1.641212] systemd-modules-load[74]: Inserted module 'dm_mod'915second # [ 1.648866] [drm] features: -context_init916second # [ 1.649339] [drm] number of scanouts: 1917second # [ 1.649355] [drm] number of cap sets: 0918test # [ 1.642107] systemd-modules-load[74]: Module 'virtio_balloon' is built in919second # [ 1.650476] systemd-modules-load[75]: Inserted module 'dm_mod'920test # [ 1.656874] systemd[1]: Started Journal Service.921second # [ 1.651426] systemd-modules-load[75]: Module 'virtio_balloon' is built in922second # [ 1.652358] systemd-modules-load[75]: Module 'virtio_console' is built in923test # [ 1.650000] systemd-modules-load[74]: Module 'virtio_console' is built in924test # [ 1.651456] systemd-modules-load[74]: Inserted module 'virtio_gpu'925test # [ 1.657432] systemd-modules-load[74]: Module 'virtio_rng' is built in926second # [ 1.662543] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic927second # [ 1.662561] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0928second # [ 1.662770] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.929second # [ 1.668732] systemd[1]: Starting Create Static Device Nodes in /dev...930test # [ 1.672087] systemd[1]: Starting Create System Files and Directories...931second # [ 1.684868] Console: switching to colour frame buffer device 160x50932second # [ 1.685895] systemd[1]: Finished Create Static Device Nodes in /dev.933second # [ 1.686513] systemd[1]: Reached target Preparation for Local File Systems.934second # [ 1.687116] systemd[1]: Reached target Local File Systems.935test # [ 1.684869] systemd[1]: Finished Apply Kernel Variables.936test # [ 1.689121] systemd-udevd[79]: Using default interface naming scheme 'v261'.937second # [ 1.695078] systemd[1]: Starting Create System Files and Directories...938second # [ 1.702485] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device939second # [ 1.708689] systemd-modules-load[75]: Inserted module 'virtio_gpu'940second # [ 1.709304] systemd-modules-load[75]: Module 'virtio_rng' is built in941test # [ 1.709323] systemd[1]: Finished Create System Files and Directories.942second # [ 1.716959] systemd[1]: Starting Rule-based Manager for Device Events and Files...943second # [ 1.717647] systemd[1]: Finished Load Kernel Modules.944test # [ 1.720575] systemd[1]: Started Rule-based Manager for Device Events and Files.945second # [ 1.729329] systemd[1]: Starting Apply Kernel Variables...946second # [ 1.729896] systemd[1]: Finished Create System Files and Directories.947second # [ 1.753548] systemd[1]: Finished Apply Kernel Variables.948second # [ 1.771544] systemd-udevd[86]: Using default interface naming scheme 'v261'.949test # [ 1.776837] systemd[1]: Starting Virtual Console Setup...950second # [ 1.790953] systemd[1]: Started Rule-based Manager for Device Events and Files.951test # [ 1.820559] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.952test # [ 1.823050] systemd[1]: Finished Virtual Console Setup.953second # [ 1.837944] systemd[1]: Starting Virtual Console Setup...954second # [ 1.876374] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.955second # [ 1.878765] systemd[1]: Finished Virtual Console Setup.956test # [ 2.043157] systemd[1]: Finished Coldplug All udev Devices.957test # [ 2.043755] systemd[1]: Reached target System Initialization.958test # [ 2.048153] systemd[1]: Reached target Basic System.959second # [ 2.069385] systemd[1]: Finished Coldplug All udev Devices.960second # [ 2.070017] systemd[1]: Reached target System Initialization.961second # [ 2.070541] systemd[1]: Reached target Basic System.962test # [ 2.144370] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.963test # [ 2.168333] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.964second # [ 2.171555] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.965second # [ 2.187588] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.966test # [ 2.240892] systemd[1]: Found device /dev/disk/by-label/nixos.967test # [ 2.242298] systemd[1]: Reached target Initrd Root Device.968second # [ 2.247412] systemd[1]: Found device /dev/disk/by-label/nixos.969test # [ 2.244915] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...970second # [ 2.249846] systemd[1]: Reached target Initrd Root Device.971second # [ 2.252696] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...972test # [ 2.285503] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks973test # [ 2.290224] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.974second # [ 2.298050] systemd-fsck[111]: nixos: clean, 12/65536 files, 13019/262144 blocks975second # [ 2.302319] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.976test # [ 2.495281] systemd[1]: Mounting /sysroot...977test # [ 2.525348] EXT4-fs (vda): mounted filesystem 090cfdd4-258a-4788-bd09-67bdaf4e6b69 r/w with ordered data mode. Quota mode: none.978test # [ 2.519710] systemd[1]: Mounted /sysroot.979test # [ 2.521038] systemd[1]: Reached target Initrd Root File System.980test # [ 2.523986] systemd[1]: Mounting /sysroot/nix/.ro-store...981test # [ 2.533835] systemd[1]: Mounting /sysroot/nix/.rw-store...982test # [ 2.541335] systemd[1]: Mounting /sysroot/run...983test # [ 2.557422] systemd[1]: Mounting /sysroot/tmp/shared...984test # [ 2.566246] systemd[1]: Mounting /sysroot/tmp/xchg...985second # [ 2.582960] systemd[1]: Mounting /sysroot...986test # [ 2.586622] systemd[1]: Starting Mountpoints Configured in the Real Root...987test # [ 2.589357] systemd[1]: Mounted /sysroot/nix/.rw-store.988second # [ 2.610888] EXT4-fs (vda): mounted filesystem cb3e193c-7f4a-4810-ba20-cf8d89f80a5f r/w with ordered data mode. Quota mode: none.989second # [ 2.607913] systemd[1]: Mounted /sysroot.990second # [ 2.609006] systemd[1]: Reached target Initrd Root File System.991second # [ 2.611664] systemd[1]: Starting Mountpoints Configured in the Real Root...992test # [ 2.617661] systemd[1]: Starting rw-sysroot-nix-store.service...993test # [ 2.619513] systemd[1]: Mounted /sysroot/nix/.ro-store.994test # [ 2.627748] systemd[1]: Mounted /sysroot/run.995second # [ 2.630215] systemd-sysroot-fstab-check[119]: /sysroot should be mounted in the initrd, will request daemon-reload.996second # [ 2.635691] systemd[1]: Reload requested from client PID 119 ('systemd-sysroot') (unit initrd-parse-etc.service)...997second # [ 2.639200] systemd[1]: Reloading...998test # [ 2.641179] systemd[1]: Mounted /sysroot/tmp/xchg.999test # [ 2.644670] systemd[1]: Mounted /sysroot/tmp/shared.1000test # [ 2.645352] systemd-sysroot-fstab-check[125]: /sysroot should be mounted in the initrd, will request daemon-reload.1001test # [ 2.654415] systemd[1]: Reload requested from client PID 125 ('systemd-sysroot') (unit initrd-parse-etc.service)...1002test # [ 2.656984] systemd[1]: Reloading...1003second # [ 2.803500] systemd[1]: Reloading finished in 165 ms.1004test # [ 2.826566] systemd[1]: Reloading finished in 169 ms.1005second # [ 2.833054] systemd-sysroot-fstab-check[119]: Requesting initrd-fs.target/start/replace...1006second # [ 2.833933] systemd-sysroot-fstab-check[119]: Requesting swap.target/start/replace...1007second # [ 2.836250] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1008second # [ 2.838583] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1009second # [ 2.846712] systemd[1]: Finished Mountpoints Configured in the Real Root.1010second # [ 2.847361] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1011second # [ 2.854442] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1012second # [ 2.856172] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1013test # [ 2.853233] systemd-sysroot-fstab-check[125]: Requesting initrd-fs.target/start/replace...1014test # [ 2.855391] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1015test # [ 2.856113] systemd[1]: Finished rw-sysroot-nix-store.service.1016test # [ 2.860817] systemd-sysroot-fstab-check[125]: Requesting swap.target/start/replace...1017test # [ 2.861523] systemd[1]: Starting rw-sysroot-nix-store.service...1018test # [ 2.864882] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1019test # [ 2.867664] systemd[1]: Finished Mountpoints Configured in the Real Root.1020test # [ 2.869262] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1021test # [ 2.887323] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1022test # [ 2.888078] systemd[1]: Finished rw-sysroot-nix-store.service.1023second # [ 3.058013] (udev-worker)[98]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1024second # [ 3.061901] (udev-worker)[98]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1025second # [ 3.074934] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1026second # [ 3.076782] systemd[1]: Stopped Virtual Console Setup.1027second # [ 3.078335] systemd[1]: Stopping Virtual Console Setup...1028second # [ 3.080549] systemd[1]: Starting Virtual Console Setup...1029test # [ 3.082021] (udev-worker)[102]: mtd0ro: Failed to find and pin callout binary "/nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/mtd_probe": No such file or directory1030test # [ 3.085724] (udev-worker)[102]: mtd0ro: /nix/store/awq6qdiyxlvdp21km39qj020fcshgkia-systemd-261.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1031second # [ 3.103438] systemd-vconsole-setup[140]: Configuration of first virtual console was skipped, ignoring remaining ones.1032second # [ 3.106667] systemd[1]: Finished Virtual Console Setup.1033test # [ 3.105948] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1034test # [ 3.108148] systemd[1]: Stopped Virtual Console Setup.1035test # [ 3.109369] systemd[1]: Stopping Virtual Console Setup...1036test # [ 3.112249] systemd[1]: Starting Virtual Console Setup...1037test # [ 3.131744] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.1038test # [ 3.134560] systemd[1]: Finished Virtual Console Setup.1039test # [ 3.496690] systemd[1]: Mounting /sysroot/nix/store...1040test # [ 3.541013] systemd[1]: Mounted /sysroot/nix/store.1041test # [ 3.544202] systemd[1]: Reached target Initrd File Systems.1042test # [ 3.546995] systemd[1]: Starting Find NixOS closure...1043test # [ 3.556384] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1044second # [ 3.587240] systemd[1]: Mounting /sysroot/nix/.ro-store...1045second # [ 3.596337] systemd[1]: Mounting /sysroot/nix/.rw-store...1046test # [ 3.596361] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1047test # [ 3.600380] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1048second # [ 3.608301] systemd[1]: Mounting /sysroot/run...1049test # [ 3.614835] systemd[1]: Finished Find NixOS closure.1050test # [ 3.618015] systemd[1]: Reached target Initrd Default Target.1051test # [ 3.619422] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1052second # [ 3.628364] systemd[1]: Mounting /sysroot/tmp/shared...1053second # [ 3.644294] systemd[1]: Mounting /sysroot/tmp/xchg...1054test # [ 3.654425] systemd[1]: Stopped target Initrd Default Target.1055second # [ 3.657574] systemd[1]: Mounted /sysroot/nix/.rw-store.1056test # [ 3.655873] systemd[1]: Stopped target Basic System.1057test # [ 3.657592] systemd[1]: Stopped target Initrd Root Device.1058test # [ 3.660153] systemd[1]: Stopped target Path Units.1059test # [ 3.660960] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1060test # [ 3.663689] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1061test # [ 3.668180] systemd[1]: Stopped target Slice Units.1062test # [ 3.668914] systemd[1]: Stopped target Socket Units.1063second # [ 3.671650] systemd[1]: Starting rw-sysroot-nix-store.service...1064test # [ 3.669550] systemd[1]: Stopped target System Initialization.1065test # [ 3.670258] systemd[1]: Stopped target Swaps.1066test # [ 3.670827] systemd[1]: Stopped target Timer Units.1067test # [ 3.671448] systemd[1]: dbus.socket: Deactivated successfully.1068second # [ 3.674348] systemd[1]: Mounted /sysroot/nix/.ro-store.1069second # [ 3.676301] systemd[1]: Mounted /sysroot/run.1070test # [ 3.676157] systemd[1]: Closed D-Bus System Message Bus Socket.1071test # [ 3.677039] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1072test # [ 3.677901] systemd[1]: Stopped Find NixOS closure.1073test # [ 3.678473] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1074test # [ 3.680056] systemd[1]: Starting rw-sysroot-nix-store.service...1075test # [ 3.684132] systemd[1]: systemd-sysctl.service: Deactivated successfully.1076test # [ 3.686926] systemd[1]: Stopped Apply Kernel Variables.1077second # [ 3.691581] systemd[1]: Mounted /sysroot/tmp/shared.1078test # [ 3.689575] systemd[1]: systemd-modules-load.service: Deactivated successfully.1079test # [ 3.691425] systemd[1]: Stopped Load Kernel Modules.1080second # [ 3.699493] systemd[1]: Mounted /sysroot/tmp/xchg.1081second # [ 3.700450] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1082second # [ 3.701470] systemd[1]: Finished rw-sysroot-nix-store.service.1083second # [ 3.703647] systemd[1]: Mounting /sysroot/nix/store...1084test # [ 3.691993] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1085test # [ 3.704794] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1086test # [ 3.709770] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1087test # [ 3.710605] systemd[1]: Stopped Create System Files and Directories.1088test # [ 3.711297] systemd[1]: Stopped target Local File Systems.1089test # [ 3.712900] systemd[1]: Stopped target Preparation for Local File Systems.1090test # [ 3.714736] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1091test # [ 3.715391] systemd[1]: Stopped Coldplug All udev Devices.1092test # [ 3.716489] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1093test # [ 3.717179] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1094test # [ 3.717813] systemd[1]: Stopped Virtual Console Setup.1095test # [ 3.718271] systemd[1]: initrd-cleanup.service: Deactivated successfully.1096test # [ 3.718847] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1097test # [ 3.719409] systemd[1]: systemd-udevd.service: Deactivated successfully.1098test # [ 3.719976] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1099test # [ 3.725550] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1100second # [ 3.729309] systemd[1]: Mounted /sysroot/nix/store.1101second # [ 3.730076] systemd[1]: Reached target Initrd File Systems.1102test # [ 3.728160] systemd[1]: Closed udev Control Socket.1103test # [ 3.728737] systemd[1]: Starting Cleanup udev Database...1104second # [ 3.731581] systemd[1]: Starting Find NixOS closure...1105test # [ 3.729239] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1106test # [ 3.732203] systemd[1]: Stopped Create Static Device Nodes in /dev.1107test # [ 3.732763] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1108test # [ 3.733412] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1109test # [ 3.734101] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1110test # [ 3.736568] systemd[1]: Stopped Create List of Static Device Nodes.1111second # [ 3.739142] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1112test # [ 3.737155] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1113test # [ 3.740343] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1114test # [ 3.740898] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1115test # [ 3.741512] systemd[1]: Finished rw-sysroot-nix-store.service.1116test # [ 3.748974] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1117test # [ 3.750262] systemd[1]: Finished Cleanup udev Database.1118test # [ 3.752103] systemd[1]: Reached target Switch Root.1119test # [ 3.752633] systemd[1]: Starting NixOS Activation...1120second # [ 3.760552] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1121second # [ 3.772106] systemd[1]: Finished Find NixOS closure.1122second # [ 3.773878] systemd[1]: Reached target Initrd Default Target.1123second # [ 3.774846] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1124second # [ 3.794096] systemd[1]: Stopped target Initrd Default Target.1125second # [ 3.795454] systemd[1]: Stopped target Basic System.1126second # [ 3.796505] systemd[1]: Stopped target Initrd Root Device.1127second # [ 3.797471] systemd[1]: Stopped target Path Units.1128second # [ 3.800264] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1129second # [ 3.801110] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1130second # [ 3.802524] systemd[1]: Stopped target Slice Units.1131second # [ 3.803513] systemd[1]: Stopped target Socket Units.1132second # [ 3.807911] systemd[1]: Stopped target System Initialization.1133second # [ 3.808653] systemd[1]: Stopped target Swaps.1134second # [ 3.809129] systemd[1]: Stopped target Timer Units.1135second # [ 3.809626] systemd[1]: dbus.socket: Deactivated successfully.1136second # [ 3.810204] systemd[1]: Closed D-Bus System Message Bus Socket.1137second # [ 3.810794] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1138second # [ 3.811796] systemd[1]: Stopped Find NixOS closure.1139second # [ 3.812993] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1140second # [ 3.822720] systemd[1]: Starting rw-sysroot-nix-store.service...1141second # [ 3.823525] systemd[1]: systemd-sysctl.service: Deactivated successfully.1142second # [ 3.825349] systemd[1]: Stopped Apply Kernel Variables.1143second # [ 3.826014] systemd[1]: systemd-modules-load.service: Deactivated successfully.1144second # [ 3.832198] systemd[1]: Stopped Load Kernel Modules.1145second # [ 3.832804] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1146second # [ 3.835871] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1147second # [ 3.836815] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1148second # [ 3.838701] systemd[1]: Stopped Create System Files and Directories.1149second # [ 3.840275] systemd[1]: Stopped target Local File Systems.1150second # [ 3.841641] systemd[1]: Stopped target Preparation for Local File Systems.1151second # [ 3.843184] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1152second # [ 3.843864] systemd[1]: Stopped Coldplug All udev Devices.1153second # [ 3.845515] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1154second # [ 3.846235] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1155second # [ 3.846897] systemd[1]: Stopped Virtual Console Setup.1156second # [ 3.847407] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1157second # [ 3.853703] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1158second # [ 3.854352] systemd[1]: systemd-udevd.service: Deactivated successfully.1159second # [ 3.855256] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1160second # [ 3.858180] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1161second # [ 3.859270] systemd[1]: Finished rw-sysroot-nix-store.service.1162second # [ 3.860750] systemd[1]: initrd-cleanup.service: Deactivated successfully.1163second # [ 3.861725] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1164second # [ 3.864156] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1165second # [ 3.864845] systemd[1]: Closed udev Control Socket.1166second # [ 3.865562] systemd[1]: Starting Cleanup udev Database...1167second # [ 3.868328] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1168second # [ 3.869100] systemd[1]: Stopped Create Static Device Nodes in /dev.1169second # [ 3.869672] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1170second # [ 3.871883] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1171second # [ 3.872786] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1172second # [ 3.873409] systemd[1]: Stopped Create List of Static Device Nodes.1173second # [ 3.895718] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1174second # [ 3.898173] systemd[1]: Finished Cleanup udev Database.1175second # [ 3.898683] systemd[1]: Reached target Switch Root.1176second # [ 3.899387] systemd[1]: Starting NixOS Activation...1177test # [ 3.926894] initrd-nixos-activation-start[180]: booting system configuration /nix/store/3ji2r193lkaf461yxs3pg3xqr57zn9p6-nixos-system-test-test1178test # [ 3.993549] initrd-nixos-activation-start[180]: running activation script...1179second # [ 4.074611] initrd-nixos-activation-start[177]: booting system configuration /nix/store/6mm6azwqpgwq914mxh4a6fdrwn8dx9vz-nixos-system-second-test1180second # [ 4.116327] initrd-nixos-activation-start[177]: running activation script...1181test # [ 4.330649] initrd-nixos-activation-start[203]: setting up /etc...1182second # [ 4.504549] initrd-nixos-activation-start[200]: setting up /etc...1183test # [ 4.533897] initrd-nixos-activation-start[203]: setting up age secrets...1184second # [ 4.754065] initrd-nixos-activation-start[200]: setting up age secrets...1185test # [ 4.759797] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1186test # [ 4.761648] systemd[1]: Finished NixOS Activation.1187test # [ 4.764103] systemd[1]: Starting Switch Root...1188test # [ 4.776563] systemd[1]: Switching root.1189test # [ 4.947124] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1190second # [ 4.956821] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1191second # [ 4.958503] systemd[1]: Finished NixOS Activation.1192second # [ 4.959254] systemd[1]: Starting Switch Root...1193second # [ 4.972465] systemd[1]: Switching root.1194second # [ 5.148421] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1195test # [ 5.539314] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1196test # [ 5.546429] systemd[1]: Detected virtualization qemu.1197test # [ 5.548106] systemd[1]: Detected architecture arm64.1198test # [ 5.550348] systemd[1]: Detected first boot.1199test # [ 5.555235] systemd[1]: Initializing machine ID from random generator.1200second # [ 5.741578] systemd[1]: systemd 261.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1201second # [ 5.748921] systemd[1]: Detected virtualization qemu.1202second # [ 5.750743] systemd[1]: Detected architecture arm64.1203second # [ 5.752857] systemd[1]: Detected first boot.1204second # [ 5.758211] systemd[1]: Initializing machine ID from random generator.1205test # [ 5.836752] systemd[1]: bpf-restrict-fs: LSM BPF program attached1206test # [ 6.033906] systemd[1]: Applying preset policy.1207second # [ 6.058051] systemd[1]: bpf-restrict-fs: LSM BPF program attached1208second # [ 6.245745] systemd[1]: Applying preset policy.1209test # [ 6.608454] systemd[1]: Populated /etc with preset unit settings.1210second # [ 6.817115] systemd[1]: Populated /etc with preset unit settings.1211test # [ 7.181313] systemd[1]: initrd-switch-root.service: Deactivated successfully.1212test # [ 7.182160] systemd[1]: Stopped initrd-switch-root.service.1213test # [ 7.184000] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1214test # [ 7.186935] systemd[1]: Created slice Slice /system/getty.1215test # [ 7.188407] systemd[1]: Created slice User and Session Slice.1216test # [ 7.189774] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1217test # [ 7.189858] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1218test # [ 7.189904] systemd[1]: Expecting device /dev/hvc0...1219test # [ 7.189922] systemd[1]: Expecting device /dev/ttyAMA0...1220test # [ 7.190318] systemd[1]: Reached target Local Encrypted Volumes.1221test # [ 7.190593] systemd[1]: Stopped target initrd-fs.target.1222test # [ 7.190862] systemd[1]: Stopped target initrd-root-fs.target.1223test # [ 7.191105] systemd[1]: Stopped target initrd-switch-root.target.1224test # [ 7.191354] systemd[1]: Reached target Virtual Machines and Containers.1225test # [ 7.191604] systemd[1]: Reached target Path Units.1226test # [ 7.191843] systemd[1]: Reached target Remote File Systems.1227test # [ 7.192072] systemd[1]: Reached target Slice Units.1228test # [ 7.192303] systemd[1]: Reached target Swaps.1229test # [ 7.200956] systemd[1]: Listening on Query the User Interactively for a Password.1230test # [ 7.205240] systemd[1]: Listening on Process Core Dump Socket.1231test # [ 7.208574] systemd[1]: Listening on Credential Encryption/Decryption.1232test # [ 7.211981] systemd[1]: Listening on Factory Reset Management.1233test # [ 7.213013] systemd[1]: Listening on Hostname Service Socket.1234test # [ 7.217405] systemd[1]: Starting Journal Log Access Socket...1235test # [ 7.219340] systemd[1]: Listening on Journal Audit Socket.1236test # [ 7.222956] systemd[1]: Listening on Console Output Muting Service Socket.1237test # [ 7.224556] systemd[1]: Listening on Network Management Metrics Varlink Socket.1238test # [ 7.226432] systemd[1]: Listening on Network Management Varlink Socket.1239test # [ 7.228052] systemd[1]: Listening on Network Management Netlink Socket.1240test # [ 7.230377] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1241test # [ 7.231937] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1242test # [ 7.233746] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1243test # [ 7.245673] systemd[1]: Listening on Disk Repartitioning Service Socket.1244test # [ 7.247215] systemd[1]: Listening on Resolve Monitor Varlink Socket.1245test # [ 7.248723] systemd[1]: Listening on Resolve Service Varlink Socket.1246test # [ 7.250231] systemd[1]: Listening on udev Control Socket.1247test # [ 7.251662] systemd[1]: Listening on udev Varlink Socket.1248test # [ 7.255415] systemd[1]: Mounting Huge Pages File System...1249test # [ 7.259135] systemd[1]: Mounting POSIX Message Queue File System...1250test # [ 7.267278] systemd[1]: Mounting Kernel Debug File System...1251test # [ 7.271349] systemd[1]: Mounting Kernel Trace File System...1252test # [ 7.282459] systemd[1]: Starting Create List of Static Device Nodes...1253test # [ 7.291016] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1254test # [ 7.292936] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1255test # [ 7.308701] systemd[1]: Mounting Kernel Configuration File System...1256test # [ 7.310636] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1257test # [ 7.314725] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1258test # [ 7.322395] systemd[1]: Starting Load Kernel Module fuse...1259test # [ 7.323820] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671260test # [ 7.377495] systemd[1]: Starting Journal Service...1261second # [ 7.407548] systemd[1]: initrd-switch-root.service: Deactivated successfully.1262test # [ 7.406818] systemd[1]: Starting Load Kernel Modules...1263second # [ 7.408436] systemd[1]: Stopped initrd-switch-root.service.1264second # [ 7.410630] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1265second # [ 7.413218] systemd[1]: Created slice Slice /system/getty.1266second # [ 7.414472] systemd[1]: Created slice User and Session Slice.1267second # [ 7.415660] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1268second # [ 7.416995] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1269second # [ 7.418311] systemd[1]: Expecting device /dev/hvc0...1270second # [ 7.419325] systemd[1]: Expecting device /dev/ttyAMA0...1271second # [ 7.420368] systemd[1]: Reached target Local Encrypted Volumes.1272second # [ 7.421464] systemd[1]: Stopped target initrd-fs.target.1273second # [ 7.422634] systemd[1]: Stopped target initrd-root-fs.target.1274second # [ 7.423579] systemd[1]: Stopped target initrd-switch-root.target.1275second # [ 7.424622] systemd[1]: Reached target Virtual Machines and Containers.1276second # [ 7.425694] systemd[1]: Reached target Path Units.1277second # [ 7.426620] systemd[1]: Reached target Remote File Systems.1278second # [ 7.428106] systemd[1]: Reached target Slice Units.1279second # [ 7.429512] systemd[1]: Reached target Swaps.1280test # [ 7.432117] fuse: init (API version 7.45)1281second # [ 7.433622] systemd[1]: Listening on Query the User Interactively for a Password.1282test # [ 7.435317] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1283second # [ 7.438380] systemd[1]: Listening on Process Core Dump Socket.1284second # [ 7.442051] systemd[1]: Listening on Credential Encryption/Decryption.1285second # [ 7.445680] systemd[1]: Listening on Factory Reset Management.1286second # [ 7.446844] systemd[1]: Listening on Hostname Service Socket.1287second # [ 7.451703] systemd[1]: Starting Journal Log Access Socket...1288second # [ 7.453780] systemd[1]: Listening on Journal Audit Socket.1289test # [ 7.455329] systemd[1]: Starting Remount Root and Kernel File Systems...1290second # [ 7.457748] systemd[1]: Listening on Console Output Muting Service Socket.1291second # [ 7.459662] systemd[1]: Listening on Network Management Metrics Varlink Socket.1292second # [ 7.461446] systemd[1]: Listening on Network Management Varlink Socket.1293second # [ 7.462740] systemd[1]: Listening on Network Management Netlink Socket.1294test # [ 7.461449] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1295second # [ 7.464496] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1296second # [ 7.466286] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1297second # [ 7.467981] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1298second # [ 7.477943] systemd[1]: Listening on Disk Repartitioning Service Socket.1299second # [ 7.479058] systemd[1]: Listening on Resolve Monitor Varlink Socket.1300second # [ 7.480138] systemd[1]: Listening on Resolve Service Varlink Socket.1301second # [ 7.481224] systemd[1]: Listening on udev Control Socket.1302second # [ 7.482269] systemd[1]: Listening on udev Varlink Socket.1303test # [ 7.486219] systemd[1]: Starting Coldplug All udev Devices...1304second # [ 7.486177] systemd[1]: Mounting Huge Pages File System...1305second # [ 7.490034] systemd[1]: Mounting POSIX Message Queue File System...1306test # [ 7.493559] systemd-journald[328]: Collecting audit messages is enabled.1307second # [ 7.497569] systemd[1]: Mounting Kernel Debug File System...1308test # [ 7.499286] systemd[1]: Listening on Journal Log Access Socket.1309second # [ 7.501573] systemd[1]: Mounting Kernel Trace File System...1310test # [ 7.504717] systemd[1]: Mounted Huge Pages File System.1311second # [ 7.510481] systemd[1]: Starting Create List of Static Device Nodes...1312test # [ 7.514964] systemd[1]: Mounted POSIX Message Queue File System.1313test # [ 7.516918] systemd[1]: Mounted Kernel Debug File System.1314test # [ 7.510351] systemd[1]: Queued start job for default target Multi-User System.1315test # [ 7.511211] systemd[1]: systemd-journald.service: Deactivated successfully.1316test # [ 7.520882] systemd[1]: Started Journal Service.1317second # [ 7.522552] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1318test # [ 7.515014] systemd-modules-load[329]: Module 'atkbd' is built in1319second # [ 7.525268] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1320test # [ 7.519978] systemd-modules-load[329]: Module 'ctr' is built in1321test # [ 7.526256] systemd-modules-load[329]: Module 'loop' is built in1322test # [ 7.526908] systemd[1]: Mounted Kernel Trace File System.1323second # [ 7.535548] systemd[1]: Mounting Kernel Configuration File System...1324test # [ 7.527512] systemd[1]: Finished Create List of Static Device Nodes.1325second # [ 7.538335] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1326second # [ 7.540496] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1327test # [ 7.531370] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1328test # [ 7.540188] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1329test # [ 7.542474] systemd[1]: Mounted Kernel Configuration File System.1330second # [ 7.552290] systemd[1]: Starting Load Kernel Module fuse...1331second # [ 7.553246] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671332test # [ 7.546374] systemd[1]: modprobe@fuse.service: Deactivated successfully.1333test # [ 7.552164] systemd[1]: Finished Load Kernel Module fuse.1334test # [ 7.558997] systemd[1]: Finished Load Kernel Modules.1335test # [ 7.561939] systemd[1]: Mounting FUSE Control File System...1336test # [ 7.565336] systemd[1]: Starting Firewall...1337test # [ 7.576242] systemd-oomd[331]: No swap; memory pressure usage will be degraded1338test # [ 7.588779] EXT4-fs (vda): re-mounted 090cfdd4-258a-4788-bd09-67bdaf4e6b69.1339test # [ 7.593338] systemd[1]: Starting Apply Kernel Variables...1340test # [ 7.599472] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1341test # [ 7.609405] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1342test # [ 7.610138] systemd[1]: Finished Remount Root and Kernel File Systems.1343second # [ 7.619810] systemd[1]: Starting Journal Service...1344test # [ 7.621761] systemd[1]: Listening on Disk Image Download Service Socket.1345test # [ 7.639075] systemd[1]: Starting Flush Journal to Persistent Storage...1346test # [ 7.645611] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1347second # [ 7.656477] systemd[1]: Starting Load Kernel Modules...1348test # [ 7.651754] systemd[1]: Starting Load/Save OS Random Seed...1349test # [ 7.652508] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1350second # [ 7.689448] fuse: init (API version 7.45)1351second # [ 7.690022] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1352second # [ 7.700306] systemd[1]: Starting Remount Root and Kernel File Systems...1353test # [ 7.697603] systemd[1]: Mounted FUSE Control File System.1354second # [ 7.705919] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1355second # [ 7.722934] systemd[1]: Starting Coldplug All udev Devices...1356second # [ 7.742882] systemd[1]: Listening on Journal Log Access Socket.1357second # [ 7.743339] systemd[1]: Mounted Huge Pages File System.1358second # [ 7.743711] systemd[1]: Mounted POSIX Message Queue File System.1359second # [ 7.744065] systemd[1]: Mounted Kernel Debug File System.1360second # [ 7.744409] systemd[1]: Mounted Kernel Trace File System.1361test # [ 7.743339] systemd[1]: Finished Apply Kernel Variables.1362second # [ 7.756830] systemd[1]: Finished Create List of Static Device Nodes.1363second # [ 7.760223] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1364test # [ 7.760277] systemd-journald[328]: Received client request to flush runtime journal.1365second # [ 7.764084] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1366second # [ 7.766935] systemd[1]: Mounted Kernel Configuration File System.1367second # [ 7.775358] systemd[1]: modprobe@fuse.service: Deactivated successfully.1368second # [ 7.779272] systemd[1]: Finished Load Kernel Module fuse.1369second # [ 7.783286] systemd[1]: Finished Load Kernel Modules.1370second # [ 7.791208] systemd-journald[305]: Collecting audit messages is enabled.1371second # [ 7.797581] systemd[1]: Mounting FUSE Control File System...1372second # [ 7.803525] systemd[1]: Queued start job for default target Multi-User System.1373second # [ 7.804437] systemd[1]: systemd-journald.service: Deactivated successfully.1374second # [ 7.805207] systemd-modules-load[306]: Module 'atkbd' is built in1375second # [ 7.805849] systemd-modules-load[306]: Module 'ctr' is built in1376second # [ 7.806478] systemd-modules-load[306]: Module 'loop' is built in1377second # [ 7.818210] systemd[1]: Starting Firewall...1378second # [ 7.822297] EXT4-fs (vda): re-mounted cb3e193c-7f4a-4810-ba20-cf8d89f80a5f.1379test # [ 7.816719] systemd[1]: Starting Network Name Resolution...1380second # [ 7.827111] systemd[1]: Starting Apply Kernel Variables...1381test # [ 7.821775] systemd[1]: Finished Load/Save OS Random Seed.1382test # [ 7.824414] systemd[1]: Reached target First Boot Complete.1383test # [ 7.824997] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1384test # [ 7.831660] systemd[1]: Starting Create Static Device Nodes in /dev...1385test # [ 7.834388] systemd[1]: Finished Flush Journal to Persistent Storage.1386second # [ 7.853676] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1387second # [ 7.854776] systemd[1]: Started Journal Service.1388second # [ 7.877872] systemd[1]: Finished Remount Root and Kernel File Systems.1389second # [ 7.880488] systemd-oomd[307]: No swap; memory pressure usage will be degraded1390second # [ 7.895528] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1391test # [ 7.921047] systemd[1]: Finished Create Static Device Nodes in /dev.1392test # [ 7.922600] systemd[1]: Reached target Preparation for Local File Systems.1393second # [ 7.927220] systemd[1]: Listening on Disk Image Download Service Socket.1394test # [ 7.926632] systemd-resolved[357]: Positive Trust Anchors:1395test # [ 7.929619] systemd-resolved[357]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1396test # [ 7.932248] systemd-resolved[357]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161397second # [ 7.941195] systemd[1]: Starting Flush Journal to Persistent Storage...1398second # [ 7.941939] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1399second # [ 7.946744] systemd[1]: Starting Load/Save OS Random Seed...1400second # [ 7.947679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1401test # [ 7.936893] systemd-resolved[357]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test1402second # [ 7.956138] systemd[1]: Mounted FUSE Control File System.1403second # [ 7.966724] systemd[1]: Finished Apply Kernel Variables.1404test # [ 7.964591] systemd[1]: Starting Rule-based Manager for Device Events and Files...1405second # [ 7.995492] systemd[1]: Starting Network Name Resolution...1406second # [ 8.017948] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1407second # [ 8.025738] systemd[1]: Starting Create Static Device Nodes in /dev...1408second # [ 8.030716] systemd[1]: Finished Load/Save OS Random Seed.1409second # [ 8.032265] systemd[1]: Reached target First Boot Complete.1410second # [ 8.054699] systemd-journald[305]: Received client request to flush runtime journal.1411test # [ 8.058955] systemd-udevd[371]: Using default interface naming scheme 'v261'.1412second # [ 8.122896] systemd[1]: Finished Flush Journal to Persistent Storage.1413second # [ 8.156125] systemd[1]: Finished Create Static Device Nodes in /dev.1414second # [ 8.156774] systemd[1]: Reached target Preparation for Local File Systems.1415second # [ 8.161310] systemd[1]: Starting Rule-based Manager for Device Events and Files...1416test # [ 8.176633] systemd[1]: Mounting /run/wrappers...1417second # [ 8.189507] systemd-resolved[334]: Positive Trust Anchors:1418test # [ 8.192757] systemd-resolved[357]: Using system hostname 'test'.1419second # [ 8.191083] systemd-resolved[334]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1420second # [ 8.196479] systemd-resolved[334]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161421test # [ 8.200758] systemd[1]: Started Network Name Resolution.1422second # [ 8.198107] systemd-resolved[334]: 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 test1423test # [ 8.245649] systemd[1]: Mounted /run/wrappers.1424test # [ 8.246240] systemd[1]: Reached target Local File Systems.1425test # [ 8.249932] systemd[1]: Listening on Boot Loader Control Service Socket.1426test # [ 8.254413] systemd[1]: Starting register-nix-paths.service...1427test # [ 8.257393] systemd[1]: Starting Create SUID/SGID Wrappers...1428test # [ 8.258103] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1429test # [ 8.262176] systemd[1]: Starting Save Transient machine-id to Disk...1430second # [ 8.301169] systemd-udevd[347]: Using default interface naming scheme 'v261'.1431test # [ 8.298471] systemd[1]: Starting Create System Files and Directories...1432second # [ 8.407785] systemd[1]: Mounting /run/wrappers...1433test # [ 8.406749] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1434test # [ 8.409023] systemd[1]: Started Rule-based Manager for Device Events and Files.1435test # [ 8.410651] systemd[1]: Finished Save Transient machine-id to Disk.1436second # [ 8.460400] systemd-resolved[334]: Using system hostname 'second'.1437second # [ 8.464332] systemd[1]: Mounted /run/wrappers.1438second # [ 8.466310] systemd[1]: Reached target Local File Systems.1439second # [ 8.471461] systemd[1]: Listening on Boot Loader Control Service Socket.1440test # [ 8.471592] systemd[1]: Finished Create System Files and Directories.1441second # [ 8.478883] systemd[1]: Starting register-nix-paths.service...1442second # [ 8.481167] systemd[1]: Starting Create SUID/SGID Wrappers...1443second # [ 8.483664] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1444test # [ 8.490904] systemd[1]: Starting Rebuild Journal Catalog...1445test # [ 8.493177] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1446second # [ 8.503922] systemd[1]: Starting Save Transient machine-id to Disk...1447second # [ 8.520961] systemd[1]: Starting Create System Files and Directories...1448second # [ 8.524106] systemd[1]: Started Network Name Resolution.1449test # [ 8.603619] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1450second # [ 8.616101] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1451second # [ 8.622744] systemd[1]: Finished Save Transient machine-id to Disk.1452test # [ 8.633415] systemd[1]: Finished Coldplug All udev Devices.1453test # [ 8.675123] systemd[1]: Finished Rebuild Journal Catalog.1454test # [ 8.682454] systemd[1]: Starting Update is Completed...1455second # [ 8.691455] systemd[1]: Started Rule-based Manager for Device Events and Files.1456second # [ 8.702093] systemd[1]: Finished Create System Files and Directories.1457second # [ 8.706415] systemd[1]: Starting Rebuild Journal Catalog...1458second # [ 8.728845] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1459test # [ 8.745371] systemd[1]: Finished Update is Completed.1460second # [ 8.827788] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1461test # [ 8.874638] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1462second # [ 8.903928] systemd[1]: Finished Rebuild Journal Catalog.1463second # [ 8.912540] systemd[1]: Starting Update is Completed...1464second # [ 8.931223] systemd[1]: Finished Coldplug All udev Devices.1465second # [ 8.987317] systemd[1]: Finished Update is Completed.1466test # [ 9.102204] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1467test # [ 9.104262] systemd[1]: Finished Create SUID/SGID Wrappers.1468test # [ 9.113177] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1469test # [ 9.157867] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1470second # [ 9.165973] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1471second # [ 9.362340] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1472second # [ 9.364591] systemd[1]: Finished Create SUID/SGID Wrappers.1473second # [ 9.403848] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1474second # [ 9.461604] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1475test # [ 9.476057] (udev-worker)[479]: Network interface NamePolicy= disabled on kernel command line.1476test # [ 9.478076] systemd[1]: Finished Firewall.1477test # [ 9.478552] systemd[1]: Reached target Preparation for Network.1478test # [ 9.479861] systemd[1]: Listening on Network Management Resolve Hook Socket.1479test # [ 9.482039] (udev-worker)[475]: Network interface NamePolicy= disabled on kernel command line.1480test # [ 9.485384] systemd[1]: Starting Network Management...1481test # [ 9.487184] systemd[1]: Finished register-nix-paths.service.1482test # [ 9.490041] systemd[1]: Reached target System Initialization.1483test # [ 9.492398] systemd[1]: Started Discard unused filesystem blocks once a week.1484test # [ 9.494561] systemd[1]: Started Daily Cleanup of Temporary Directories.1485test # [ 9.496802] systemd[1]: Reached target Timer Units.1486test # [ 9.497804] systemd[1]: Listening on D-Bus System Message Bus Socket.1487test # [ 9.500732] systemd[1]: Listening on Nix Daemon Socket.1488test # [ 9.510254] systemd[1]: Listening on Authorization Manager Agent Helper.1489test # [ 9.512214] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1490test # [ 9.513954] systemd[1]: Reached target Socket Units.1491test # [ 9.523167] systemd[1]: Starting D-Bus System Message Bus...1492second # [ 9.656334] systemd[1]: Finished register-nix-paths.service.1493second # [ 9.659000] systemd[1]: Reached target System Initialization.1494second # [ 9.661326] systemd[1]: Started Discard unused filesystem blocks once a week.1495second # [ 9.664346] systemd[1]: Started Daily Cleanup of Temporary Directories.1496second # [ 9.666624] systemd[1]: Reached target Timer Units.1497second # [ 9.668315] systemd[1]: Listening on D-Bus System Message Bus Socket.1498second # [ 9.671555] systemd[1]: Listening on Nix Daemon Socket.1499second # [ 9.675451] systemd[1]: Listening on Authorization Manager Agent Helper.1500second # [ 9.677106] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.1501second # [ 9.678186] systemd[1]: Reached target Socket Units.1502second # [ 9.681275] systemd[1]: Starting D-Bus System Message Bus...1503second # [ 9.684257] systemd[1]: Finished Firewall.1504second # [ 9.685097] systemd[1]: Reached target Preparation for Network.1505second # [ 9.690701] systemd[1]: Listening on Network Management Resolve Hook Socket.1506test # [ 9.694395] mousedev: PS/2 mouse device common for all mice1507second # [ 9.696685] systemd[1]: Starting Network Management...1508test # [ 9.709800] cfg80211: Loading compiled-in X.509 certificates for regulatory database1509test # [ 9.726347] dbus-broker-launch[554]: Looking up NSS user entry for 'systemd-timesync'...1510test # [ 9.745245] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1511test # [ 9.745746] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1512test # [ 9.742249] dbus-broker-launch[554]: NSS returned no entry for 'systemd-timesync'1513test # [ 9.742985] dbus-broker-launch[554]: Invalid user-name in /nix/store/3kbz1c7kf55s2gymixbphi46vczkadln-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1514second # [ 9.757853] (udev-worker)[449]: Network interface NamePolicy= disabled on kernel command line.1515second # [ 9.761711] (udev-worker)[460]: Network interface NamePolicy= disabled on kernel command line.1516test # [ 9.792813] systemd-networkd[550]: /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.1517test # [ 9.796784] systemd-networkd[550]: /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.1518test # [ 9.804487] dbus-broker-launch[554]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1519test # [ 9.811240] dbus-broker-launch[554]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1520test # [ 9.824357] systemd-networkd[550]: lo: Link UP1521test # [ 9.828986] systemd-networkd[550]: lo: Gained carrier1522test # [ 9.829499] dbus-broker-launch[554]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/9prxmvwvdr9mfqbb38w7jq4kicc7zi18-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1523test # [ 9.831233] systemd[1]: Started Network Management.1524test # [ 9.831693] dbus-broker-launch[554]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/5cpm90flydbxw9km2kl7pk8i1i5sc3nk-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1525test # [ 9.843757] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1526test # [ 9.848325] systemd[1]: Started D-Bus System Message Bus.1527test # [ 9.849638] systemd[1]: Reached target Basic System.1528test # [ 9.853050] systemd-networkd[550]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1529test # [ 9.864229] systemd-networkd[550]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1530test # [ 9.865015] systemd-networkd[550]: eth1: Link UP1531test # [ 9.865453] systemd-networkd[550]: eth1: Gained carrier1532test # [ 9.865922] systemd-networkd[550]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1533test # [ 9.867223] systemd[1]: Starting Network Manager Script Dispatcher Service...1534test # [ 9.867845] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1535second # [ 9.872828] dbus-broker-launch[526]: Looking up NSS user entry for 'systemd-timesync'...1536second # [ 9.887324] dbus-broker-launch[526]: NSS returned no entry for 'systemd-timesync'1537test # [ 9.888110] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1538second # [ 9.892778] dbus-broker-launch[526]: Invalid user-name in /nix/store/3kbz1c7kf55s2gymixbphi46vczkadln-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1539test # [ 9.902397] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1540second # [ 9.919039] cfg80211: Loading compiled-in X.509 certificates for regulatory database1541test # [ 9.917428] dbus-broker-launch[554]: Ready1542second # [ 9.936343] mousedev: PS/2 mouse device common for all mice1543test # [ 9.934610] systemd[1]: Starting Network Manager...1544second # [ 9.956944] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1545second # [ 9.957467] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1546test # [ 9.954401] systemd[1]: Started backdoor.service.1547second # [ 9.959450] dbus-broker-launch[526]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1548second # [ 9.964325] dbus-broker-launch[526]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/pdjzsjaza8krvfmvr6a1j515biym3hbw-networkmanager-1.58.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1549test # [ 9.966920] systemd[1]: Starting Import lastlog data into lastlog2 database...1550second # [ 9.969382] dbus-broker-launch[526]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/9prxmvwvdr9mfqbb38w7jq4kicc7zi18-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1551second # [ 9.979669] dbus-broker-launch[526]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/5cpm90flydbxw9km2kl7pk8i1i5sc3nk-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1552second # [ 9.988917] systemd[1]: Started D-Bus System Message Bus.1553second # [ 9.992520] systemd[1]: Reached target Basic System.1554test # [ 9.994445] dhnniqqyxyx1kvmbmrrk9766fbhx5chi-wifi-secrets[563]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1555second # [ 9.995500] systemd[1]: Starting Network Manager Script Dispatcher Service...1556second # [ 10.004366] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1557second # [ 10.005002] systemd[1]: Starting Network Manager...1558test # [ 10.013661] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1559second # [ 10.036173] systemd[1]: Started backdoor.service.1560test # [ 10.046741] systemd[1]: Starting Post-Boot Actions...1561second # [ 10.057352] systemd[1]: Starting Import lastlog data into lastlog2 database...1562second # [ 10.066011] 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.1563second # [ 10.069349] 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.1564test # [ 10.075602] kmrn99zb7hfffrbx2s86i3g3dg0y4036-wifi-secrets[566]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1565second # [ 10.080467] dbus-broker-launch[526]: Ready1566second # [ 10.082711] systemd-networkd[528]: lo: Link UP1567second # [ 10.084123] systemd-networkd[528]: lo: Gained carrier1568test # [ 10.086149] systemd[1]: Started Reset console on configuration changes.1569second # [ 10.089973] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1570test # [ 10.095625] r3qn2wrxq8bfm2mry46agf2zjadxngay-wifi-secrets[564]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1571second # [ 10.101308] dhnniqqyxyx1kvmbmrrk9766fbhx5chi-wifi-secrets[539]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1572second # [ 10.110314] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1573test # [ 10.109921] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1574test # [ 10.115524] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1575second # [ 10.119153] systemd-networkd[528]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1576second # [ 10.132804] systemd-networkd[528]: eth1: Link UP1577test # [ 10.123537] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1578second # [ 10.137232] systemd-networkd[528]: eth1: Gained carrier1579test # [ 10.131227] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1580test # [ 10.139093] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1581second # [ 10.144092] systemd-networkd[528]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1582second # [ 10.153323] systemd[1]: Starting Post-Boot Actions...1583test # [ 10.154576] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1584test # connecting to host...1585second # [ 10.159554] systemd[1]: Started Reset console on configuration changes.1586second # [ 10.167236] systemd[1]: Started Network Management.1587second # [ 10.167715] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1588test # [ 10.161890] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1589test # [ 10.174664] systemd[1]: Finished Post-Boot Actions.1590second # [ 10.175631] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1591test: Guest shell says: b'Spawning backdoor root shell...\n'1592test # [ 10.222402] nsncd[571]: Aug 31 15:04:19.048 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1593test # [ 10.231504] systemd[1]: Started Name Service Cache Daemon (nsncd).1594test # [ 10.246591] systemd[1]: Reached target Host and Network Name Lookups.1595test # [ 10.249952] systemd[1]: Reached target User and Group Name Lookups.1596second # connecting to host...1597test # [ 10.261438] systemd[1]: Starting User Login Management...1598second # [ 10.265335] systemd[1]: Finished Post-Boot Actions.1599test: connected to guest root shell1600second # [ 10.276614] nsncd[543]: Aug 31 15:04:19.111 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1601test: (connecting took 10.53 seconds)1602second # [ 10.284173] systemd[1]: Started Name Service Cache Daemon (nsncd).1603test: (finished: waiting for the VM to finish booting, in 10.53 seconds)1604second # [ 10.295301] systemd[1]: Reached target Host and Network Name Lookups.1605second # [ 10.301153] systemd[1]: Reached target User and Group Name Lookups.1606test # [ 10.299327] systemd[1]: Finished Import lastlog data into lastlog2 database.1607test # [ 10.299982] systemd[1]: Started Network Manager Script Dispatcher Service.1608second # [ 10.311464] systemd[1]: Starting User Login Management...1609second # [ 10.322672] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1610second # [ 10.354783] systemd[1]: Finished Import lastlog data into lastlog2 database.1611test # [ 10.415708] systemd-logind[609]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1612test # [ 10.419780] systemd-logind[609]: Watching system buttons on /dev/input/event0 (gpio-keys)1613test # [ 10.422919] systemd-logind[609]: New seat seat0.1614test # [ 10.426187] systemd[1]: Started User Login Management.1615test # [ 10.436122] systemd[1]: Starting linger-users.service...1616test # [ 10.439999] systemd[1]: Starting Hostname Service...1617second # [ 10.449422] systemd[1]: Started Network Manager Script Dispatcher Service.1618second # [ 10.454045] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1619second # [ 10.496597] systemd-logind[571]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1620second # [ 10.499895] systemd-logind[571]: Watching system buttons on /dev/input/event0 (gpio-keys)1621test # [ 10.502154] systemd[1]: linger-users.service: Deactivated successfully.1622second # [ 10.505009] systemd-logind[571]: New seat seat0.1623second # [ 10.505576] systemd[1]: Started User Login Management.1624test # [ 10.508224] systemd[1]: Finished linger-users.service.1625second # [ 10.512989] systemd[1]: Starting linger-users.service...1626second # [ 10.529453] systemd[1]: Starting Hostname Service...1627test # [ 10.554556] systemd[1]: Started Hostname Service.1628second # [ 10.585337] systemd[1]: linger-users.service: Deactivated successfully.1629second # [ 10.588298] systemd[1]: Finished linger-users.service.1630second # [ 10.643900] systemd[1]: Started Hostname Service.1631test # [ 10.650119] systemd-networkd[550]: eth0: Link UP1632test # [ 10.650600] systemd-networkd[550]: eth0: Gained carrier1633test # [ 10.668820] systemd[1]: Started Network Manager.1634test # [ 10.670912] systemd[1]: Reached target Network.1635test # [ 10.676435] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1636test # [ 10.682476] systemd[1]: Starting Permit User Sessions...1637second # [ 10.735727] systemd-networkd[528]: eth0: Link UP1638second # [ 10.736311] systemd-networkd[528]: eth0: Gained carrier1639second # [ 10.754209] systemd[1]: Started Network Manager.1640second # [ 10.756354] systemd[1]: Reached target Network.1641second # [ 10.761340] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1642second # [ 10.765891] systemd[1]: Starting Permit User Sessions...1643test # [ 10.822774] systemd[1]: Finished Permit User Sessions.1644test # [ 10.837135] systemd[1]: Started Getty on tty1.1645test # [ 10.841092] systemd[1]: Reached target Login Prompts.1646test # [ 10.889128] NetworkManager[567]: <warn> [1788188659.7150] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1647test # [ 10.903828] systemd-resolved[357]: eth0: Bus client set default route setting: yes1648test # [ 10.910341] systemd-resolved[357]: eth0: Bus client set DNS server list to: 10.0.2.31649second # [ 10.918339] systemd[1]: Finished Permit User Sessions.1650second # [ 10.933999] systemd[1]: Started Getty on tty1.1651second # [ 10.934489] systemd[1]: Reached target Login Prompts.1652test # [ 10.945793] systemd-networkd[550]: eth1: Gained IPv6LL1653test # [ 10.958267] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1654test # [ 10.960707] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1655second # [ 10.966766] systemd-resolved[334]: eth0: Bus client set default route setting: yes1656second # [ 10.970411] systemd-resolved[334]: eth0: Bus client set DNS server list to: 10.0.2.31657test # [ 10.971865] systemd[1]: Reached target Multi-User System.1658test # [ 10.976207] systemd[1]: Startup finished in 745ms (kernel) + 4.291s (initrd) + 5.935s (userspace) = 10.972s.1659second # [ 11.042638] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1660second # [ 11.043384] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1661second # [ 11.215119] systemd[1]: Reached target Multi-User System.1662second # [ 11.218378] systemd[1]: Startup finished in 745ms (kernel) + 4.495s (initrd) + 5.976s (userspace) = 11.216s.1663test # [ 11.350763] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31664second # [ 11.464330] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input31665test: (finished: waiting for unit NetworkManager.service, in 11.75 seconds)1666test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1667test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1668test: must succeed: cat /run/secrets/NetworkManager/wifi-secrets1669test: (finished: must succeed: cat /run/secrets/NetworkManager/wifi-secrets, in 0.04 seconds)1670test: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection1671test: (finished: must succeed: cat /run/NetworkManager/system-connections/open.nmconnection, in 0.03 seconds)1672(finished: run the VM test script, in 12.34 seconds)1673test # [ 11.660343] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.1674test script finished in 12.40s1675cleanup1676kill QemuMachine (pid 47)1677second # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1678test # [ 11.695771] systemd[1]: Starting Virtual Console Setup...1679test # [ 11.710387] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1680test # [ 11.714697] systemd[1]: Stopped Virtual Console Setup.1681test # [ 11.717596] systemd[1]: Starting Virtual Console Setup...1682test # [ 11.729562] systemd-logind[609]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)1683kill QemuMachine (pid 48)1684test # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1685(finished: cleanup, in 0.24 seconds)