vm-test-run-wifi
checks.aarch64-linux.wifi
· build #682
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)4Test will time out and terminate in 3600 seconds5run the VM test script6additionally exposed symbols:7 second, test,8 vlan1,9 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh10start all VMs11second: starting vm12test: starting vm13second # Disk image does not exist, creating the virtualisation disk image...14test: QEMU running (pid 14)15second # Formatting '/build/vm-state-second/tmp.KjYf2WUoZY', fmt=raw size=107374182416second: QEMU running (pid 15)17second # mke2fs 1.47.4 (6-Mar-2025)18test # Disk image does not exist, creating the virtualisation disk image...19second # Discarding device blocks: 0/262144 done20test # Formatting '/build/vm-state-test/tmp.Jv8Y3O8mFw', fmt=raw size=107374182421second # Creating filesystem with 262144 4k blocks and 65536 inodes22test # mke2fs 1.47.4 (6-Mar-2025)23second # Filesystem UUID: b578eba6-f3f9-49f5-bc89-137d002a362724test # Discarding device blocks: 0/262144 done25second # Superblock backups stored on blocks:26test # Creating filesystem with 262144 4k blocks and 65536 inodes27second # 32768, 98304, 163840, 22937628test # Filesystem UUID: b7fc944f-0269-4de1-a53f-1675dabf6eb229second # 30test # Superblock backups stored on blocks:31second # Allocating group tables: 0/8 done32test # 32768, 98304, 163840, 22937633second # Writing inode tables: 0/8 done34test # 35second # Creating journal (8192 blocks): done36test # Allocating group tables: 0/8 done37second # Writing superblocks and filesystem accounting information: 0/8 done38test # Writing inode tables: 0/8 done39second # 40test # Creating journal (8192 blocks): done41second # Virtualisation disk image created.42test # Writing superblocks and filesystem accounting information: 0/8 done43(finished: start all VMs, in 0.46 seconds)44test # 45test: waiting for unit NetworkManager.service46test # Virtualisation disk image created.47test: waiting for the VM to finish booting48test # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]49second # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]50test # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202651test # [ 0.000000] KASLR enabled52second # [ 0.000000] Linux version 6.18.52 (nixbld@localhost) (gcc (GCC) 15.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 14 11:36:19 UTC 202653test # [ 0.000000] random: crng init done54second # [ 0.000000] KASLR enabled55test # [ 0.000000] Machine model: linux,dummy-virt56second # [ 0.000000] random: crng init done57test # [ 0.000000] efi: UEFI not found.58second # [ 0.000000] Machine model: linux,dummy-virt59second # [ 0.000000] efi: UEFI not found.60test # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT61second # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT62test # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]63second # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]64test # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]65test # [ 0.000000] Zone ranges:66second # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fc07740-0x7fc0aebf]67second # [ 0.000000] Zone ranges:68test # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]69test # [ 0.000000] DMA32 empty70second # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]71test # [ 0.000000] Normal empty72second # [ 0.000000] DMA32 empty73test # [ 0.000000] Device empty74second # [ 0.000000] Normal empty75test # [ 0.000000] Movable zone start for each node76second # [ 0.000000] Device empty77test # [ 0.000000] Early memory node ranges78second # [ 0.000000] Movable zone start for each node79second # [ 0.000000] Early memory node ranges80test # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]81second # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]82test # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]83second # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]84test # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000085second # [ 0.000000] cma: Reserved 32 MiB at 0x000000007ca0000086test # [ 0.000000] psci: probing for conduit method from DT.87test # [ 0.000000] psci: PSCIv1.3 detected in firmware.88second # [ 0.000000] psci: probing for conduit method from DT.89second # [ 0.000000] psci: PSCIv1.3 detected in firmware.90test # [ 0.000000] psci: Using standard PSCI v0.2 function IDs91second # [ 0.000000] psci: Using standard PSCI v0.2 function IDs92test # [ 0.000000] psci: Trusted OS migration not required93test # [ 0.000000] psci: SMC Calling Convention v1.194second # [ 0.000000] psci: Trusted OS migration not required95second # [ 0.000000] psci: SMC Calling Convention v1.196test # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)97second # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)98test # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u31129699second # [ 0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296100test # [ 0.000000] Detected PIPT I-cache on CPU0101second # [ 0.000000] Detected PIPT I-cache on CPU0102test # [ 0.000000] CPU features: detected: GICv3 CPU interface103second # [ 0.000000] CPU features: detected: GICv3 CPU interface104test # [ 0.000000] CPU features: detected: Spectre-v4105second # [ 0.000000] CPU features: detected: Spectre-v4106test # [ 0.000000] CPU features: detected: Spectre-BHB107second # [ 0.000000] CPU features: detected: Spectre-BHB108test # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR109second # [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR110test # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)111second # [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI)112test # [ 0.000000] CPU features: detected: ARM erratum 1418040113second # [ 0.000000] CPU features: detected: ARM erratum 1418040114test # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion115second # [ 0.000000] CPU features: detected: Broken broadcast TLBI completion116test # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing117second # [ 0.000000] CPU features: detected: SSBS not fully self-synchronizing118test # [ 0.000000] alternatives: applying boot alternatives119second # [ 0.000000] alternatives: applying boot alternatives120test # [ 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/8s3xa3465xzn32x6zjcvn547misifpi7-nixos-system-test-test/init regInfo=/nix/store/a0yp0pgf047rkhwyjdq5wcr5r2hs7d0c-closure-info/registration console=ttyAMA0,115200n8 console=tty0121second # [ 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/1b96nrwxbw21h7bf1llsg40sg8kx139s-nixos-system-second-test/init regInfo=/nix/store/8n8547vc97i1w9xmz8dbckhilmk5wp31-closure-info/registration console=ttyAMA0,115200n8 console=tty0122test # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/a0yp0pgf047rkhwyjdq5wcr5r2hs7d0c-closure-info/registration", will be passed to user space.123second # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/8n8547vc97i1w9xmz8dbckhilmk5wp31-closure-info/registration", will be passed to user space.124test # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes125second # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes126test # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)127second # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)128test # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)129second # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)130test # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB131second # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB132test # [ 0.000000] software IO TLB: area num 1.133second # [ 0.000000] software IO TLB: area num 1.134test # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)135second # [ 0.000000] software IO TLB: mapped [mem 0x000000007c900000-0x000000007ca00000] (1MB)136test # [ 0.000000] Fallback order for Node 0: 0137second # [ 0.000000] Fallback order for Node 0: 0138test # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144139second # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 262144140test # [ 0.000000] Policy zone: DMA141second # [ 0.000000] Policy zone: DMA142test # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off143second # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off144test # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1145second # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1146test # [ 0.000000] allocated 2097152 bytes of page_ext147second # [ 0.000000] allocated 2097152 bytes of page_ext148test # [ 0.000000] ftrace: allocating 74815 entries in 294 pages149second # [ 0.000000] ftrace: allocating 74815 entries in 294 pages150test # [ 0.000000] ftrace: allocated 294 pages with 4 groups151second # [ 0.000000] ftrace: allocated 294 pages with 4 groups152test # [ 0.000000] rcu: Hierarchical RCU implementation.153second # [ 0.000000] rcu: Hierarchical RCU implementation.154test # [ 0.000000] rcu: RCU event tracing is enabled.155second # [ 0.000000] rcu: RCU event tracing is enabled.156test # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.157second # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.158test # [ 0.000000] Trampoline variant of Tasks RCU enabled.159second # [ 0.000000] Trampoline variant of Tasks RCU enabled.160test # [ 0.000000] Rude variant of Tasks RCU enabled.161second # [ 0.000000] Rude variant of Tasks RCU enabled.162test # [ 0.000000] Tracing variant of Tasks RCU enabled.163second # [ 0.000000] Tracing variant of Tasks RCU enabled.164test # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.165second # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.166second # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1167test # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1168second # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169test # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.170test # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.171second # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.172test # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.173second # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.174test # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0175second # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0176test # [ 0.000000] GICv3: 256 SPIs implemented177second # [ 0.000000] GICv3: 256 SPIs implemented178second # [ 0.000000] GICv3: 0 Extended SPIs implemented179test # [ 0.000000] GICv3: 0 Extended SPIs implemented180second # [ 0.000000] Root IRQ handler: gic_handle_irq181test # [ 0.000000] Root IRQ handler: gic_handle_irq182second # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI183test # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI184second # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0185test # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0186second # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000187test # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000188second # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]189test # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]190second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)191test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)192second # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)193test # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (flat, esz 8, psz 64K, shr 1)194second # [ 0.000000] GICv3: using LPI property table @0x0000000044d10000195test # [ 0.000000] GICv3: using LPI property table @0x0000000044d00000196second # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000197test # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d20000198second # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.199test # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.200second # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns201test # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns202test # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040203second # [ 0.000000] arch_timer: Enabling local workaround for ARM erratum 1418040204test # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).205second # [ 0.000000] arch_timer: cp15 timer running at 25.00MHz (virt).206test # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns207second # [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns208test # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns209second # [ 0.000000] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns210test # [ 0.000058] arm-pv: using stolen time PV211second # [ 0.000054] arm-pv: using stolen time PV212test # [ 0.000373] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)213second # [ 0.000412] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)214second # [ 0.000692] Console: colour dummy device 80x25215test # [ 0.000643] Console: colour dummy device 80x25216test # [ 0.000669] printk: legacy console [tty0] enabled217second # [ 0.000703] printk: legacy console [tty0] enabled218test # [ 0.000919] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)219second # [ 0.000934] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=100000)220test # [ 0.000925] pid_max: default: 32768 minimum: 301221second # [ 0.000939] pid_max: default: 32768 minimum: 301222test # [ 0.000992] LSM: initializing lsm=capability,landlock,yama,bpf,ima223second # [ 0.001009] LSM: initializing lsm=capability,landlock,yama,bpf,ima224test # [ 0.001146] landlock: Up and running.225second # [ 0.001151] landlock: Up and running.226test # [ 0.001149] Yama: becoming mindful.227second # [ 0.001153] Yama: becoming mindful.228test # [ 0.001544] LSM support for eBPF active229second # [ 0.001612] LSM support for eBPF active230test # [ 0.001690] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)231second # [ 0.001828] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)232test # [ 0.001708] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)233second # [ 0.001846] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)234test # [ 0.004528] rcu: Hierarchical SRCU implementation.235second # [ 0.004594] rcu: Hierarchical SRCU implementation.236test # [ 0.004536] rcu: Max phase no-delay instances is 1000.237second # [ 0.004602] rcu: Max phase no-delay instances is 1000.238test # [ 0.005473] fsl-mc MSI: its@8080000 domain created239second # [ 0.005554] fsl-mc MSI: its@8080000 domain created240test # [ 0.005657] EFI services will not be available.241second # [ 0.005696] EFI services will not be available.242test # [ 0.005793] smp: Bringing up secondary CPUs ...243second # [ 0.005791] smp: Bringing up secondary CPUs ...244test # [ 0.005825] smp: Brought up 1 node, 1 CPU245second # [ 0.005823] smp: Brought up 1 node, 1 CPU246test # [ 0.005828] SMP: Total of 1 processors activated.247second # [ 0.005826] SMP: Total of 1 processors activated.248test # [ 0.005830] CPU: All CPU(s) started at EL1249second # [ 0.005827] CPU: All CPU(s) started at EL1250test # [ 0.005842] CPU features: detected: 32-bit EL0 Support251second # [ 0.005841] CPU features: detected: 32-bit EL0 Support252test # [ 0.005845] CPU features: detected: Data cache clean to the PoU not required for I/D coherence253second # [ 0.005843] CPU features: detected: Data cache clean to the PoU not required for I/D coherence254test # [ 0.005847] CPU features: detected: Common not Private translations255second # [ 0.005846] CPU features: detected: Common not Private translations256test # [ 0.005849] CPU features: detected: CRC32 instructions257second # [ 0.005849] CPU features: detected: CRC32 instructions258test # [ 0.005851] CPU features: detected: Data cache clean to Point of Persistence259second # [ 0.005851] CPU features: detected: Data cache clean to Point of Persistence260test # [ 0.005854] CPU features: detected: Enhanced Virtualization Traps261second # [ 0.005853] CPU features: detected: Enhanced Virtualization Traps262test # [ 0.005864] CPU features: detected: RCpc load-acquire (LDAPR)263second # [ 0.005863] CPU features: detected: RCpc load-acquire (LDAPR)264test # [ 0.005867] CPU features: detected: LSE atomic instructions265second # [ 0.005865] CPU features: detected: LSE atomic instructions266test # [ 0.005869] CPU features: detected: Privileged Access Never267second # [ 0.005867] CPU features: detected: Privileged Access Never268test # [ 0.005871] CPU features: detected: PMUv3269second # [ 0.005869] CPU features: detected: PMUv3270test # [ 0.005873] CPU features: detected: RAS Extension Support271second # [ 0.005871] CPU features: detected: RAS Extension Support272test # [ 0.005876] CPU features: detected: Speculative Store Bypassing Safe (SSBS)273second # [ 0.005874] CPU features: detected: Speculative Store Bypassing Safe (SSBS)274test # [ 0.005898] alternatives: applying system-wide alternatives275second # [ 0.005899] alternatives: applying system-wide alternatives276test # [ 0.007334] CPU features: detected: Hardware dirty bit management on CPU0277second # [ 0.007347] CPU features: detected: Hardware dirty bit management on CPU0278test # [ 0.007490] Memory: 893644K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113420K reserved, 32768K cma-reserved)279second # [ 0.007534] Memory: 893828K/1048576K available (24320K kernel code, 7090K rwdata, 26356K rodata, 4736K init, 1103K bss, 113428K reserved, 32768K cma-reserved)280test # [ 0.007941] devtmpfs: initialized281second # [ 0.008007] devtmpfs: initialized282test # [ 0.009421] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)283second # [ 0.009526] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)284test # [ 0.009445] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).285second # [ 0.009550] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).286test # [ 0.009581] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL287second # [ 0.009684] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL288test # [ 0.009585] 0 pages in range for non-PLT usage289second # [ 0.009688] 0 pages in range for non-PLT usage290test # [ 0.009585] 508336 pages in range for PLT usage291second # [ 0.009689] 508336 pages in range for PLT usage292test # [ 0.009719] pinctrl core: initialized pinctrl subsystem293second # [ 0.009829] pinctrl core: initialized pinctrl subsystem294test # [ 0.010509] DMI not present or invalid.295second # [ 0.010597] DMI not present or invalid.296test # [ 0.013921] NET: Registered PF_NETLINK/PF_ROUTE protocol family297second # [ 0.013981] NET: Registered PF_NETLINK/PF_ROUTE protocol family298test # [ 0.016350] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations299second # [ 0.016464] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations300test # [ 0.016499] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations301second # [ 0.016614] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations302test # [ 0.016669] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations303second # [ 0.016786] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations304test # [ 0.016690] audit: initializing netlink subsys (disabled)305second # [ 0.016805] audit: initializing netlink subsys (disabled)306test # [ 0.017279] thermal_sys: Registered thermal governor 'fair_share'307second # [ 0.017446] thermal_sys: Registered thermal governor 'fair_share'308test # [ 0.017282] thermal_sys: Registered thermal governor 'bang_bang'309second # [ 0.017448] thermal_sys: Registered thermal governor 'bang_bang'310test # [ 0.017285] thermal_sys: Registered thermal governor 'step_wise'311second # [ 0.017451] thermal_sys: Registered thermal governor 'step_wise'312test # [ 0.017287] thermal_sys: Registered thermal governor 'user_space'313second # [ 0.017454] thermal_sys: Registered thermal governor 'user_space'314test # [ 0.017289] thermal_sys: Registered thermal governor 'power_allocator'315second # [ 0.017456] thermal_sys: Registered thermal governor 'power_allocator'316test # [ 0.017321] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1317test # [ 0.017335] cpuidle: using governor ladder318second # [ 0.017497] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1319test # [ 0.017340] cpuidle: using governor menu320second # [ 0.017513] cpuidle: using governor ladder321second # [ 0.017518] cpuidle: using governor menu322test # [ 0.017474] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.323second # [ 0.017656] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.324test # [ 0.017495] ASID allocator initialised with 32768 entries325test # [ 0.018148] Serial: AMBA PL011 UART driver326second # [ 0.017677] ASID allocator initialised with 32768 entries327second # [ 0.018330] Serial: AMBA PL011 UART driver328test # [ 0.021687] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1329test # [ 0.021871] printk: console [ttyAMA0] enabled330second # [ 0.021426] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1331second # [ 0.021600] printk: console [ttyAMA0] enabled332test # [ 0.095704] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages333test # [ 0.095721] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page334test # [ 0.095725] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages335test # [ 0.095731] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page336test # [ 0.095734] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages337test # [ 0.095737] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page338test # [ 0.095740] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages339second # [ 0.098432] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages340test # [ 0.095743] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page341second # [ 0.098451] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page342second # [ 0.098456] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages343second # [ 0.098461] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page344test # [ 0.100853] fbcon: Taking over console345second # [ 0.098465] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages346test # [ 0.100875] ACPI: Interpreter disabled.347second # [ 0.098468] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page348second # [ 0.098471] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages349second # [ 0.098474] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page350second # [ 0.103773] fbcon: Taking over console351second # [ 0.103794] ACPI: Interpreter disabled.352second # [ 0.105080] iommu: Default domain type: Translated353second # [ 0.105091] iommu: DMA domain TLB invalidation policy: strict mode354second # [ 0.106362] SCSI subsystem initialized355test # [ 0.107469] iommu: Default domain type: Translated356second # [ 0.106971] usbcore: registered new interface driver usbfs357test # [ 0.107482] iommu: DMA domain TLB invalidation policy: strict mode358second # [ 0.107006] usbcore: registered new interface driver hub359test # [ 0.107854] SCSI subsystem initialized360second # [ 0.107021] usbcore: registered new device driver usb361second # [ 0.107226] pps_core: LinuxPPS API ver. 1 registered362test # [ 0.109271] usbcore: registered new interface driver usbfs363second # [ 0.107232] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>364test # [ 0.109313] usbcore: registered new interface driver hub365second # [ 0.107239] PTP clock support registered366test # [ 0.109324] usbcore: registered new device driver usb367second # [ 0.107268] EDAC MC: Ver: 3.0.0368test # [ 0.109562] pps_core: LinuxPPS API ver. 1 registered369second # [ 0.110373] scmi_core: SCMI protocol bus registered370test # [ 0.109567] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>371test # [ 0.109573] PTP clock support registered372test # [ 0.109602] EDAC MC: Ver: 3.0.0373second # [ 0.111135] FPGA manager framework374test # [ 0.112832] scmi_core: SCMI protocol bus registered375test # [ 0.113619] FPGA manager framework376test # [ 0.114803] vgaarb: loaded377test # [ 0.115269] clocksource: Switched to clocksource arch_sys_counter378second # [ 0.118355] vgaarb: loaded379second # [ 0.118919] clocksource: Switched to clocksource arch_sys_counter380test # [ 0.125425] VFS: Disk quotas dquot_6.6.0381second # [ 0.125205] VFS: Disk quotas dquot_6.6.0382test # [ 0.125465] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)383second # [ 0.125242] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)384test # [ 0.127870] netfs: FS-Cache loaded385test # [ 0.128012] pnp: PnP ACPI: disabled386second # [ 0.127734] netfs: FS-Cache loaded387second # [ 0.127871] pnp: PnP ACPI: disabled388test # [ 0.130554] NET: Registered PF_INET protocol family389second # [ 0.130454] NET: Registered PF_INET protocol family390test # [ 0.130752] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)391second # [ 0.130676] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)392test # [ 0.155068] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)393second # [ 0.154831] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)394test # [ 0.155102] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)395second # [ 0.154863] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)396test # [ 0.155116] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)397second # [ 0.154877] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)398test # [ 0.155157] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)399test # [ 0.155225] TCP: Hash tables configured (established 8192 bind 8192)400second # [ 0.156966] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)401second # [ 0.157040] TCP: Hash tables configured (established 8192 bind 8192)402second # [ 0.157158] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)403second # [ 0.157224] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)404second # [ 0.157281] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)405second # [ 0.157463] NET: Registered PF_UNIX/PF_LOCAL protocol family406second # [ 0.157532] NET: Registered PF_XDP protocol family407second # [ 0.157562] PCI: CLS 0 bytes, default 64408test # [ 0.158531] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)409second # [ 0.157789] Trying to unpack rootfs image as initramfs...410test # [ 0.158608] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)411test # [ 0.158636] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)412test # [ 0.158793] NET: Registered PF_UNIX/PF_LOCAL protocol family413test # [ 0.158866] NET: Registered PF_XDP protocol family414test # [ 0.158896] PCI: CLS 0 bytes, default 64415test # [ 0.159120] Trying to unpack rootfs image as initramfs...416test # [ 0.170624] kvm [1]: HYP mode not available417second # [ 0.174948] kvm [1]: HYP mode not available418test # [ 0.237523] Initialise system trusted keyrings419test # [ 0.238046] workingset: timestamp_bits=42 max_order=18 bucket_order=0420test # [ 0.238992] squashfs: version 4.0 (2009/01/31) Phillip Lougher421second # [ 0.241344] Initialise system trusted keyrings422second # [ 0.241901] workingset: timestamp_bits=42 max_order=18 bucket_order=0423second # [ 0.242876] squashfs: version 4.0 (2009/01/31) Phillip Lougher424test # [ 0.239587] 9p: Installing v9fs 9p2000 file system support425second # [ 0.243455] 9p: Installing v9fs 9p2000 file system support426test # [ 0.258501] Key type asymmetric registered427test # [ 0.258529] Asymmetric key parser 'x509' registered428test # [ 0.258591] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)429second # [ 0.262345] Key type asymmetric registered430test # [ 0.263427] io scheduler mq-deadline registered431second # [ 0.262371] Asymmetric key parser 'x509' registered432test # [ 0.263441] io scheduler kyber registered433second # [ 0.262447] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)434second # [ 0.271020] io scheduler mq-deadline registered435second # [ 0.271037] io scheduler kyber registered436second # [ 0.273957] pl061_gpio 9030000.pl061: PL061 GPIO chip registered437second # [ 0.274769] ledtrig-cpu: registered to indicate activity on CPUs438test # [ 0.271405] pl061_gpio 9030000.pl061: PL061 GPIO chip registered439test # [ 0.271778] ledtrig-cpu: registered to indicate activity on CPUs440second # [ 0.275625] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:441test # [ 0.272085] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:442second # [ 0.275651] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000443test # [ 0.272107] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000444second # [ 0.275666] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000445test # [ 0.272122] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000446second # [ 0.275672] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000447test # [ 0.272129] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000448second # [ 0.275705] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits449test # [ 0.272159] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits450second # [ 0.275729] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]451second # [ 0.275814] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00452test # [ 0.272182] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]453second # [ 0.275838] pci_bus 0000:00: root bus resource [bus 00-ff]454test # [ 0.272262] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00455second # [ 0.275843] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]456test # [ 0.272279] pci_bus 0000:00: root bus resource [bus 00-ff]457test # [ 0.272284] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]458second # [ 0.275847] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]459test # [ 0.272288] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]460second # [ 0.275850] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]461test # [ 0.272292] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]462second # [ 0.275977] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint463test # [ 0.272375] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint464second # [ 0.276410] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint465second # [ 0.276529] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]466test # [ 0.272799] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint467second # [ 0.276540] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]468test # [ 0.272919] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]469test # [ 0.272930] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]470second # [ 0.276560] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]471test # [ 0.272950] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]472second # [ 0.276572] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]473test # [ 0.272962] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]474second # [ 0.276862] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint475second # [ 0.276980] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]476test # [ 0.273246] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint477test # [ 0.273360] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]478second # [ 0.276991] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]479test # [ 0.273370] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]480second # [ 0.277011] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]481test # [ 0.273390] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]482second # [ 0.277315] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint483test # [ 0.273671] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint484second # [ 0.277430] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]485test # [ 0.273784] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]486second # [ 0.277440] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]487test # [ 0.273794] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]488second # [ 0.277461] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]489test # [ 0.273814] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]490second # [ 0.277763] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint491test # [ 0.274092] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint492second # [ 0.277879] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]493test # [ 0.274215] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]494second # [ 0.277889] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]495test # [ 0.274226] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]496second # [ 0.277910] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]497test # [ 0.274247] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]498second # [ 0.278200] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint499test # [ 0.274532] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint500second # [ 0.278317] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]501test # [ 0.274645] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]502second # [ 0.278328] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]503test # [ 0.274656] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]504second # [ 0.278348] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]505test # [ 0.274676] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]506second # [ 0.278630] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint507test # [ 0.274960] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint508second # [ 0.278746] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]509test # [ 0.275076] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]510second # [ 0.278756] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]511test # [ 0.275087] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]512second # [ 0.278777] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]513test # [ 0.275107] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]514test # [ 0.275412] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint515test # [ 0.275529] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]516test # [ 0.275539] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]517test # [ 0.275560] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]518test # [ 0.275575] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]519test # [ 0.275885] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint520test # [ 0.276005] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]521test # [ 0.276025] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]522test # [ 0.276320] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint523test # [ 0.276436] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]524test # [ 0.276456] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]525test # [ 0.276721] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint526test # [ 0.276836] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]527test # [ 0.276998] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint528second # [ 0.303178] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint529test # [ 0.277208] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]530second # [ 0.303317] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]531test # [ 0.277222] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]532second # [ 0.303329] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]533test # [ 0.277242] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]534second # [ 0.303351] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]535test # [ 0.277534] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint536second # [ 0.303367] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]537test # [ 0.277650] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]538second # [ 0.303710] pci 0000:00:08.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint539test # [ 0.277660] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]540second # [ 0.303832] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]541test # [ 0.277680] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]542second # [ 0.303852] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]543test # [ 0.278097] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned544test # [ 0.278108] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned545second # [ 0.304141] pci 0000:00:09.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint546second # [ 0.304261] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]547test # [ 0.278113] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned548second # [ 0.304282] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]549test # [ 0.278146] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned550second # [ 0.304545] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint551test # [ 0.278178] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned552second # [ 0.304661] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]553test # [ 0.278222] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned554second # [ 0.304843] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint555test # [ 0.278253] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned556second # [ 0.305046] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]557second # [ 0.305061] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]558test # [ 0.278284] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned559second # [ 0.305082] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]560test # [ 0.278315] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned561second # [ 0.305386] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint562test # [ 0.278346] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned563second # [ 0.305516] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]564test # [ 0.278377] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned565second # [ 0.305528] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]566test # [ 0.278408] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned567second # [ 0.305548] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]568test # [ 0.278494] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned569second # [ 0.305962] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned570test # [ 0.278536] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned571second # [ 0.305974] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned572test # [ 0.278550] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned573second # [ 0.305979] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned574test # [ 0.278564] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned575second # [ 0.306014] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned576test # [ 0.278579] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned577second # [ 0.306048] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned578test # [ 0.278593] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned579second # [ 0.306081] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned580test # [ 0.278607] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned581test # [ 0.278621] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned582second # [ 0.306115] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned583test # [ 0.278635] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned584second # [ 0.306149] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned585test # [ 0.278649] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned586second # [ 0.306181] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned587test # [ 0.278664] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned588second # [ 0.306213] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned589test # [ 0.278678] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned590second # [ 0.306246] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned591test # [ 0.278693] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned592test # [ 0.278707] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned593second # [ 0.306277] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned594test # [ 0.278721] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned595second # [ 0.306359] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned596test # [ 0.278735] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned597second # [ 0.306391] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned598test # [ 0.278749] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned599second # [ 0.306406] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned600test # [ 0.278769] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned601test # [ 0.278782] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned602second # [ 0.306421] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned603test # [ 0.278797] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned604second # [ 0.306435] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned605test # [ 0.278811] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned606second # [ 0.306450] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned607test # [ 0.278825] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned608second # [ 0.306465] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned609test # [ 0.278844] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]610second # [ 0.306479] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned611test # [ 0.278850] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]612second # [ 0.306494] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned613test # [ 0.278854] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]614second # [ 0.306508] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned615second # [ 0.306523] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned616second # [ 0.306538] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned617second # [ 0.306553] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned618second # [ 0.306567] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned619second # [ 0.306582] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned620second # [ 0.306596] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned621second # [ 0.306611] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned622second # [ 0.306631] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned623second # [ 0.306645] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned624second # [ 0.306660] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned625second # [ 0.306674] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned626second # [ 0.306689] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned627second # [ 0.306708] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]628second # [ 0.306714] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]629second # [ 0.306718] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]630test # [ 0.343656] pci 0000:00:0a.0: enabling device (0000 -> 0002)631second # [ 0.343524] pci 0000:00:0a.0: enabling device (0000 -> 0002)632test # [ 0.350592] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)633second # [ 0.350002] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)634test # [ 0.360455] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)635second # [ 0.360048] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)636test # [ 0.363951] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)637test # [ 0.365730] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)638second # [ 0.367038] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)639second # [ 0.368780] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)640second # [ 0.370549] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)641test # [ 0.375409] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)642test # [ 0.377584] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)643second # [ 0.380070] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)644second # [ 0.381967] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)645second # [ 0.388054] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)646second # [ 0.389685] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)647test # [ 0.387419] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)648test # [ 0.389802] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)649test # [ 0.391403] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)650test # [ 0.393499] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)651second # [ 0.400456] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)652second # [ 0.402654] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)653test # [ 0.403796] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)654test # [ 0.407890] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled655test # [ 0.409460] msm_serial: driver initialized656test # [ 0.409602] SuperH (H)SCI(F) driver initialized657test # [ 0.409645] STM32 USART driver initialized658second # [ 0.415013] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled659second # [ 0.416491] msm_serial: driver initialized660second # [ 0.416577] SuperH (H)SCI(F) driver initialized661second # [ 0.416610] STM32 USART driver initialized662test # [ 0.436166] loop: module loaded663test # [ 0.436306] virtio_blk virtio5: 1/0/0 default/read/poll queues664test # [ 0.436893] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)665second # [ 0.442737] loop: module loaded666second # [ 0.442875] virtio_blk virtio5: 1/0/0 default/read/poll queues667second # [ 0.444275] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)668test # [ 0.443702] megasas: 07.734.00.00-rc1669test # [ 0.444177] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]670second # [ 0.455336] megasas: 07.734.00.00-rc1671second # [ 0.455844] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]672second # [ 0.458060] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000673second # [ 0.458080] Intel/Sharp Extended Query Table at 0x0031674test # [ 0.455835] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000675test # [ 0.455861] Intel/Sharp Extended Query Table at 0x0031676test # [ 0.457499] Using buffer write method677test # [ 0.457541] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]678second # [ 0.461926] Using buffer write method679test # [ 0.460139] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000680second # [ 0.462006] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]681test # [ 0.460157] Intel/Sharp Extended Query Table at 0x0031682test # [ 0.461606] Using buffer write method683test # [ 0.461635] Concatenating MTD devices:684test # [ 0.461640] (0): "0.flash"685test # [ 0.461643] (1): "0.flash"686test # [ 0.461645] into device "0.flash"687second # [ 0.464778] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000688second # [ 0.464800] Intel/Sharp Extended Query Table at 0x0031689second # [ 0.466656] Using buffer write method690second # [ 0.466688] Concatenating MTD devices:691second # [ 0.466693] (0): "0.flash"692second # [ 0.466696] (1): "0.flash"693second # [ 0.466698] into device "0.flash"694test # [ 0.652791] Freeing initrd memory: 24812K695second # [ 0.657375] Freeing initrd memory: 24820K696test # [ 0.659714] tun: Universal TUN/TAP device driver, 1.6697test # [ 0.662549] thunder_xcv, ver 1.0698test # [ 0.662578] thunder_bgx, ver 1.0699test # [ 0.662591] nicpf, ver 1.0700test # [ 0.662920] e1000: Intel(R) PRO/1000 Network Driver701test # [ 0.662930] e1000: Copyright (c) 1999-2006 Intel Corporation.702test # [ 0.662946] e1000e: Intel(R) PRO/1000 Network Driver703second # [ 0.664278] tun: Universal TUN/TAP device driver, 1.6704test # [ 0.662952] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.705test # [ 0.662966] igb: Intel(R) Gigabit Ethernet Network Driver706test # [ 0.662969] igb: Copyright (c) 2007-2014 Intel Corporation.707test # [ 0.662980] igbvf: Intel(R) Gigabit Virtual Function Network Driver708test # [ 0.662983] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.709test # [ 0.663050] sky2: driver version 1.30710test # [ 0.668650] usbcore: registered new interface driver usb-storage711test # [ 0.668784] usbcore: registered new interface driver usbserial_generic712second # [ 0.666969] thunder_xcv, ver 1.0713test # [ 0.668794] usbserial: USB Serial support registered for generic714second # [ 0.666995] thunder_bgx, ver 1.0715second # [ 0.667006] nicpf, ver 1.0716test # [ 0.669104] hv_vmbus: registering driver hyperv_keyboard717second # [ 0.667290] e1000: Intel(R) PRO/1000 Network Driver718second # [ 0.667298] e1000: Copyright (c) 1999-2006 Intel Corporation.719second # [ 0.667312] e1000e: Intel(R) PRO/1000 Network Driver720test # [ 0.672137] ehci-pci 0000:00:0a.0: EHCI Host Controller721second # [ 0.667319] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.722test # [ 0.672174] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1723second # [ 0.667332] igb: Intel(R) Gigabit Ethernet Network Driver724test # [ 0.672375] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000725second # [ 0.667336] igb: Copyright (c) 2007-2014 Intel Corporation.726second # [ 0.667373] igbvf: Intel(R) Gigabit Virtual Function Network Driver727second # [ 0.667378] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.728test # [ 0.674044] rtc-pl031 9010000.pl031: registered as rtc0729second # [ 0.667466] sky2: driver version 1.30730second # [ 0.668229] usbcore: registered new interface driver usb-storage731test # [ 0.674086] rtc-pl031 9010000.pl031: setting system clock to 2026-09-17T00:19:05 UTC (1789604345)732second # [ 0.668274] usbcore: registered new interface driver usbserial_generic733test # [ 0.674280] i2c_dev: i2c /dev entries driver734second # [ 0.668283] usbserial: USB Serial support registered for generic735second # [ 0.668550] hv_vmbus: registering driver hyperv_keyboard736second # [ 0.669593] ehci-pci 0000:00:0a.0: EHCI Host Controller737second # [ 0.669628] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1738second # [ 0.669850] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000739test # [ 0.677529] sdhci: Secure Digital Host Controller Interface driver740second # [ 0.678099] rtc-pl031 9010000.pl031: registered as rtc0741test # [ 0.677546] sdhci: Copyright(c) Pierre Ossman742test # [ 0.677699] Synopsys Designware Multimedia Card Interface Driver743second # [ 0.678143] rtc-pl031 9010000.pl031: setting system clock to 2026-09-17T00:19:05 UTC (1789604345)744second # [ 0.678331] i2c_dev: i2c /dev entries driver745test # [ 0.677880] sdhci-pltfm: SDHCI platform and OF driver helper746test # [ 0.678762] hid: raw HID events driver (C) Jiri Kosina747test # [ 0.678920] usbcore: registered new interface driver usbhid748second # [ 0.680314] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00749test # [ 0.678925] usbhid: USB HID core driver750second # [ 0.680658] hub 1-0:1.0: USB hub found751second # [ 0.680687] hub 1-0:1.0: 6 ports detected752test # [ 0.682627] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available753test # [ 0.683322] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00754test # [ 0.683645] hub 1-0:1.0: USB hub found755test # [ 0.683674] hub 1-0:1.0: 6 ports detected756second # [ 0.683159] sdhci: Secure Digital Host Controller Interface driver757second # [ 0.683173] sdhci: Copyright(c) Pierre Ossman758test # [ 0.685989] drop_monitor: Initializing network drop monitor service759second # [ 0.683302] Synopsys Designware Multimedia Card Interface Driver760test # [ 0.686161] NET: Registered PF_INET6 protocol family761second # [ 0.683465] sdhci-pltfm: SDHCI platform and OF driver helper762second # [ 0.684274] hid: raw HID events driver (C) Jiri Kosina763test # [ 0.687874] Segment Routing with IPv6764second # [ 0.684415] usbcore: registered new interface driver usbhid765test # [ 0.687905] In-situ OAM (IOAM) with IPv6766second # [ 0.684420] usbhid: USB HID core driver767test # [ 0.687999] NET: Registered PF_PACKET protocol family768second # [ 0.688143] hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 (0,8000003f) counters available769second # [ 0.688887] drop_monitor: Initializing network drop monitor service770second # [ 0.689010] NET: Registered PF_INET6 protocol family771test # [ 0.689110] 9pnet: Installing 9P2000 support772test # [ 0.690794] Key type dns_resolver registered773second # [ 0.691383] Segment Routing with IPv6774second # [ 0.691414] In-situ OAM (IOAM) with IPv6775second # [ 0.691467] NET: Registered PF_PACKET protocol family776test # [ 0.694419] registered taskstats version 1777second # [ 0.692681] 9pnet: Installing 9P2000 support778test # [ 0.694567] Loading compiled-in X.509 certificates779second # [ 0.694264] Key type dns_resolver registered780second # [ 0.697850] registered taskstats version 1781second # [ 0.698014] Loading compiled-in X.509 certificates782test # [ 0.700082] Demotion targets for Node 0: null783test # [ 0.700167] Key type .fscrypt registered784test # [ 0.700173] Key type fscrypt-provisioning registered785test # [ 0.700280] ima: No TPM chip found, activating TPM-bypass!786test # [ 0.700294] ima: Allocated hash algorithm: sha1787test # [ 0.700346] ima: No architecture policies found788test # [ 0.703120] input: gpio-keys as /devices/platform/gpio-keys/input/input0789second # [ 0.703670] Demotion targets for Node 0: null790second # [ 0.703765] Key type .fscrypt registered791second # [ 0.703771] Key type fscrypt-provisioning registered792second # [ 0.703887] ima: No TPM chip found, activating TPM-bypass!793second # [ 0.703900] ima: Allocated hash algorithm: sha1794second # [ 0.703962] ima: No architecture policies found795second # [ 0.707013] input: gpio-keys as /devices/platform/gpio-keys/input/input0796test # [ 0.713653] clk: Disabling unused clocks797test # [ 0.713677] PM: genpd: Disabling unused power domains798test # [ 0.716922] Freeing unused kernel memory: 4736K799test # [ 0.717014] Run /init as init process800second # [ 0.717406] clk: Disabling unused clocks801second # [ 0.717432] PM: genpd: Disabling unused power domains802second # [ 0.720770] Freeing unused kernel memory: 4736K803second # [ 0.720863] Run /init as init process804test # [ 0.737669] systemd[1]: Successfully made /usr/ read-only.805second # [ 0.741309] systemd[1]: Successfully made /usr/ read-only.806second # [ 0.927066] usb 1-1: new high-speed USB device number 2 using ehci-pci807test # [ 0.931371] usb 1-1: new high-speed USB device number 2 using ehci-pci808test # [ 1.072464] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)809test # [ 1.079994] systemd[1]: Detected virtualization qemu.810test # [ 1.081274] systemd[1]: Detected architecture arm64.811test # [ 1.082620] systemd[1]: Running in initrd.812second # [ 1.076255] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)813second # [ 1.084504] systemd[1]: Detected virtualization qemu.814test # [ 1.084839] systemd[1]: Initializing machine ID from random generator.815second # [ 1.085736] systemd[1]: Detected architecture arm64.816test # [ 1.086905] systemd[1]: Hostname set to <test>.817second # [ 1.086878] systemd[1]: Running in initrd.818second # [ 1.089404] systemd[1]: Initializing machine ID from random generator.819second # [ 1.092311] 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/input1820second # [ 1.096267] systemd[1]: Hostname set to <second>.821test # [ 1.095826] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1822second # [ 1.179165] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0823test # [ 1.183554] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0824test # [ 1.195236] systemd[1]: Queued start job for default target Initrd Default Target.825test # [ 1.205498] systemd[1]: Created slice Slice /system/modprobe.826second # [ 1.205660] systemd[1]: Queued start job for default target Initrd Default Target.827test # [ 1.206568] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.828test # [ 1.207745] systemd[1]: Expecting device /dev/disk/by-label/nixos...829test # [ 1.208633] systemd[1]: Reached target Path Units.830test # [ 1.209299] systemd[1]: Reached target Slice Units.831test # [ 1.209970] systemd[1]: Reached target Swaps.832test # [ 1.210597] systemd[1]: Reached target Timer Units.833test # [ 1.211597] systemd[1]: Listening on D-Bus System Message Bus Socket.834test # [ 1.212676] systemd[1]: Listening on Journal Socket (/dev/log).835test # [ 1.213659] systemd[1]: Listening on Journal Sockets.836test # [ 1.214521] systemd[1]: Listening on udev Control Socket.837test # [ 1.215429] systemd[1]: Listening on udev Kernel Socket.838test # [ 1.216423] systemd[1]: Reached target Socket Units.839second # [ 1.216754] systemd[1]: Created slice Slice /system/modprobe.840second # [ 1.217608] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.841second # [ 1.218450] systemd[1]: Expecting device /dev/disk/by-label/nixos...842test # [ 1.218925] systemd[1]: Starting Create List of Static Device Nodes...843second # [ 1.219136] systemd[1]: Reached target Path Units.844second # [ 1.219682] systemd[1]: Reached target Slice Units.845second # [ 1.220183] systemd[1]: Reached target Swaps.846second # [ 1.220631] systemd[1]: Reached target Timer Units.847second # [ 1.221341] systemd[1]: Listening on D-Bus System Message Bus Socket.848second # [ 1.222187] systemd[1]: Listening on Journal Socket (/dev/log).849second # [ 1.222994] systemd[1]: Listening on Journal Sockets.850second # [ 1.223646] systemd[1]: Listening on udev Control Socket.851second # [ 1.224320] systemd[1]: Listening on udev Kernel Socket.852second # [ 1.224876] systemd[1]: Reached target Socket Units.853test # [ 1.227441] systemd[1]: Starting Load Kernel Module 9pnet_virtio...854second # [ 1.227461] systemd[1]: Starting Create List of Static Device Nodes...855test # [ 1.228471] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs856second # [ 1.235082] systemd[1]: Starting Load Kernel Module 9pnet_virtio...857second # [ 1.236003] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs858test # [ 1.239425] systemd[1]: Mounting Kernel Configuration File System...859second # [ 1.243290] systemd[1]: Mounting Kernel Configuration File System...860test # [ 1.247692] systemd[1]: Starting Journal Service...861second # [ 1.259085] systemd[1]: Starting Journal Service...862second # [ 1.267836] systemd[1]: Starting Load Kernel Modules...863second # [ 1.268366] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki864test # [ 1.269999] systemd[1]: Starting Load Kernel Modules...865test # [ 1.271050] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki866test # [ 1.282427] systemd[1]: Starting Coldplug All udev Devices...867test # [ 1.286331] systemd[1]: Finished Create List of Static Device Nodes.868test # [ 1.288763] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.869second # [ 1.298982] usb 1-2: new high-speed USB device number 3 using ehci-pci870second # [ 1.299988] systemd[1]: Starting Coldplug All udev Devices...871test # [ 1.299601] systemd[1]: Finished Load Kernel Module 9pnet_virtio.872test # [ 1.300079] systemd[1]: Mounted Kernel Configuration File System.873test # [ 1.303368] usb 1-2: new high-speed USB device number 3 using ehci-pci874second # [ 1.306586] systemd-journald[73]: Collecting audit messages is disabled.875second # [ 1.307518] systemd[1]: Finished Create List of Static Device Nodes.876second # [ 1.309177] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.877second # [ 1.309359] systemd[1]: Finished Load Kernel Module 9pnet_virtio.878test # [ 1.319511] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...879second # [ 1.323510] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...880second # [ 1.323724] systemd[1]: Mounted Kernel Configuration File System.881test # [ 1.353767] systemd-journald[73]: Collecting audit messages is disabled.882second # [ 1.363559] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.883second # [ 1.364698] systemd[1]: Starting Create Static Device Nodes in /dev...884test # [ 1.372016] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.885test # [ 1.379394] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.886test # [ 1.383638] systemd[1]: Starting Create Static Device Nodes in /dev...887test # [ 1.391405] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev888second # [ 1.404033] systemd[1]: Started Journal Service.889second # [ 1.411482] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.890second # [ 1.412682] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev891second # [ 1.408823] systemd-modules-load[74]: Inserted module 'dm_mod'892second # [ 1.412164] systemd-modules-load[74]: Module 'virtio_balloon' is built in893second # [ 1.412845] systemd-modules-load[74]: Module 'virtio_console' is built in894test # [ 1.424019] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0895test # [ 1.424269] [drm] features: -virgl +edid -resource_blob -host_visible896test # [ 1.424276] [drm] features: -context_init897test # [ 1.424771] [drm] number of scanouts: 1898test # [ 1.424788] [drm] number of cap sets: 0899second # [ 1.428201] [drm] pci: virtio-gpu-pci detected at 0000:00:09.0900second # [ 1.428439] [drm] features: -virgl +edid -resource_blob -host_visible901second # [ 1.428446] [drm] features: -context_init902second # [ 1.428935] [drm] number of scanouts: 1903second # [ 1.428951] [drm] number of cap sets: 0904second # [ 1.424665] systemd[1]: Finished Create Static Device Nodes in /dev.905second # [ 1.425308] systemd[1]: Reached target Preparation for Local File Systems.906second # [ 1.425891] systemd[1]: Reached target Local File Systems.907test # [ 1.433376] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic908test # [ 1.433394] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0909second # [ 1.436103] systemd[1]: Starting Create System Files and Directories...910test # [ 1.443739] systemd[1]: Finished Create Static Device Nodes in /dev.911test # [ 1.443951] systemd[1]: Reached target Preparation for Local File Systems.912test # [ 1.443982] systemd[1]: Reached target Local File Systems.913test # [ 1.446657] systemd[1]: Starting Rule-based Manager for Device Events and Files...914test # [ 1.449786] systemd[1]: Started Journal Service.915second # [ 1.447412] virtio-pci 0000:00:09.0: [drm] Registered 1 planes with drm panic916second # [ 1.447429] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:09.0 on minor 0917test # [ 1.444469] systemd-modules-load[74]: Inserted module 'dm_mod'918test # [ 1.445374] systemd-modules-load[74]: Module 'virtio_balloon' is built in919second # [ 1.449666] systemd[1]: Starting Rule-based Manager for Device Events and Files...920second # [ 1.460033] 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/input2921second # [ 1.460149] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0922test # [ 1.459802] Console: switching to colour frame buffer device 160x50923test # [ 1.454576] systemd-modules-load[74]: Module 'virtio_console' is built in924second # [ 1.463778] Console: switching to colour frame buffer device 160x50925test # [ 1.473065] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2926test # [ 1.473151] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0927test # [ 1.468995] systemd[1]: Starting Create System Files and Directories...928test # [ 1.482909] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device929test # [ 1.476686] systemd-modules-load[74]: Inserted module 'virtio_gpu'930test # [ 1.477322] systemd-modules-load[74]: Module 'virtio_rng' is built in931second # [ 1.491548] virtio-pci 0000:00:09.0: [drm] fb0: virtio_gpudrmfb frame buffer device932second # [ 1.487221] systemd[1]: Finished Create System Files and Directories.933test # [ 1.484949] systemd[1]: Finished Load Kernel Modules.934second # [ 1.496649] systemd-modules-load[74]: Inserted module 'virtio_gpu'935second # [ 1.497341] systemd-modules-load[74]: Module 'virtio_rng' is built in936second # [ 1.499451] systemd[1]: Finished Load Kernel Modules.937test # [ 1.496157] systemd[1]: Starting Apply Kernel Variables...938second # [ 1.512877] systemd[1]: Starting Apply Kernel Variables...939test # [ 1.512878] systemd[1]: Finished Create System Files and Directories.940second # [ 1.523635] systemd-udevd[86]: Using default interface naming scheme 'v260'.941second # [ 1.531851] systemd[1]: Finished Apply Kernel Variables.942test # [ 1.528435] systemd[1]: Finished Apply Kernel Variables.943test # [ 1.538323] systemd-udevd[81]: Using default interface naming scheme 'v260'.944second # [ 1.552750] systemd[1]: Started Rule-based Manager for Device Events and Files.945test # [ 1.564804] systemd[1]: Started Rule-based Manager for Device Events and Files.946second # [ 1.620668] systemd[1]: Starting Virtual Console Setup...947test # [ 1.619707] systemd[1]: Starting Virtual Console Setup...948second # [ 1.664453] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.949second # [ 1.667231] systemd[1]: Finished Virtual Console Setup.950test # [ 1.677717] systemd-vconsole-setup[104]: Configuration of first virtual console was skipped, ignoring remaining ones.951test # [ 1.678920] systemd[1]: Finished Virtual Console Setup.952second # [ 1.933024] systemd[1]: Finished Coldplug All udev Devices.953second # [ 1.933642] systemd[1]: Reached target System Initialization.954second # [ 1.934162] systemd[1]: Reached target Basic System.955test # [ 1.939634] systemd[1]: Finished Coldplug All udev Devices.956test # [ 1.940314] systemd[1]: Reached target System Initialization.957test # [ 1.940824] systemd[1]: Reached target Basic System.958second # [ 2.035718] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.959test # [ 2.047549] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.960second # [ 2.059864] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.961test # [ 2.069195] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.962second # [ 2.108117] systemd[1]: Found device /dev/disk/by-label/nixos.963second # [ 2.111927] systemd[1]: Reached target Initrd Root Device.964second # [ 2.115120] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...965test # [ 2.128437] systemd[1]: Found device /dev/disk/by-label/nixos.966test # [ 2.131941] systemd[1]: Reached target Initrd Root Device.967test # [ 2.135121] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...968second # [ 2.180359] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks969second # [ 2.190710] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.970test # [ 2.208720] systemd-fsck[113]: nixos: clean, 12/65536 files, 13019/262144 blocks971test # [ 2.219460] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.972test # [ 2.275935] systemd[1]: Mounting /sysroot...973second # [ 2.300082] systemd[1]: Mounting /sysroot...974test # [ 2.320786] EXT4-fs (vda): mounted filesystem b7fc944f-0269-4de1-a53f-1675dabf6eb2 r/w with ordered data mode. Quota mode: none.975test # [ 2.313271] systemd[1]: Mounted /sysroot.976test # [ 2.314493] systemd[1]: Reached target Initrd Root File System.977test # [ 2.321027] systemd[1]: Mounting /sysroot/nix/.ro-store...978second # [ 2.333114] EXT4-fs (vda): mounted filesystem b578eba6-f3f9-49f5-bc89-137d002a3627 r/w with ordered data mode. Quota mode: none.979second # [ 2.331656] systemd[1]: Mounted /sysroot.980second # [ 2.332445] systemd[1]: Reached target Initrd Root File System.981second # [ 2.334949] systemd[1]: Starting Mountpoints Configured in the Real Root...982test # [ 2.333073] systemd[1]: Mounting /sysroot/nix/.rw-store...983test # [ 2.345292] systemd[1]: Mounting /sysroot/run...984second # [ 2.353811] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.985second # [ 2.357543] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...986second # [ 2.360563] systemd[1]: Reloading...987test # [ 2.356954] systemd[1]: Mounting /sysroot/tmp/shared...988test # [ 2.384202] systemd[1]: Mounting /sysroot/tmp/xchg...989test # [ 2.402807] systemd[1]: Starting Mountpoints Configured in the Real Root...990test # [ 2.406068] systemd[1]: Mounted /sysroot/nix/.rw-store.991test # [ 2.409751] systemd[1]: Mounted /sysroot/run.992test # [ 2.419382] systemd[1]: Mounted /sysroot/tmp/shared.993test # [ 2.424231] systemd[1]: Mounted /sysroot/nix/.ro-store.994test # [ 2.443370] systemd-sysroot-fstab-check[126]: /sysroot should be mounted in the initrd, will request daemon-reload.995test # [ 2.445615] systemd[1]: Starting rw-sysroot-nix-store.service...996test # [ 2.451312] systemd[1]: Mounted /sysroot/tmp/xchg.997test # [ 2.458242] systemd[1]: Reload requested from client PID 126 ('systemd-sysroot') (unit initrd-parse-etc.service)...998test # [ 2.460175] systemd[1]: Reloading...999second # [ 2.545356] systemd[1]: Reloading finished in 184 ms.1000second # [ 2.555782] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...1001second # [ 2.559199] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...1002second # [ 2.560983] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1003second # [ 2.567695] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1004second # [ 2.569137] systemd[1]: Finished Mountpoints Configured in the Real Root.1005second # [ 2.571034] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1006second # [ 2.588570] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1007second # [ 2.589282] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1008test # [ 2.657673] systemd[1]: Reloading finished in 197 ms.1009test # [ 2.670803] systemd-sysroot-fstab-check[126]: Requesting initrd-fs.target/start/replace...1010test # [ 2.674323] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1011test # [ 2.676354] systemd[1]: Finished rw-sysroot-nix-store.service.1012test # [ 2.680103] systemd-sysroot-fstab-check[126]: Requesting swap.target/start/replace...1013test # [ 2.686346] systemd[1]: Starting rw-sysroot-nix-store.service...1014test # [ 2.697047] systemd[1]: initrd-parse-etc.service: Deactivated successfully.1015test # [ 2.699004] systemd[1]: Finished Mountpoints Configured in the Real Root.1016test # [ 2.700494] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.1017test # [ 2.713446] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1018test # [ 2.714372] systemd[1]: Finished rw-sysroot-nix-store.service.1019second # [ 3.029275] (udev-worker)[97]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1020second # [ 3.033090] (udev-worker)[97]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1021second # [ 3.056969] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1022second # [ 3.057879] systemd[1]: Stopped Virtual Console Setup.1023second # [ 3.058484] systemd[1]: Stopping Virtual Console Setup...1024second # [ 3.061626] systemd[1]: Starting Virtual Console Setup...1025second # [ 3.091421] systemd-vconsole-setup[141]: Configuration of first virtual console was skipped, ignoring remaining ones.1026second # [ 3.096389] systemd[1]: Finished Virtual Console Setup.1027test # [ 3.124522] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/mtd_probe": No such file or directory1028test # [ 3.128286] (udev-worker)[99]: mtd0ro: /nix/store/x8r7i65cbx5zbf8f0dmnvwrm00r5ild8-systemd-260.2/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory1029test # [ 3.144142] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1030test # [ 3.146776] systemd[1]: Stopped Virtual Console Setup.1031test # [ 3.147389] systemd[1]: Stopping Virtual Console Setup...1032test # [ 3.151938] systemd[1]: Starting Virtual Console Setup...1033test # [ 3.171822] systemd-vconsole-setup[154]: Configuration of first virtual console was skipped, ignoring remaining ones.1034test # [ 3.174911] systemd[1]: Finished Virtual Console Setup.1035test # [ 3.275625] systemd[1]: Mounting /sysroot/nix/store...1036second # [ 3.300161] systemd[1]: Mounting /sysroot/nix/.ro-store...1037second # [ 3.305431] systemd[1]: Mounting /sysroot/nix/.rw-store...1038second # [ 3.317304] systemd[1]: Mounting /sysroot/run...1039test # [ 3.324519] systemd[1]: Mounted /sysroot/nix/store.1040test # [ 3.326013] systemd[1]: Reached target Initrd File Systems.1041test # [ 3.328834] systemd[1]: Starting Find NixOS closure...1042second # [ 3.332805] systemd[1]: Mounting /sysroot/tmp/shared...1043test # [ 3.336403] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1044second # [ 3.348460] systemd[1]: Mounting /sysroot/tmp/xchg...1045test # [ 3.370417] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1046test # [ 3.376401] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1047second # [ 3.382957] systemd[1]: Mounted /sysroot/nix/.ro-store.1048second # [ 3.389119] systemd[1]: Mounted /sysroot/nix/.rw-store.1049second # [ 3.390964] systemd[1]: Mounted /sysroot/run.1050test # [ 3.392499] systemd[1]: Finished Find NixOS closure.1051second # [ 3.396810] systemd[1]: Mounted /sysroot/tmp/shared.1052test # [ 3.394579] systemd[1]: Reached target Initrd Default Target.1053test # [ 3.395772] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1054second # [ 3.404556] systemd[1]: Starting rw-sysroot-nix-store.service...1055second # [ 3.408567] systemd[1]: Mounted /sysroot/tmp/xchg.1056second # [ 3.423921] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1057second # [ 3.425143] systemd[1]: Finished rw-sysroot-nix-store.service.1058test # [ 3.425353] systemd[1]: Stopped target Initrd Default Target.1059test # [ 3.426574] systemd[1]: Stopped target Basic System.1060test # [ 3.428228] systemd[1]: Stopped target Initrd Root Device.1061test # [ 3.429720] systemd[1]: Stopped target Path Units.1062test # [ 3.432106] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1063test # [ 3.433197] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1064test # [ 3.435111] systemd[1]: Stopped target Slice Units.1065test # [ 3.437113] systemd[1]: Stopped target Socket Units.1066test # [ 3.440118] systemd[1]: Stopped target System Initialization.1067test # [ 3.440833] systemd[1]: Stopped target Swaps.1068test # [ 3.441377] systemd[1]: Stopped target Timer Units.1069test # [ 3.441960] systemd[1]: dbus.socket: Deactivated successfully.1070test # [ 3.442649] systemd[1]: Closed D-Bus System Message Bus Socket.1071test # [ 3.443596] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1072test # [ 3.448224] systemd[1]: Stopped Find NixOS closure.1073test # [ 3.448791] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1074test # [ 3.450248] systemd[1]: Starting rw-sysroot-nix-store.service...1075test # [ 3.451723] systemd[1]: systemd-sysctl.service: Deactivated successfully.1076test # [ 3.455189] systemd[1]: Stopped Apply Kernel Variables.1077test # [ 3.455794] systemd[1]: systemd-modules-load.service: Deactivated successfully.1078test # [ 3.456747] systemd[1]: Stopped Load Kernel Modules.1079test # [ 3.458000] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1080test # [ 3.464387] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1081test # [ 3.466293] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1082test # [ 3.468246] systemd[1]: Stopped Create System Files and Directories.1083test # [ 3.470391] systemd[1]: Stopped target Local File Systems.1084test # [ 3.474051] systemd[1]: Stopped target Preparation for Local File Systems.1085test # [ 3.475932] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1086test # [ 3.486394] systemd[1]: Stopped Coldplug All udev Devices.1087test # [ 3.487177] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1088test # [ 3.489392] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1089test # [ 3.491251] systemd[1]: Stopped Virtual Console Setup.1090test # [ 3.492355] systemd[1]: initrd-cleanup.service: Deactivated successfully.1091test # [ 3.493909] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1092test # [ 3.495145] systemd[1]: systemd-udevd.service: Deactivated successfully.1093test # [ 3.497554] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1094test # [ 3.498213] systemd[1]: systemd-udevd.service: Consumed 1.030s CPU time over 2.054s wall clock time, 21.6M memory peak.1095test # [ 3.500242] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1096test # [ 3.501450] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1097test # [ 3.504181] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1098test # [ 3.504864] systemd[1]: Finished rw-sysroot-nix-store.service.1099test # [ 3.505382] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1100test # [ 3.506924] systemd[1]: Closed udev Control Socket.1101test # [ 3.507412] systemd[1]: Starting Cleanup udev Database...1102test # [ 3.508438] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1103test # [ 3.509221] systemd[1]: Stopped Create Static Device Nodes in /dev.1104test # [ 3.509782] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1105test # [ 3.510504] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1106test # [ 3.511169] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1107test # [ 3.511773] systemd[1]: Stopped Create List of Static Device Nodes.1108test # [ 3.532659] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1109test # [ 3.534533] systemd[1]: Finished Cleanup udev Database.1110test # [ 3.535074] systemd[1]: Reached target Switch Root.1111test # [ 3.536895] systemd[1]: Starting NixOS Activation...1112test # [ 3.743199] initrd-nixos-activation-start[179]: booting system configuration /nix/store/8s3xa3465xzn32x6zjcvn547misifpi7-nixos-system-test-test1113test # [ 3.816885] initrd-nixos-activation-start[179]: running activation script...1114second # [ 4.296904] systemd[1]: Mounting /sysroot/nix/store...1115test # [ 4.305844] initrd-nixos-activation-start[202]: setting up /etc...1116second # [ 4.341806] systemd[1]: Mounted /sysroot/nix/store.1117second # [ 4.344116] systemd[1]: Reached target Initrd File Systems.1118second # [ 4.348540] systemd[1]: Starting Find NixOS closure...1119second # [ 4.360352] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...1120second # [ 4.401074] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.1121second # [ 4.404347] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.1122second # [ 4.419443] systemd[1]: Finished Find NixOS closure.1123second # [ 4.422556] systemd[1]: Reached target Initrd Default Target.1124second # [ 4.428770] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...1125second # [ 4.457269] systemd[1]: Stopped target Initrd Default Target.1126second # [ 4.460494] systemd[1]: Stopped target Basic System.1127second # [ 4.461386] systemd[1]: Stopped target Initrd Root Device.1128second # [ 4.463805] systemd[1]: Stopped target Path Units.1129second # [ 4.467272] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.1130second # [ 4.468667] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.1131second # [ 4.471417] systemd[1]: Stopped target Slice Units.1132second # [ 4.476183] systemd[1]: Stopped target Socket Units.1133second # [ 4.476868] systemd[1]: Stopped target System Initialization.1134second # [ 4.477658] systemd[1]: Stopped target Swaps.1135second # [ 4.478294] systemd[1]: Stopped target Timer Units.1136second # [ 4.479150] systemd[1]: dbus.socket: Deactivated successfully.1137second # [ 4.479881] systemd[1]: Closed D-Bus System Message Bus Socket.1138second # [ 4.484185] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.1139second # [ 4.485060] systemd[1]: Stopped Find NixOS closure.1140second # [ 4.485685] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1141second # [ 4.487318] systemd[1]: Starting rw-sysroot-nix-store.service...1142second # [ 4.488845] systemd[1]: systemd-sysctl.service: Deactivated successfully.1143second # [ 4.491626] systemd[1]: Stopped Apply Kernel Variables.1144second # [ 4.492314] systemd[1]: systemd-modules-load.service: Deactivated successfully.1145second # [ 4.493757] systemd[1]: Stopped Load Kernel Modules.1146second # [ 4.495735] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.1147second # [ 4.497969] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.1148second # [ 4.503761] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.1149second # [ 4.511584] systemd[1]: Stopped Create System Files and Directories.1150second # [ 4.513322] systemd[1]: Stopped target Local File Systems.1151second # [ 4.513946] systemd[1]: Stopped target Preparation for Local File Systems.1152second # [ 4.514953] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.1153second # [ 4.515651] systemd[1]: Stopped Coldplug All udev Devices.1154second # [ 4.523675] systemd[1]: Stopping Rule-based Manager for Device Events and Files...1155second # [ 4.524494] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.1156second # [ 4.525609] systemd[1]: Stopped Virtual Console Setup.1157second # [ 4.527014] systemd[1]: initrd-cleanup.service: Deactivated successfully.1158second # [ 4.527990] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.1159second # [ 4.535464] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1160second # [ 4.536332] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1161second # [ 4.536928] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.1162second # [ 4.538040] systemd[1]: Finished rw-sysroot-nix-store.service.1163second # [ 4.538669] systemd[1]: systemd-udevd.service: Deactivated successfully.1164second # [ 4.539302] systemd[1]: Stopped Rule-based Manager for Device Events and Files.1165second # [ 4.539960] systemd[1]: systemd-udevd.service: Consumed 1.033s CPU time over 3.080s wall clock time, 21.6M memory peak.1166second # [ 4.541621] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.1167second # [ 4.542274] systemd[1]: Closed udev Control Socket.1168second # [ 4.542768] systemd[1]: Starting Cleanup udev Database...1169second # [ 4.543275] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.1170second # [ 4.543933] systemd[1]: Stopped Create Static Device Nodes in /dev.1171test # [ 4.547094] initrd-nixos-activation-start[202]: setting up age secrets...1172second # [ 4.552206] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.1173second # [ 4.552953] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.1174second # [ 4.553811] systemd[1]: kmod-static-nodes.service: Deactivated successfully.1175second # [ 4.554478] systemd[1]: Stopped Create List of Static Device Nodes.1176second # [ 4.564587] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.1177second # [ 4.566094] systemd[1]: Finished Cleanup udev Database.1178second # [ 4.566637] systemd[1]: Reached target Switch Root.1179second # [ 4.568901] systemd[1]: Starting NixOS Activation...1180second # [ 4.782876] initrd-nixos-activation-start[179]: booting system configuration /nix/store/1b96nrwxbw21h7bf1llsg40sg8kx139s-nixos-system-second-test1181test # [ 4.789241] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1182test # [ 4.789976] systemd[1]: Finished NixOS Activation.1183test # [ 4.790729] systemd[1]: Starting Switch Root...1184test # [ 4.805254] systemd[1]: Switching root.1185test # [ 4.852100] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1186second # [ 4.862148] initrd-nixos-activation-start[179]: running activation script...1187second # [ 5.374956] initrd-nixos-activation-start[202]: setting up /etc...1188test # [ 5.428987] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1189test # [ 5.432839] systemd[1]: Detected virtualization qemu.1190test # [ 5.434038] systemd[1]: Detected architecture arm64.1191test # [ 5.435565] systemd[1]: Detected first boot.1192test # [ 5.439440] systemd[1]: Initializing machine ID from random generator.1193second # [ 5.663191] initrd-nixos-activation-start[202]: setting up age secrets...1194test # [ 5.728134] systemd[1]: bpf-restrict-fs: LSM BPF program attached1195test # [ 5.916335] systemd[1]: Applying preset policy.1196second # [ 5.923364] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.1197second # [ 5.925197] systemd[1]: Finished NixOS Activation.1198second # [ 5.928065] systemd[1]: Starting Switch Root...1199second # [ 5.941511] systemd[1]: Switching root.1200second # [ 5.983523] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).1201test # [ 6.417394] systemd[1]: Populated /etc with preset unit settings.1202second # [ 6.533083] systemd[1]: systemd 260.2 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)1203second # [ 6.536195] systemd[1]: Detected virtualization qemu.1204second # [ 6.537284] systemd[1]: Detected architecture arm64.1205second # [ 6.539866] systemd[1]: Detected first boot.1206second # [ 6.545942] systemd[1]: Initializing machine ID from random generator.1207second # [ 6.833321] systemd[1]: bpf-restrict-fs: LSM BPF program attached1208test # [ 6.925465] systemd[1]: initrd-switch-root.service: Deactivated successfully.1209test # [ 6.926397] systemd[1]: Stopped initrd-switch-root.service.1210test # [ 6.928596] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1211test # [ 6.930294] systemd[1]: Created slice Slice /system/getty.1212test # [ 6.932004] systemd[1]: Created slice User and Session Slice.1213test # [ 6.933264] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1214test # [ 6.934051] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1215test # [ 6.935525] systemd[1]: Expecting device /dev/hvc0...1216test # [ 6.936126] systemd[1]: Expecting device /dev/ttyAMA0...1217test # [ 6.937059] systemd[1]: Reached target Local Encrypted Volumes.1218test # [ 6.938202] systemd[1]: Stopped target initrd-fs.target.1219test # [ 6.939211] systemd[1]: Stopped target initrd-root-fs.target.1220test # [ 6.940290] systemd[1]: Stopped target initrd-switch-root.target.1221test # [ 6.941325] systemd[1]: Reached target Virtual Machines and Containers.1222test # [ 6.942851] systemd[1]: Reached target Path Units.1223test # [ 6.944257] systemd[1]: Reached target Remote File Systems.1224test # [ 6.945663] systemd[1]: Reached target Slice Units.1225test # [ 6.946996] systemd[1]: Reached target Swaps.1226test # [ 6.952267] systemd[1]: Listening on Process Core Dump Socket.1227test # [ 6.955840] systemd[1]: Listening on Credential Encryption/Decryption.1228test # [ 6.960870] systemd[1]: Starting Journal Log Access Socket...1229test # [ 6.962670] systemd[1]: Listening on Journal Audit Socket.1230test # [ 6.963947] systemd[1]: Listening on Network Management Metrics Varlink Socket.1231test # [ 6.965560] systemd[1]: Listening on Network Management Netlink Socket.1232test # [ 6.967249] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1233test # [ 6.968980] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1234test # [ 6.971020] systemd[1]: Listening on udev Control Socket.1235test # [ 6.975884] systemd[1]: Mounting Huge Pages File System...1236test # [ 6.980160] systemd[1]: Mounting POSIX Message Queue File System...1237test # [ 6.988258] systemd[1]: Mounting Kernel Debug File System...1238test # [ 6.999156] systemd[1]: Mounting Kernel Trace File System...1239second # [ 7.002584] systemd[1]: Applying preset policy.1240test # [ 7.013386] systemd[1]: Starting Create List of Static Device Nodes...1241test # [ 7.024085] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1242test # [ 7.025888] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1243test # [ 7.035260] systemd[1]: Mounting Kernel Configuration File System...1244test # [ 7.036586] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1245test # [ 7.038852] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1246test # [ 7.050069] systemd[1]: Starting Load Kernel Module fuse...1247test # [ 7.052001] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671248test # [ 7.104312] systemd[1]: Starting Journal Service...1249test # [ 7.127313] systemd[1]: Starting Load Kernel Modules...1250test # [ 7.148482] fuse: init (API version 7.45)1251test # [ 7.156118] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1252test # [ 7.169468] systemd[1]: Starting Remount Root and Kernel File Systems...1253test # [ 7.175629] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1254test # [ 7.191363] systemd[1]: Starting Coldplug All udev Devices...1255test # [ 7.208590] systemd[1]: Listening on Journal Log Access Socket.1256test # [ 7.222170] systemd[1]: Mounted Huge Pages File System.1257test # [ 7.229347] systemd[1]: Mounted POSIX Message Queue File System.1258test # [ 7.236131] systemd[1]: Mounted Kernel Debug File System.1259test # [ 7.245299] systemd[1]: Mounted Kernel Trace File System.1260test # [ 7.248757] systemd[1]: Finished Create List of Static Device Nodes.1261test # [ 7.252055] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1262test # [ 7.256157] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1263test # [ 7.256779] systemd[1]: Mounted Kernel Configuration File System.1264test # [ 7.257276] systemd[1]: modprobe@fuse.service: Deactivated successfully.1265test # [ 7.263039] systemd[1]: Finished Load Kernel Module fuse.1266test # [ 7.270465] systemd-journald[322]: Collecting audit messages is enabled.1267test # [ 7.275389] systemd[1]: Finished Load Kernel Modules.1268test # [ 7.281223] systemd[1]: Mounting FUSE Control File System...1269test # [ 7.279577] systemd[1]: Queued start job for default target Multi-User System.1270test # [ 7.280628] systemd[1]: systemd-journald.service: Deactivated successfully.1271test # [ 7.292889] systemd[1]: Starting Firewall...1272test # [ 7.292252] systemd-modules-load[323]: Module 'atkbd' is built in1273test # [ 7.293641] systemd-modules-load[323]: Module 'ctr' is built in1274test # [ 7.294529] systemd-modules-load[323]: Module 'loop' is built in1275test # [ 7.312667] systemd[1]: Starting Apply Kernel Variables...1276test # [ 7.330036] EXT4-fs (vda): re-mounted b7fc944f-0269-4de1-a53f-1675dabf6eb2.1277test # [ 7.329327] systemd-oomd[324]: No swap; memory pressure usage will be degraded1278test # [ 7.355477] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1279test # [ 7.357933] systemd[1]: Started Journal Service.1280test # [ 7.368992] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1281test # [ 7.377400] systemd[1]: Finished Remount Root and Kernel File Systems.1282second # [ 7.438260] systemd[1]: Populated /etc with preset unit settings.1283test # [ 7.438653] systemd[1]: Starting Flush Journal to Persistent Storage...1284test # [ 7.443272] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1285test # [ 7.455652] systemd[1]: Starting Load/Save OS Random Seed...1286test # [ 7.461317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1287test # [ 7.466021] systemd[1]: Mounted FUSE Control File System.1288test # [ 7.486135] systemd[1]: Finished Apply Kernel Variables.1289test # [ 7.508742] systemd[1]: Starting Network Name Resolution...1290test # [ 7.561432] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1291test # [ 7.575573] systemd[1]: Starting Create Static Device Nodes in /dev...1292test # [ 7.594263] systemd-journald[322]: Received client request to flush runtime journal.1293test # [ 7.660507] systemd[1]: Finished Load/Save OS Random Seed.1294test # [ 7.663973] systemd[1]: Reached target First Boot Complete.1295test # [ 7.669571] systemd[1]: Finished Flush Journal to Persistent Storage.1296test # [ 7.724053] systemd[1]: Finished Create Static Device Nodes in /dev.1297test # [ 7.725197] systemd[1]: Reached target Preparation for Local File Systems.1298test # [ 7.732959] systemd-resolved[352]: Positive Trust Anchors:1299test # [ 7.734697] systemd-resolved[352]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1300test # [ 7.735980] systemd-resolved[352]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161301test # [ 7.738916] systemd-resolved[352]: 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 test1302test # [ 7.769858] systemd[1]: Starting Rule-based Manager for Device Events and Files...1303test # [ 7.883205] systemd-udevd[367]: Using default interface naming scheme 'v260'.1304second # [ 7.918014] systemd[1]: initrd-switch-root.service: Deactivated successfully.1305second # [ 7.918958] systemd[1]: Stopped initrd-switch-root.service.1306second # [ 7.921594] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.1307second # [ 7.924174] systemd[1]: Created slice Slice /system/getty.1308second # [ 7.925952] systemd[1]: Created slice User and Session Slice.1309second # [ 7.927527] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.1310second # [ 7.928958] systemd[1]: Started Forward Password Requests to Wall Directory Watch.1311second # [ 7.930484] systemd[1]: Expecting device /dev/hvc0...1312second # [ 7.931718] systemd[1]: Expecting device /dev/ttyAMA0...1313second # [ 7.932848] systemd[1]: Reached target Local Encrypted Volumes.1314second # [ 7.934078] systemd[1]: Stopped target initrd-fs.target.1315second # [ 7.935269] systemd[1]: Stopped target initrd-root-fs.target.1316test # [ 7.925930] systemd[1]: Mounting /run/wrappers...1317second # [ 7.936842] systemd[1]: Stopped target initrd-switch-root.target.1318second # [ 7.938472] systemd[1]: Reached target Virtual Machines and Containers.1319second # [ 7.940234] systemd[1]: Reached target Path Units.1320second # [ 7.941159] systemd[1]: Reached target Remote File Systems.1321second # [ 7.942278] systemd[1]: Reached target Slice Units.1322second # [ 7.943390] systemd[1]: Reached target Swaps.1323second # [ 7.948379] systemd[1]: Listening on Process Core Dump Socket.1324second # [ 7.952452] systemd[1]: Listening on Credential Encryption/Decryption.1325second # [ 7.957505] systemd[1]: Starting Journal Log Access Socket...1326second # [ 7.959800] systemd[1]: Listening on Journal Audit Socket.1327second # [ 7.960928] systemd[1]: Listening on Network Management Metrics Varlink Socket.1328second # [ 7.962179] systemd[1]: Listening on Network Management Netlink Socket.1329second # [ 7.963502] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.1330second # [ 7.965163] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1331second # [ 7.967523] systemd[1]: Listening on udev Control Socket.1332second # [ 7.972688] systemd[1]: Mounting Huge Pages File System...1333second # [ 7.977115] systemd[1]: Mounting POSIX Message Queue File System...1334second # [ 7.982171] systemd[1]: Mounting Kernel Debug File System...1335second # [ 7.992212] systemd[1]: Mounting Kernel Trace File System...1336second # [ 8.000779] systemd[1]: Starting Create List of Static Device Nodes...1337test # [ 7.997564] systemd[1]: Mounted /run/wrappers.1338test # [ 8.001216] systemd[1]: Reached target Local File Systems.1339test # [ 8.002618] systemd-resolved[352]: Using system hostname 'test'.1340second # [ 8.014708] systemd[1]: Starting Load Kernel Module 9pnet_virtio...1341second # [ 8.016603] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1342test # [ 8.008157] systemd[1]: Listening on Boot Loader Control Service Socket.1343test # [ 8.017855] systemd[1]: Starting register-nix-paths.service...1344second # [ 8.027654] systemd[1]: Mounting Kernel Configuration File System...1345second # [ 8.029516] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm1346test # [ 8.021591] systemd[1]: Starting Create SUID/SGID Wrappers...1347second # [ 8.032029] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1348test # [ 8.023313] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1349second # [ 8.045428] systemd[1]: Starting Load Kernel Module fuse...1350second # [ 8.046788] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671351test # [ 8.037667] systemd[1]: Starting Save Transient machine-id to Disk...1352test # [ 8.073849] systemd[1]: Starting Create System Files and Directories...1353test # [ 8.076801] systemd[1]: Started Network Name Resolution.1354second # [ 8.091216] systemd[1]: Starting Journal Service...1355second # [ 8.120019] systemd[1]: Starting Load Kernel Modules...1356second # [ 8.139619] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...1357second # [ 8.159432] systemd[1]: Starting Remount Root and Kernel File Systems...1358second # [ 8.169647] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1359test # [ 8.178449] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1360second # [ 8.190182] systemd[1]: Starting Coldplug All udev Devices...1361test # [ 8.190397] systemd[1]: Finished Save Transient machine-id to Disk.1362second # [ 8.215798] systemd[1]: Listening on Journal Log Access Socket.1363second # [ 8.234011] fuse: init (API version 7.45)1364second # [ 8.239819] systemd[1]: Mounted Huge Pages File System.1365second # [ 8.241772] systemd[1]: Mounted POSIX Message Queue File System.1366second # [ 8.244441] systemd[1]: Mounted Kernel Debug File System.1367second # [ 8.249084] systemd[1]: Mounted Kernel Trace File System.1368second # [ 8.253351] systemd-journald[302]: Collecting audit messages is enabled.1369second # [ 8.258640] systemd[1]: Finished Create List of Static Device Nodes.1370second # [ 8.264459] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.1371second # [ 8.270592] systemd[1]: Finished Load Kernel Module 9pnet_virtio.1372second # [ 8.269526] systemd[1]: Queued start job for default target Multi-User System.1373second # [ 8.279118] systemd[1]: Started Journal Service.1374second # [ 8.278566] systemd[1]: systemd-journald.service: Deactivated successfully.1375test # [ 8.280586] systemd[1]: Finished Create System Files and Directories.1376second # [ 8.284863] systemd-modules-load[303]: Module 'atkbd' is built in1377second # [ 8.285608] systemd-modules-load[303]: Module 'ctr' is built in1378second # [ 8.286302] systemd-modules-load[303]: Module 'loop' is built in1379second # [ 8.286972] systemd[1]: Mounted Kernel Configuration File System.1380second # [ 8.287643] systemd[1]: modprobe@fuse.service: Deactivated successfully.1381test # [ 8.296325] systemd[1]: Starting Rebuild Journal Catalog...1382second # [ 8.300238] systemd[1]: Finished Load Kernel Module fuse.1383second # [ 8.302959] systemd[1]: Finished Load Kernel Modules.1384test # [ 8.299295] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1385second # [ 8.310861] systemd[1]: Mounting FUSE Control File System...1386second # [ 8.315442] systemd[1]: Starting Firewall...1387second # [ 8.342808] EXT4-fs (vda): re-mounted b578eba6-f3f9-49f5-bc89-137d002a3627.1388second # [ 8.338649] systemd-oomd[304]: No swap; memory pressure usage will be degraded1389second # [ 8.342377] systemd[1]: Starting Apply Kernel Variables...1390test # [ 8.344493] systemd[1]: Started Rule-based Manager for Device Events and Files.1391second # [ 8.358288] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...1392second # [ 8.359061] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.1393second # [ 8.366007] systemd[1]: Finished Remount Root and Kernel File Systems.1394second # [ 8.410568] systemd[1]: Starting Flush Journal to Persistent Storage...1395second # [ 8.411377] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1396second # [ 8.433401] systemd[1]: Starting Load/Save OS Random Seed...1397second # [ 8.434569] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-uki1398test # [ 8.454693] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1399second # [ 8.485107] systemd[1]: Mounted FUSE Control File System.1400test # [ 8.526246] systemd[1]: Finished Rebuild Journal Catalog.1401second # [ 8.531208] systemd[1]: Finished Apply Kernel Variables.1402test # [ 8.539208] systemd[1]: Starting Update is Completed...1403second # [ 8.557030] systemd-journald[302]: Received client request to flush runtime journal.1404second # [ 8.628146] systemd[1]: Starting Network Name Resolution...1405second # [ 8.632972] systemd[1]: Finished Load/Save OS Random Seed.1406second # [ 8.636310] systemd[1]: Reached target First Boot Complete.1407second # [ 8.638344] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.1408test # [ 8.638670] systemd[1]: Finished Update is Completed.1409second # [ 8.648322] systemd[1]: Starting Create Static Device Nodes in /dev...1410second # [ 8.650128] systemd[1]: Finished Flush Journal to Persistent Storage.1411test # [ 8.677742] systemd[1]: Finished Coldplug All udev Devices.1412second # [ 8.732942] systemd-resolved[332]: Positive Trust Anchors:1413second # [ 8.735202] systemd-resolved[332]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d1414second # [ 8.736836] systemd-resolved[332]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b161415second # [ 8.739568] systemd-resolved[332]: 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 test1416second # [ 8.774737] systemd[1]: Finished Create Static Device Nodes in /dev.1417second # [ 8.777972] systemd[1]: Reached target Preparation for Local File Systems.1418second # [ 8.784405] systemd[1]: Starting Rule-based Manager for Device Events and Files...1419test # [ 8.887668] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1420second # [ 8.919898] systemd-udevd[346]: Using default interface naming scheme 'v260'.1421second # [ 8.922999] systemd[1]: Mounting /run/wrappers...1422second # [ 9.003051] systemd[1]: Mounted /run/wrappers.1423second # [ 9.004340] systemd[1]: Reached target Local File Systems.1424second # [ 9.012112] systemd[1]: Listening on Boot Loader Control Service Socket.1425second # [ 9.019063] systemd[1]: Starting register-nix-paths.service...1426second # [ 9.022711] systemd[1]: Starting Create SUID/SGID Wrappers...1427second # [ 9.024421] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1428second # [ 9.030147] systemd-resolved[332]: Using system hostname 'second'.1429second # [ 9.037083] systemd[1]: Starting Save Transient machine-id to Disk...1430second # [ 9.056073] systemd[1]: Starting Create System Files and Directories...1431second # [ 9.065795] systemd[1]: Started Network Name Resolution.1432test # [ 9.185785] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1433second # [ 9.202886] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.1434second # [ 9.207958] systemd[1]: Finished Save Transient machine-id to Disk.1435test # [ 9.245003] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1436test # [ 9.246960] systemd[1]: Finished Create SUID/SGID Wrappers.1437test # [ 9.266115] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1438second # [ 9.281540] systemd[1]: Finished Create System Files and Directories.1439second # [ 9.303055] systemd[1]: Starting Rebuild Journal Catalog...1440second # [ 9.309860] systemd[1]: Starting Record System Boot/Shutdown in UTMP...1441second # [ 9.352568] systemd[1]: Started Rule-based Manager for Device Events and Files.1442test # [ 9.453920] systemd[1]: Finished register-nix-paths.service.1443test # [ 9.455932] systemd[1]: Reached target System Initialization.1444second # [ 9.463100] systemd[1]: Finished Record System Boot/Shutdown in UTMP.1445test # [ 9.459733] systemd[1]: Started Discard unused filesystem blocks once a week.1446test # [ 9.463131] systemd[1]: Started Daily Cleanup of Temporary Directories.1447test # [ 9.464598] systemd[1]: Reached target Timer Units.1448test # [ 9.466539] systemd[1]: Listening on D-Bus System Message Bus Socket.1449test # [ 9.468421] systemd[1]: Listening on Nix Daemon Socket.1450test # [ 9.472965] systemd[1]: Listening on Authorization Manager Agent Helper.1451test # [ 9.475393] systemd[1]: Listening on Hostname Service Socket.1452test # [ 9.478156] systemd[1]: Reached target Socket Units.1453test # [ 9.480812] systemd[1]: Starting D-Bus System Message Bus...1454test # [ 9.501790] systemd[1]: Finished Firewall.1455test # [ 9.503217] systemd[1]: Reached target Preparation for Network.1456test # [ 9.509068] systemd[1]: Starting Network Management...1457second # [ 9.544498] systemd[1]: Finished Rebuild Journal Catalog.1458second # [ 9.558223] systemd[1]: Starting Update is Completed...1459second # [ 9.635502] systemd[1]: Finished Update is Completed.1460test # [ 9.640289] (udev-worker)[480]: Network interface NamePolicy= disabled on kernel command line.1461test # [ 9.644754] (udev-worker)[467]: Network interface NamePolicy= disabled on kernel command line.1462test # [ 9.679749] dbus-broker-launch[553]: Looking up NSS user entry for 'systemd-timesync'...1463test # [ 9.702343] dbus-broker-launch[553]: NSS returned no entry for 'systemd-timesync'1464test # [ 9.706010] dbus-broker-launch[553]: Invalid user-name in /nix/store/hq19d34s1b2pl0p886ir814jdqrkxwwr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1465second # [ 9.724334] systemd[1]: Finished Coldplug All udev Devices.1466test # [ 9.775929] cfg80211: Loading compiled-in X.509 certificates for regulatory database1467test # [ 9.776774] dbus-broker-launch[553]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1468test # [ 9.780310] dbus-broker-launch[553]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1469test # [ 9.785509] dbus-broker-launch[553]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1470test # [ 9.796183] dbus-broker-launch[553]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1471test # [ 9.800634] systemd[1]: Started D-Bus System Message Bus.1472test # [ 9.831968] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1473test # [ 9.832526] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1474test # [ 9.849384] dbus-broker-launch[553]: Ready1475test # [ 9.854222] systemd[1]: Reached target Basic System.1476test # [ 9.860485] systemd[1]: Starting Network Manager Script Dispatcher Service...1477test # [ 9.868447] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1478test # [ 9.884680] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1479test # [ 9.905099] systemd-networkd[556]: /etc/systemd/network/99-ethernet-default-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1480test # [ 9.914224] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1481test # [ 9.919668] systemd-networkd[556]: /etc/systemd/network/99-wireless-client-dhcp.network: No valid settings found in the [Match] section, ignoring file. To match all interfaces, add Name=* in the [Match] section.1482test # [ 9.927121] systemd-networkd[556]: lo: Link UP1483test # [ 9.928143] systemd-networkd[556]: lo: Gained carrier1484second # [ 9.933414] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs1485test # [ 9.946106] mousedev: PS/2 mouse device common for all mice1486test # [ 9.940468] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1487test # [ 9.947273] systemd-networkd[556]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1488test # [ 9.953016] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1489test # [ 9.962898] systemd-networkd[556]: eth1: Link UP1490test # [ 9.971536] systemd-networkd[556]: eth1: Gained carrier1491test # [ 9.973411] systemd-networkd[556]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1492test # [ 9.979631] systemd[1]: Starting Network Manager...1493test # [ 9.993518] systemd[1]: Started backdoor.service.1494test # [ 10.039365] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[567]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1495test # [ 10.048278] systemd[1]: Starting Import lastlog data into lastlog2 database...1496test # [ 10.071136] ispsphlnyb2js715g0mg6bn3157dmskk-wifi-secrets[568]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1497test # [ 10.081030] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1498test # [ 10.119717] systemd[1]: Starting Post-Boot Actions...1499test # [ 10.135484] vz5k34ilhns09vh81dvgw89nvkf1plwv-wifi-secrets[569]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1500test # [ 10.180140] systemd[1]: Started Reset console on configuration changes.1501test # [ 10.195814] systemd[1]: Started Network Management.1502test # connecting to host...1503test # [ 10.229749] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1504second # [ 10.236237] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1505second # [ 10.241100] systemd[1]: Finished Create SUID/SGID Wrappers.1506test # [ 10.246125] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1507test # [ 10.258383] systemd[1]: NetworkManager-setup-secrets-wg-test-one.service: Deactivated successfully.1508second # [ 10.263011] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.1509test # [ 10.260631] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1510test # [ 10.270189] systemd[1]: NetworkManager-setup-secrets-wg-test-open.service: Deactivated successfully.1511test # [ 10.279485] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1512test: Guest shell says: b'Spawning backdoor root shell...\n'1513test: connected to guest root shell1514second # [ 10.321365] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.1515test: (connecting took 10.60 seconds)1516test: (finished: waiting for the VM to finish booting, in 10.60 seconds)1517test # [ 10.338642] systemd[1]: Finished Post-Boot Actions.1518test # [ 10.363826] systemd[1]: Started Network Manager Script Dispatcher Service.1519test # [ 10.373500] nsncd[581]: Sep 17 00:19:15.207 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1520test # [ 10.379644] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1521test # [ 10.387521] systemd[1]: Started Name Service Cache Daemon (nsncd).1522test # [ 10.393460] systemd[1]: Reached target Host and Network Name Lookups.1523test # [ 10.394069] systemd[1]: Reached target User and Group Name Lookups.1524test # [ 10.421725] systemd[1]: Starting User Login Management...1525test # [ 10.434011] systemd[1]: Finished Import lastlog data into lastlog2 database.1526second # [ 10.456996] systemd[1]: Finished register-nix-paths.service.1527second # [ 10.458764] systemd[1]: Reached target System Initialization.1528second # [ 10.461170] systemd[1]: Started Discard unused filesystem blocks once a week.1529second # [ 10.463546] systemd[1]: Started Daily Cleanup of Temporary Directories.1530second # [ 10.468470] systemd[1]: Reached target Timer Units.1531second # [ 10.470534] systemd[1]: Listening on D-Bus System Message Bus Socket.1532second # [ 10.473242] systemd[1]: Listening on Nix Daemon Socket.1533second # [ 10.478189] systemd[1]: Listening on Authorization Manager Agent Helper.1534second # [ 10.481049] systemd[1]: Listening on Hostname Service Socket.1535second # [ 10.481668] systemd[1]: Reached target Socket Units.1536second # [ 10.484867] systemd[1]: Starting D-Bus System Message Bus...1537test # [ 10.544586] systemd[1]: Starting Hostname Service...1538second # [ 10.566065] systemd[1]: Finished Firewall.1539second # [ 10.566690] systemd[1]: Reached target Preparation for Network.1540second # [ 10.568897] systemd[1]: Starting Network Management...1541test # [ 10.605260] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1542test # [ 10.637216] systemd-logind[622]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1543test # [ 10.640916] systemd-logind[622]: Watching system buttons on /dev/input/event0 (gpio-keys)1544test # [ 10.641600] systemd-logind[622]: New seat seat0.1545test # [ 10.646586] systemd[1]: Started User Login Management.1546test # [ 10.653708] systemd[1]: Starting linger-users.service...1547second # [ 10.682589] dbus-broker-launch[529]: Looking up NSS user entry for 'systemd-timesync'...1548second # [ 10.700312] dbus-broker-launch[529]: NSS returned no entry for 'systemd-timesync'1549second # [ 10.701702] dbus-broker-launch[529]: Invalid user-name in /nix/store/hq19d34s1b2pl0p886ir814jdqrkxwwr-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1550test # [ 10.707073] systemd[1]: Started Hostname Service.1551second # [ 10.720372] (udev-worker)[397]: Network interface NamePolicy= disabled on kernel command line.1552test # [ 10.731090] systemd[1]: linger-users.service: Deactivated successfully.1553test # [ 10.731753] systemd[1]: Finished linger-users.service.1554second # [ 10.735777] (udev-worker)[396]: Network interface NamePolicy= disabled on kernel command line.1555second # [ 10.761294] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.nm_dispatcher' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_dispatcher.service'1556second # [ 10.767495] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.nm_priv_helper' in service file '/nix/store/ymh91gdvr306kjzkdbl3hk635w870k65-networkmanager-1.56.0/share/dbus-1/system-services/org.freedesktop.nm_priv_helper.service'1557second # [ 10.777841] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.ModemManager1' in service file '/nix/store/wrvznzg05dkx9ia8dwkg7f4i04nhv3v6-modemmanager-1.24.2/share/dbus-1/system-services/org.freedesktop.ModemManager1.service'1558second # [ 10.782272] dbus-broker-launch[529]: Ignoring duplicate name 'org.freedesktop.PolicyKit1' in service file '/nix/store/wns3agiik86mwy41ad6z21sjzqgla78w-polkit-127/share/dbus-1/system-services/org.freedesktop.PolicyKit1.service'1559second # [ 10.798414] systemd[1]: Started D-Bus System Message Bus.1560second # [ 10.825530] cfg80211: Loading compiled-in X.509 certificates for regulatory database1561test # [ 10.829477] systemd-networkd[556]: eth0: Link UP1562test # [ 10.830001] systemd-networkd[556]: eth0: Gained carrier1563second # [ 10.862660] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'1564second # [ 10.864439] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'1565test # [ 10.857180] systemd[1]: Started Network Manager.1566test # [ 10.859189] systemd[1]: Reached target Network.1567second # [ 10.868616] dbus-broker-launch[529]: Ready1568test # [ 10.868315] systemd[1]: Starting Ensure that NetworkManager declarative profiles are created...1569second # [ 10.872791] systemd[1]: Reached target Basic System.1570test # [ 10.876782] systemd[1]: Starting Permit User Sessions...1571second # [ 10.882035] systemd[1]: Starting Network Manager Script Dispatcher Service...1572second # [ 10.887628] systemd[1]: Starting Generate Wi-Fi secrets for NetworkManager...1573second # [ 10.894282] systemd[1]: Starting Network Manager...1574second # [ 10.921490] systemd[1]: Started backdoor.service.1575second # [ 10.945834] systemd-networkd[536]: /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.1576second # [ 10.954340] systemd[1]: Starting Import lastlog data into lastlog2 database...1577second # [ 10.956909] systemd-networkd[536]: /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.1578second # [ 10.964624] systemd-networkd[536]: lo: Link UP1579second # [ 10.968181] systemd-networkd[536]: lo: Gained carrier1580second # [ 10.974320] systemd[1]: Starting Name Service Cache Daemon (nsncd)...1581second # [ 11.007621] systemd[1]: Starting Post-Boot Actions...1582test # [ 11.016844] systemd[1]: Finished Permit User Sessions.1583second # [ 11.025360] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1584second # [ 11.031475] systemd-networkd[536]: eth1: Configuring with /etc/systemd/network/40-eth1.network.1585test # [ 11.036224] systemd[1]: Started Getty on tty1.1586test # [ 11.036729] systemd[1]: Reached target Login Prompts.1587second # [ 11.042167] systemd-networkd[536]: eth1: Link UP1588second # [ 11.051018] systemd-networkd[536]: eth1: Gained carrier1589second # [ 11.054567] systemd-networkd[536]: eth1: Found matching .network file, based on potentially unpredictable interface name: /etc/systemd/network/40-eth1.network1590test # [ 11.069898] systemd-resolved[352]: eth0: Bus client set default route setting: yes1591second # [ 11.076974] izfdm2vcilqki06lkkfdqg1q1s7gnwp8-wifi-secrets[547]: Generating wifi secrets file: /run/secrets/NetworkManager/wifi-secrets1592test # [ 11.073855] systemd-resolved[352]: eth0: Bus client set DNS server list to: 10.0.2.31593second # [ 11.083847] systemd[1]: Started Reset console on configuration changes.1594test # [ 11.085084] NetworkManager[570]: <warn> [1789604355.9181] keyfile: load: "/var/run/NetworkManager/system-connections/one.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1595test # [ 11.087732] NetworkManager[570]: <warn> [1789604355.9207] keyfile: load: "/var/run/NetworkManager/system-connections/all.nmconnection": failed to load connection: invalid connection: connection.id: property is empty1596second # [ 11.103200] systemd[1]: Started Network Management.1597second # [ 11.147622] systemd[1]: NetworkManager-setup-secrets-wg-test-all.service: Deactivated successfully.1598test # [ 11.147777] systemd[1]: NetworkManager-ensure-profiles.service: Deactivated successfully.1599test # [ 11.152644] systemd[1]: Finished Ensure that NetworkManager declarative profiles are created.1600second # [ 11.165283] systemd[1]: Finished Generate Wi-Fi secrets for NetworkManager.1601second # [ 11.236241] systemd[1]: Finished Post-Boot Actions.1602second # connecting to host...1603second # [ 11.267140] mousedev: PS/2 mouse device common for all mice1604second # [ 11.262897] systemd[1]: Starting Enable Persistent Storage in systemd-networkd...1605second # [ 11.299496] systemd[1]: Started Name Service Cache Daemon (nsncd).1606second # [ 11.304275] nsncd[551]: Sep 17 00:19:16.128 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"1607test # [ 11.302416] systemd[1]: Reached target Multi-User System.1608test # [ 11.306998] systemd[1]: Startup finished in 712ms (kernel) + 4.217s (initrd) + 6.374s (userspace) = 11.303s.1609second # [ 11.312739] systemd[1]: Reached target Host and Network Name Lookups.1610second # [ 11.316914] systemd[1]: Reached target User and Group Name Lookups.1611second # [ 11.331618] systemd[1]: Starting User Login Management...1612second # [ 11.334310] systemd[1]: Finished Import lastlog data into lastlog2 database.1613second # [ 11.388366] systemd[1]: Started Network Manager Script Dispatcher Service.1614second # [ 11.408808] systemd[1]: Finished Enable Persistent Storage in systemd-networkd.1615second # [ 11.497339] systemd[1]: Starting Hostname Service...1616second # [ 11.508371] systemd-logind[580]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)1617second # [ 11.513488] systemd-logind[580]: Watching system buttons on /dev/input/event0 (gpio-keys)1618second # [ 11.514251] systemd-logind[580]: New seat seat0.1619second # [ 11.520113] systemd[1]: Started User Login Management.1620second # [ 11.531061] systemd[1]: Starting linger-users.service...1621second # [ 11.617013] systemd[1]: linger-users.service: Deactivated successfully.1622second # [ 11.618232] systemd[1]: Finished linger-users.service.1623test: (finished: waiting for unit NetworkManager.service, in 11.90 seconds)1624test: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection1625second # [ 11.654266] systemd[1]: Started Hostname Service.1626test: (finished: must succeed: cat /run/NetworkManager/system-connections/one.nmconnection, in 0.04 seconds)1627cleanup1628kill QemuMachine (pid 15)1629second # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1630kill QemuMachine (pid 14)1631test # qemu-system-aarch64: terminating on signal 15 from pid 6 (/nix/store/akbq19lqha5w5h9hcchn309znqm94pq0-python3-3.13.15/bin/python3.13)1632(finished: cleanup, in 0.24 seconds)1633Traceback (most recent call last):1634 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/bin/.nixos-test-driver-wrapped", line 9, in <module>1635 sys.exit(main())1636 ~~~~^^1637 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/__init__.py", line 183, in main1638 driver.run_tests()1639 ~~~~~~~~~~~~~~~~^^1640 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 434, in run_tests1641 self.test_script()1642 ~~~~~~~~~~~~~~~~^^1643 File "/nix/store/g9529d3bpfr38l0hs56ri15djwx9bi06-nixos-test-driver-1.1/lib/python3.13/site-packages/test_driver/driver.py", line 392, in test_script1644 exec(self.tests, symbols, None)1645 ~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^1646 File "<string>", line 4, in <module>1647AssertionError: Expected mock password in connection file:1648[connection]1649autoconnect=true1650id=1651type=wifi16521653[wifi]1654mode=infrastructure1655ssid=16561657[wifi-security]1658key-mgmt=wpa-psk1659psk=1660