vm-test-run-switch-test-basics
checks.aarch64-linux.switchTest-basics
· build #31
· raw
1tribuchet: building on eliza2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.oF39HAcEhx', fmt=raw size=107374182413machine # mke2fs 1.47.4 (6-Mar-2025)14machine # Discarding device blocks: 0/262144 done15machine # Creating filesystem with 262144 4k blocks and 65536 inodes16machine # Filesystem UUID: b597e3e9-ef81-46d6-be01-b213d4719a2217machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]27machine # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202628machine # [ 0.000000] KASLR enabled29machine # [ 0.000000] random: crng init done30machine # [ 0.000000] Machine model: linux,dummy-virt31machine # [ 0.000000] efi: UEFI not found.32machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT33machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]34machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]35machine # [ 0.000000] Zone ranges:36machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]37machine # [ 0.000000] DMA32 empty38machine # [ 0.000000] Normal empty39machine # [ 0.000000] Device empty40machine # [ 0.000000] Movable zone start for each node41machine # [ 0.000000] Early memory node ranges42machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]43machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]44machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000045machine # [ 0.000000] psci: probing for conduit method from DT.46machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.47machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs48machine # [ 0.000000] psci: Trusted OS migration not required49machine # [ 0.000000] psci: SMC Calling Convention v1.150machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)51machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129652machine # [ 0.000000] Detected PIPT I-cache on CPU053machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)54machine # [ 0.000000] CPU features: detected: GICv3 CPU interface55machine # [ 0.000000] CPU features: detected: Spectre-v456machine # [ 0.000000] CPU features: detected: Spectre-BHB57machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3858machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2359machine # [ 0.000000] alternatives: applying boot alternatives60machine # [ 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/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/init regInfo=/nix/store/d5q41b5lw6z5kgxlxijdlg06v3nadhji-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/d5q41b5lw6z5kgxlxijdlg06v3nadhji-closure-info/registration", will be passed to user space.62machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes63machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)64machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)65machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB66machine # [ 0.000000] software IO TLB: area num 1.67machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)68machine # [ 0.000000] Fallback order for Node 0: 069machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214470machine # [ 0.000000] Policy zone: DMA71machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off72machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=173machine # [ 0.000000] allocated 2097152 bytes of page_ext74machine # [ 0.000000] ftrace: allocating 74885 entries in 294 pages75machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups76machine # [ 0.000000] rcu: Hierarchical RCU implementation.77machine # [ 0.000000] rcu: RCU event tracing is enabled.78machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.79machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.80machine # [ 0.000000] Rude variant of Tasks RCU enabled.81machine # [ 0.000000] Tracing variant of Tasks RCU enabled.82machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.83machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=184machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.85machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.86machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.87machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 088machine # [ 0.000000] GICv3: 256 SPIs implemented89machine # [ 0.000000] GICv3: 0 Extended SPIs implemented90machine # [ 0.000000] Root IRQ handler: gic_handle_irq91machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI92machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=093machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a000094machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]95machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44cf0000 (indirect, esz 8, psz 64K, shr 1)96machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44d00000 (flat, esz 8, psz 64K, shr 1)97machine # [ 0.000000] GICv3: using LPI property table @0x0000000044d1000098machine # [ 0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000044d2000099machine # [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.100machine # [ 0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns101machine # [ 0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).102machine # [ 0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns103machine # [ 0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns104machine # [ 0.000031] arm-pv: using stolen time PV105machine # [ 0.000428] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000792] Console: colour dummy device 80x25107machine # [ 0.000800] printk: legacy console [tty0] enabled108machine # [ 0.001001] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.001008] pid_max: default: 32768 minimum: 301110machine # [ 0.001088] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.001226] landlock: Up and running.112machine # [ 0.001229] Yama: becoming mindful.113machine # [ 0.001695] LSM support for eBPF active114machine # [ 0.001831] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001852] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002945] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003690] rcu: Hierarchical SRCU implementation.118machine # [ 0.003694] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004918] fsl-mc MSI: its@8080000 domain created120machine # [ 0.005010] EFI services will not be available.121machine # [ 0.005075] smp: Bringing up secondary CPUs ...122machine # [ 0.005084] smp: Brought up 1 node, 1 CPU123machine # [ 0.005088] SMP: Total of 1 processors activated.124machine # [ 0.005090] CPU: All CPU(s) started at EL1125machine # [ 0.005103] CPU features: detected: Branch Target Identification126machine # [ 0.005108] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.005112] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.005115] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.005119] CPU features: detected: Common not Private translations130machine # [ 0.005122] CPU features: detected: CRC32 instructions131machine # [ 0.005125] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.005129] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.005132] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.005140] CPU features: detected: E0PD135machine # [ 0.005142] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.005145] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.005149] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.005152] CPU features: detected: Fine Grained Traps139machine # [ 0.005155] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.005159] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.005162] CPU features: detected: LSE atomic instructions142machine # [ 0.005165] CPU features: detected: Privileged Access Never143machine # [ 0.005168] CPU features: detected: PMUv3144machine # [ 0.005171] CPU features: detected: RAS Extension Support145machine # [ 0.005173] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.005176] CPU features: detected: Random Number Generator147machine # [ 0.005179] CPU features: detected: Speculation barrier (SB)148machine # [ 0.005181] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.005184] CPU features: detected: TLB range maintenance instructions150machine # [ 0.005189] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.005224] alternatives: applying system-wide alternatives152machine # [ 0.008323] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008576] Memory: 894656K/1048576K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 112608K reserved, 32768K cma-reserved)154machine # [ 0.008929] devtmpfs: initialized155machine # [ 0.010685] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010708] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010907] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010912] 0 pages in range for non-PLT usage159machine # [ 0.010913] 508288 pages in range for PLT usage160machine # [ 0.011034] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011797] DMI not present or invalid.162machine # [ 0.014924] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.017223] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.017366] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.017525] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.017546] audit: initializing netlink subsys (disabled)167machine # [ 0.018115] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.018117] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.018121] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.018123] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.018129] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.018169] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.018177] cpuidle: using governor ladder174machine # [ 0.018183] cpuidle: using governor menu175machine # [ 0.018378] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.018394] ASID allocator initialised with 65536 entries177machine # [ 0.019519] Serial: AMBA PL011 UART driver178machine # [ 0.024709] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.024868] printk: console [ttyAMA0] enabled180machine # [ 0.150024] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.150049] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.150055] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.150059] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.150064] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.150068] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.150080] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.150085] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.157517] fbcon: Taking over console189machine # [ 0.157535] ACPI: Interpreter disabled.190machine # [ 0.159401] iommu: Default domain type: Translated191machine # [ 0.159411] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.161126] SCSI subsystem initialized193machine # [ 0.166413] usbcore: registered new interface driver usbfs194machine # [ 0.166446] usbcore: registered new interface driver hub195machine # [ 0.166465] usbcore: registered new device driver usb196machine # [ 0.166750] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.166757] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.166767] PTP clock support registered199machine # [ 0.166814] EDAC MC: Ver: 3.0.0200machine # [ 0.171575] scmi_core: SCMI protocol bus registered201machine # [ 0.172601] FPGA manager framework202machine # [ 0.173626] vgaarb: loaded203machine # [ 0.174293] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.180588] VFS: Disk quotas dquot_6.6.0205machine # [ 0.180649] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.182352] netfs: FS-Cache loaded207machine # [ 0.182492] pnp: PnP ACPI: disabled208machine # [ 0.186501] NET: Registered PF_INET protocol family209machine # [ 0.186662] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.216394] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.216446] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.216473] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.216517] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.216592] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.216679] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.216737] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.216795] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.216883] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.216926] NET: Registered PF_XDP protocol family220machine # [ 0.216949] PCI: CLS 0 bytes, default 64221machine # [ 0.217214] Trying to unpack rootfs image as initramfs...222machine # [ 0.238345] kvm [1]: HYP mode not available223machine # [ 0.337578] Initialise system trusted keyrings224machine # [ 0.338393] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.339710] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.340501] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.364572] Key type asymmetric registered228machine # [ 0.364607] Asymmetric key parser 'x509' registered229machine # [ 0.364685] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.370376] io scheduler mq-deadline registered231machine # [ 0.370399] io scheduler kyber registered232machine # [ 0.378456] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.379837] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.380220] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.380238] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.380259] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.380292] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.380315] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.380339] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.380415] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.380424] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.380431] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.380436] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.380441] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.380499] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.380944] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.381132] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.381148] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.381177] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.381193] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.381662] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.381847] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.381863] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.381892] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.406405] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.406624] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.406641] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.406670] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.407194] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.407383] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.407399] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.407428] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.407891] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.408076] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.408092] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.408121] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.408608] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.408792] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.408808] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.408837] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.409295] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.409478] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.409494] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.409523] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.409539] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.410007] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.410192] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.410221] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.410693] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.410884] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.410913] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.411303] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.411485] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.411743] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.412052] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.412069] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.412098] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.412575] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.412759] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.412775] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.412803] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.413395] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.413406] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.413411] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.413456] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.413502] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.413547] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.413592] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.413637] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.413683] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.413729] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.413774] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.413820] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.413882] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.413954] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.413975] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.413996] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.414017] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.414039] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.414062] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.414084] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.414105] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.414126] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.414148] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.414170] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.414192] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.414214] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.414238] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.414261] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.414282] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.482579] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.482632] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.482656] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.482679] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.482708] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.482747] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.482757] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.482762] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.483968] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.510903] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.513314] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.524074] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.526266] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.536263] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.543016] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.545309] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.555887] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.558147] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.569256] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.572717] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.587642] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.590605] msm_serial: driver initialized343machine # [ 0.590744] SuperH (H)SCI(F) driver initialized344machine # [ 0.590801] STM32 USART driver initialized345machine # [ 0.625153] loop: module loaded346machine # [ 0.625366] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.626127] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.639065] megasas: 07.734.00.00-rc1349machine # [ 0.639844] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.641838] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.641867] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.643596] Using buffer write method353machine # [ 0.643673] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.645435] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.645460] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.666010] Using buffer write method357machine # [ 0.666057] Concatenating MTD devices:358machine # [ 0.666061] (0): "0.flash"359machine # [ 0.666066] (1): "0.flash"360machine # [ 0.666070] into device "0.flash"361machine # [ 0.941203] Freeing initrd memory: 25748K362machine # [ 0.947511] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.951671] thunder_xcv, ver 1.0364machine # [ 0.951717] thunder_bgx, ver 1.0365machine # [ 0.951739] nicpf, ver 1.0366machine # [ 0.952304] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.952311] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.952340] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.952350] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.952375] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.952381] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.952403] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.952409] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.952538] sky2: driver version 1.30375machine # [ 0.954132] usbcore: registered new interface driver usb-storage376machine # [ 0.954231] usbcore: registered new interface driver usbserial_generic377machine # [ 0.954245] usbserial: USB Serial support registered for generic378machine # [ 0.955065] ehci-pci 0000:00:0a.0: EHCI Host Controller379machine # [ 0.955097] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1380machine # [ 0.955334] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000381machine # [ 0.967257] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00382machine # [ 0.967670] hub 1-0:1.0: USB hub found383machine # [ 0.967692] hub 1-0:1.0: 6 ports detected384machine # [ 0.970446] hv_vmbus: registering driver hyperv_keyboard385machine # [ 0.972021] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 0.972053] rtc-pl031 9010000.pl031: setting system clock to 2026-09-10T02:43:12 UTC (1789008192)387machine # [ 0.972387] i2c_dev: i2c /dev entries driver388machine # [ 0.977461] sdhci: Secure Digital Host Controller Interface driver389machine # [ 0.977478] sdhci: Copyright(c) Pierre Ossman390machine # [ 0.977767] Synopsys Designware Multimedia Card Interface Driver391machine # [ 0.978137] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 0.982750] hid: raw HID events driver (C) Jiri Kosina393machine # [ 0.982994] usbcore: registered new interface driver usbhid394machine # [ 0.983002] usbhid: USB HID core driver395machine # [ 0.986000] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.988680] drop_monitor: Initializing network drop monitor service397machine # [ 0.988892] NET: Registered PF_INET6 protocol family398machine # [ 0.991085] Segment Routing with IPv6399machine # [ 0.991105] In-situ OAM (IOAM) with IPv6400machine # [ 0.991135] NET: Registered PF_PACKET protocol family401machine # [ 0.992828] 9pnet: Installing 9P2000 support402machine # [ 0.995766] Key type dns_resolver registered403machine # [ 1.002253] registered taskstats version 1404machine # [ 1.002973] Loading compiled-in X.509 certificates405machine # [ 1.011342] Demotion targets for Node 0: null406machine # [ 1.011467] Key type .fscrypt registered407machine # [ 1.011473] Key type fscrypt-provisioning registered408machine # [ 1.011574] ima: No TPM chip found, activating TPM-bypass!409machine # [ 1.011592] ima: Allocated hash algorithm: sha1410machine # [ 1.011617] ima: No architecture policies found411machine # [ 1.015988] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 1.035183] clk: Disabling unused clocks413machine # [ 1.035218] PM: genpd: Disabling unused power domains414machine # [ 1.039638] Freeing unused kernel memory: 4736K415machine # [ 1.039856] Run /init as init process416machine # [ 1.057203] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.214398] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.366666] 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/input1419machine # [ 1.392397] systemd[1]: systemd 262-2ff84cf running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)420machine # [ 1.392476] systemd[1]: Detected virtualization qemu.421machine # [ 1.392565] systemd[1]: Detected architecture arm64.422machine # [ 1.392591] systemd[1]: Running in initrd.423machine # [ 1.393584] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.393875] systemd[1]: Hostname set to <machine>.425machine # [ 1.458642] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0426machine # [ 1.581972] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.582957] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.640893] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.652428] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.653468] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.654550] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.655421] systemd[1]: Reached target Path Units.433machine # [ 1.656079] systemd[1]: Reached target Slice Units.434machine # [ 1.656713] systemd[1]: Reached target Swaps.435machine # [ 1.657277] systemd[1]: Reached target Timer Units.436machine # [ 1.658084] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.659167] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.660075] systemd[1]: Listening on Journal Sockets.439machine # [ 1.660869] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.661560] systemd[1]: Reached target Socket Units.441machine # [ 1.663745] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.673749] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.674923] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.687805] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.704465] systemd[1]: Starting Journal Service...446machine # [ 1.726796] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.727924] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.731208] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.744673] 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/input2450machine # [ 1.746567] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0451machine # [ 1.759245] systemd-journald[73]: Collecting audit messages is disabled.452machine # [ 1.767157] systemd[1]: Finished Create List of Static Device Nodes.453machine # [ 1.779738] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.454machine # [ 1.786863] systemd[1]: Finished Load Kernel Module 9pnet_virtio.455machine # [ 1.822502] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...456machine # [ 1.824676] systemd[1]: Mounted Kernel Configuration File System.457machine # [ 1.838967] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.855727] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.870546] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.460machine # [ 1.874752] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0461machine # [ 1.875022] [drm] features: -virgl +edid -resource_blob -host_visible462machine # [ 1.875035] [drm] features: -context_init463machine # [ 1.875778] [drm] number of scanouts: 1464machine # [ 1.875798] [drm] number of cap sets: 0465machine # [ 1.886729] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.890733] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.890774] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.922717] Console: switching to colour frame buffer device 160x50469machine # [ 1.930009] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device470machine # [ 1.937816] systemd[1]: Started Journal Service.471machine # [ 1.936736] systemd-modules-load[74]: Inserted module 'dm_mod'472machine # [ 1.938338] systemd-modules-load[74]: Module 'virtio_balloon' is built in473machine # [ 1.944159] systemd-modules-load[74]: Module 'virtio_console' is built in474machine # [ 1.948438] systemd-modules-load[74]: Inserted module 'virtio_gpu'475machine # [ 1.956431] systemd-modules-load[74]: Module 'virtio_rng' is built in476machine # [ 1.960403] systemd[1]: Finished Load Kernel Modules.477machine # [ 1.961455] systemd[1]: Starting Apply Kernel Variables...478machine # [ 1.965006] systemd[1]: Finished Create Static Device Nodes in /dev.479machine # [ 1.974062] systemd[1]: Reached target Preparation for Local File Systems.480machine # [ 1.984233] systemd[1]: Reached target Local File Systems.481machine # [ 1.986836] systemd[1]: Starting Create System Files and Directories...482machine # [ 2.008524] systemd[1]: Starting Rule-based Manager for Device Events and Files...483machine # [ 2.012928] systemd[1]: Finished Apply Kernel Variables.484machine # [ 2.033076] systemd[1]: Finished Create System Files and Directories.485machine # [ 2.076375] systemd-udevd[88]: Using default interface naming scheme 'v261'.486machine # [ 2.098914] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 2.176144] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.248496] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.252420] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.821606] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.822602] systemd[1]: Reached target System Initialization.492machine # [ 2.823438] systemd[1]: Reached target Basic System.493machine # [ 2.976959] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.494machine # [ 2.992952] (udev-worker)[102]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.495machine # [ 2.997726] (udev-worker)[102]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 3.059932] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 3.064162] systemd[1]: Reached target Initrd Root Device.498machine # [ 3.067861] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 3.123408] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 3.132343] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 3.138773] systemd[1]: Mounting /sysroot...502machine # [ 3.189680] EXT4-fs (vda): mounted filesystem b597e3e9-ef81-46d6-be01-b213d4719a22 r/w with ordered data mode. Quota mode: none.503machine # [ 3.182913] systemd[1]: Mounted /sysroot.504machine # [ 3.185071] systemd[1]: Reached target Initrd Root File System.505machine # [ 3.189155] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 3.220594] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 3.225465] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 3.231066] systemd[1]: Reloading...509machine # [ 3.458247] systemd[1]: Reloading finished in 230 ms.510machine # [ 3.495141] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.498373] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.511263] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.514531] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.523947] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.526629] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.548716] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.553256] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.726009] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.746257] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.751612] systemd[1]: Mounting /sysroot/run...521machine # [ 3.766476] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.798511] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.848414] systemd[1]: Mounted /sysroot/nix/.rw-store.524machine # [ 3.862031] systemd[1]: Mounted /sysroot/nix/.ro-store.525machine # [ 3.886380] systemd[1]: Mounted /sysroot/run.526machine # [ 3.889006] systemd[1]: Mounted /sysroot/tmp/shared.527machine # [ 3.899903] systemd[1]: Mounted /sysroot/tmp/xchg.528machine # [ 3.909541] systemd[1]: Starting rw-sysroot-nix-store.service...529machine # [ 3.941861] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.943457] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 4.540442] (udev-worker)[99]: mtd0ro: Failed to find and pin callout binary "/nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/lib/udev/mtd_probe": No such file or directory532machine # [ 4.545773] (udev-worker)[99]: mtd0ro: /nix/store/g748j53i4yimpg27kpkbav57qnhzxzm9-systemd-262-2ff84cf/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 directory533machine # [ 4.580106] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.534machine # [ 4.588207] systemd[1]: Stopped Virtual Console Setup.535machine # [ 4.589117] systemd[1]: Stopping Virtual Console Setup...536machine # [ 4.590648] systemd[1]: Starting Virtual Console Setup...537machine # [ 4.604980] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.538machine # [ 4.606658] systemd[1]: Stopped Virtual Console Setup.539machine # [ 4.609141] systemd[1]: Starting Virtual Console Setup...540machine # [ 4.635313] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.541machine # [ 4.639201] systemd[1]: Finished Virtual Console Setup.542machine # [ 4.725614] systemd[1]: Mounting /sysroot/nix/store...543machine # [ 4.779722] systemd[1]: Mounted /sysroot/nix/store.544machine # [ 4.782870] systemd[1]: Reached target Initrd File Systems.545machine # [ 4.787566] systemd[1]: Starting Find NixOS closure...546machine # [ 4.801012] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...547machine # [ 4.844162] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.548machine # [ 4.847851] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.549machine # [ 4.864603] systemd[1]: Finished Find NixOS closure.550machine # [ 4.867439] systemd[1]: Reached target Initrd Default Target.551machine # [ 4.869560] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...552machine # [ 4.906081] systemd[1]: initrd-cleanup.service: Deactivated successfully.553machine # [ 4.908206] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.554machine # [ 4.913100] systemd[1]: Stopped target Initrd Default Target.555machine # [ 4.914398] systemd[1]: Stopped target Basic System.556machine # [ 4.916307] systemd[1]: Stopped target Initrd Root Device.557machine # [ 4.919694] systemd[1]: Stopped target Path Units.558machine # [ 4.920772] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.559machine # [ 4.924302] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.560machine # [ 4.928214] systemd[1]: Stopped target Slice Units.561machine # [ 4.929131] systemd[1]: Stopped target Socket Units.562machine # [ 4.929995] systemd[1]: Stopped target System Initialization.563machine # [ 4.932162] systemd[1]: Stopped target Swaps.564machine # [ 4.933969] systemd[1]: Stopped target Timer Units.565machine # [ 4.939995] systemd[1]: dbus.socket: Deactivated successfully.566machine # [ 4.941022] systemd[1]: Closed D-Bus System Message Bus Socket.567machine # [ 4.941924] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.568machine # [ 4.943059] systemd[1]: Stopped Find NixOS closure.569machine # [ 4.943791] systemd[1]: Starting Load Kernel Module 9pnet_virtio...570machine # [ 4.959333] systemd[1]: Starting rw-sysroot-nix-store.service...571machine # [ 4.965648] systemd[1]: systemd-sysctl.service: Deactivated successfully.572machine # [ 4.976297] systemd[1]: Stopped Apply Kernel Variables.573machine # [ 4.978393] systemd[1]: systemd-modules-load.service: Deactivated successfully.574machine # [ 4.982412] systemd[1]: Stopped Load Kernel Modules.575machine # [ 4.984329] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.576machine # [ 4.986726] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.577machine # [ 4.989554] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.578machine # [ 4.991499] systemd[1]: Stopped Create System Files and Directories.579machine # [ 4.996387] systemd[1]: Stopped target Local File Systems.580machine # [ 4.997227] systemd[1]: Stopped target Preparation for Local File Systems.581machine # [ 4.998230] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.582machine # [ 4.999231] systemd[1]: Stopped Coldplug All udev Devices.583machine # [ 5.001211] systemd[1]: Stopping Rule-based Manager for Device Events and Files...584machine # [ 5.002440] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.585machine # [ 5.003503] systemd[1]: Stopped Virtual Console Setup.586machine # [ 5.004331] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.587machine # [ 5.005359] systemd[1]: Finished rw-sysroot-nix-store.service.588machine # [ 5.006209] systemd[1]: systemd-udevd.service: Deactivated successfully.589machine # [ 5.007145] systemd[1]: Stopped Rule-based Manager for Device Events and Files.590machine # [ 5.013125] systemd[1]: systemd-udevd.service: Consumed 1.603s CPU time over 2.972s wall clock time, 22M memory peak.591machine # [ 5.016215] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.592machine # [ 5.022115] systemd[1]: Finished Load Kernel Module 9pnet_virtio.593machine # [ 5.023215] systemd[1]: Starting Cleanup udev Database...594machine # [ 5.024154] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.595machine # [ 5.028135] systemd[1]: Stopped Create Static Device Nodes in /dev.596machine # [ 5.029076] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.597machine # [ 5.030235] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.598machine # [ 5.032103] systemd[1]: kmod-static-nodes.service: Deactivated successfully.599machine # [ 5.036353] systemd[1]: Stopped Create List of Static Device Nodes.600machine # [ 5.061871] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 5.064998] systemd[1]: Finished Cleanup udev Database.602machine # [ 5.067580] systemd[1]: Reached target Switch Root.603machine # [ 5.069179] systemd[1]: Starting NixOS Activation...604machine # [ 5.251149] initrd-nixos-activation-start[180]: booting system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test605machine # [ 5.319781] initrd-nixos-activation-start[180]: running activation script...606machine # [ 5.765180] initrd-nixos-activation-start[203]: setting up /etc...607machine # [ 6.049200] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 6.052265] systemd[1]: Finished NixOS Activation.609machine # [ 6.053842] systemd[1]: Starting Switch Root...610machine # [ 6.077443] systemd[1]: Switching root.611machine # [ 6.284769] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).612machine # [ 6.931109] systemd[1]: systemd 262-2ff84cf running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)613machine # [ 6.943196] systemd[1]: Detected virtualization qemu.614machine # [ 6.946282] systemd[1]: Detected architecture arm64.615machine # [ 6.949376] systemd[1]: Detected first boot.616machine # [ 6.958228] systemd[1]: Initializing machine ID from random generator.617machine # [ 7.158202] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 7.328460] systemd[1]: Applying preset policy.619machine # [ 7.827143] systemd[1]: Populated /etc with preset unit settings.620machine # [ 8.344761] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 8.346257] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 8.349712] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 8.353790] systemd[1]: Created slice Slice /system/getty.624machine # [ 8.356927] systemd[1]: Created slice User and Session Slice.625machine # [ 8.359084] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 8.361154] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 8.363161] systemd[1]: Expecting device /dev/hvc0...628machine # [ 8.363198] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 8.363236] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 8.363273] systemd[1]: Stopped target initrd-fs.target.631machine # [ 8.364137] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 8.364462] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 8.364732] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 8.365130] systemd[1]: Reached target Path Units.635machine # [ 8.365432] systemd[1]: Reached target Remote File Systems.636machine # [ 8.365689] systemd[1]: Reached target Slice Units.637machine # [ 8.365939] systemd[1]: Reached target Swaps.638machine # [ 8.376720] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 8.381834] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 8.385990] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 8.390175] systemd[1]: Listening on Factory Reset Management.642machine # [ 8.391587] systemd[1]: Listening on Hostname Service Socket.643machine # [ 8.398093] systemd[1]: Starting Journal Log Access Socket...644machine # [ 8.401501] systemd[1]: Listening on Journal Audit Socket.645machine # [ 8.406616] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 8.409859] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 8.412899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 8.415731] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 8.429417] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 8.432073] systemd[1]: Listening on udev Varlink Socket.651machine # [ 8.438221] systemd[1]: Mounting Huge Pages File System...652machine # [ 8.447016] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 8.459501] systemd[1]: Mounting Kernel Debug File System...654machine # [ 8.467174] systemd[1]: Mounting Kernel Trace File System...655machine # [ 8.486750] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 8.504371] systemd[1]: Starting Load Kernel Module 9pnet_virtio...657machine # [ 8.507424] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs658machine # [ 8.533473] systemd[1]: Mounting Kernel Configuration File System...659machine # [ 8.538387] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm660machine # [ 8.546266] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore661machine # [ 8.578216] systemd[1]: Starting Load Kernel Module fuse...662machine # [ 8.586591] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 8.616642] systemd[1]: Starting Journal Service...664machine # [ 8.636839] systemd[1]: Starting Load Kernel Modules...665machine # [ 8.669998] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...666machine # [ 8.712426] fuse: init (API version 7.45)667machine # [ 8.717225] systemd[1]: Starting Remount Root and Kernel File Systems...668machine # [ 8.719763] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os669machine # [ 8.751065] systemd-journald[275]: Collecting audit messages is enabled.670machine # [ 8.759170] systemd[1]: Starting Coldplug All udev Devices...671machine # [ 8.761839] systemd[1]: Queued start job for default target Multi-User System.672machine # [ 8.766949] systemd[1]: systemd-journald.service: Deactivated successfully.673machine # [ 8.775057] systemd-modules-load[276]: Module 'atkbd' is built in674machine # [ 8.776357] systemd-modules-load[276]: Module 'loop' is built in675machine # [ 8.791828] systemd[1]: Listening on Journal Log Access Socket.676machine # [ 8.798849] systemd[1]: Started Journal Service.677machine # [ 8.793607] systemd[1]: Mounted Huge Pages File System.678machine # [ 8.800620] systemd[1]: Mounted POSIX Message Queue File System.679machine # [ 8.801480] systemd[1]: Mounted Kernel Debug File System.680machine # [ 8.802249] systemd[1]: Mounted Kernel Trace File System.681machine # [ 8.807487] systemd[1]: Finished Create List of Static Device Nodes.682machine # [ 8.818044] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.683machine # [ 8.819067] systemd[1]: Finished Load Kernel Module 9pnet_virtio.684machine # [ 8.819921] systemd[1]: Mounted Kernel Configuration File System.685machine # [ 8.825611] systemd[1]: modprobe@fuse.service: Deactivated successfully.686machine # [ 8.842486] EXT4-fs (vda): re-mounted b597e3e9-ef81-46d6-be01-b213d4719a22.687machine # [ 8.836805] systemd[1]: Finished Load Kernel Module fuse.688machine # [ 8.839857] systemd[1]: Finished Load Kernel Modules.689machine # [ 8.844769] systemd[1]: Finished Remount Root and Kernel File Systems.690machine # [ 8.854896] systemd[1]: Listening on Disk Image Download Service Socket.691machine # [ 8.860611] systemd[1]: Mounting FUSE Control File System...692machine # [ 8.867841] systemd-oomd[278]: No swap; memory pressure usage will be degraded693machine # [ 8.876605] systemd[1]: Starting Firewall...694machine # [ 8.880146] systemd[1]: Starting Flush Journal to Persistent Storage...695machine # [ 8.881450] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore696machine # [ 8.906427] systemd[1]: Starting Load/Save OS Random Seed...697machine # [ 8.925964] systemd[1]: Starting Apply Kernel Variables...698machine # [ 8.944550] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...699machine # [ 8.949789] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os700machine # [ 8.960496] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.701machine # [ 9.027016] systemd-journald[275]: Received client request to flush runtime journal.702machine # [ 9.101316] systemd[1]: Finished Load/Save OS Random Seed.703machine # [ 9.108365] systemd[1]: Reached target First Boot Complete.704machine # [ 9.112602] systemd[1]: Mounted FUSE Control File System.705machine # [ 9.117775] systemd[1]: Finished Flush Journal to Persistent Storage.706machine # [ 9.119181] systemd[1]: Finished Apply Kernel Variables.707machine # [ 9.178894] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.708machine # [ 9.182381] systemd[1]: Starting Create Static Device Nodes in /dev...709machine # [ 9.289075] systemd[1]: Finished Create Static Device Nodes in /dev.710machine # [ 9.291415] systemd[1]: Reached target Preparation for Local File Systems.711machine # [ 9.300414] systemd[1]: Starting Rule-based Manager for Device Events and Files...712machine # [ 9.342424] systemd[1]: Mounting /run/wrappers...713machine # [ 9.417397] systemd[1]: Mounted /run/wrappers.714machine # [ 9.418368] systemd[1]: Reached target Local File Systems.715machine # [ 9.426077] systemd[1]: Listening on Boot Loader Control Service Socket.716machine # [ 9.433618] systemd[1]: Starting Create SUID/SGID Wrappers...717machine # [ 9.440717] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.718machine # [ 9.447429] systemd[1]: Starting Save Transient machine-id to Disk...719machine # [ 9.486359] systemd[1]: Starting Create System Files and Directories...720machine # [ 9.517234] systemd-udevd[318]: Using default interface naming scheme 'v261'.721machine # [ 9.597495] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.722machine # [ 9.613540] systemd[1]: Finished Save Transient machine-id to Disk.723machine # [ 9.703439] systemd[1]: Finished Create System Files and Directories.724machine # [ 9.717378] systemd[1]: Starting Rebuild Journal Catalog...725machine # [ 9.720630] systemd[1]: Starting Record System Boot/Shutdown in UTMP...726machine # [ 9.850174] systemd[1]: Started Rule-based Manager for Device Events and Files.727machine # [ 9.859619] systemd[1]: Finished Record System Boot/Shutdown in UTMP.728machine # [ 9.933428] systemd[1]: Finished Rebuild Journal Catalog.729machine # [ 9.945771] systemd[1]: Starting Update is Completed...730machine # [ 10.020605] systemd[1]: Finished Update is Completed.731machine # [ 10.317659] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.732machine # [ 10.319543] systemd[1]: Finished Create SUID/SGID Wrappers.733machine # [ 10.710447] systemd[1]: Finished Coldplug All udev Devices.734machine # [ 10.712710] systemd[1]: Reached target System Initialization.735machine # [ 10.716481] systemd[1]: Started Discard unused filesystem blocks once a week.736machine # [ 10.721409] systemd[1]: Started Daily Cleanup of Temporary Directories.737machine # [ 10.722972] systemd[1]: Reached target Timer Units.738machine # [ 10.729175] systemd[1]: Listening on D-Bus System Message Bus Socket.739machine # [ 10.733474] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.740machine # [ 10.742331] systemd[1]: Reached target Socket Units.741machine # [ 10.743098] systemd[1]: Reached target Basic System.742machine # [ 10.747903] systemd[1]: Starting Import lastlog data into lastlog2 database...743machine # [ 10.750562] systemd[1]: Starting Name Service Cache Daemon (nsncd)...744machine # [ 10.758319] systemd[1]: Starting Post-Boot Actions...745machine # [ 10.804302] systemd[1]: Started Reset console on configuration changes.746machine # [ 10.849214] systemd[1]: Starting resolvconf update...747machine # [ 10.918757] nsncd[455]: Sep 10 02:43:22.458 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"748machine # [ 10.933523] systemd[1]: Started Name Service Cache Daemon (nsncd).749machine # [ 10.938265] systemd[1]: Reached target Host and Network Name Lookups.750machine # [ 10.944506] systemd[1]: Reached target User and Group Name Lookups.751machine # [ 10.958988] systemd[1]: Starting D-Bus System Message Bus...752machine # [ 11.008352] systemd[1]: Starting User Login Management...753machine # [ 11.017320] systemd[1]: Finished Post-Boot Actions.754machine # [ 11.051666] systemd[1]: Finished Import lastlog data into lastlog2 database.755machine # [ 11.079883] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs756machine # [ 11.246126] dbus-broker-launch[480]: Looking up NSS user entry for 'systemd-timesync'...757machine # [ 11.257297] dbus-broker-launch[480]: NSS returned no entry for 'systemd-timesync'758machine # [ 11.260490] dbus-broker-launch[480]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"759machine # [ 11.284304] systemd-logind[483]: New seat seat0.760machine # [ 11.292595] systemd[1]: Started User Login Management.761machine # [ 11.307529] systemd[1]: Starting linger-users.service...762machine # [ 11.323696] systemd[1]: Started D-Bus System Message Bus.763machine # [ 11.329647] systemd[1]: Finished Firewall.764machine # [ 11.360678] systemd[1]: Stopped target Host and Network Name Lookups.765machine # [ 11.368480] systemd[1]: Stopping Host and Network Name Lookups...766machine # [ 11.370897] systemd[1]: Stopped target User and Group Name Lookups.767machine # [ 11.378951] systemd[1]: Stopping User and Group Name Lookups...768machine # [ 11.385676] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...769machine # [ 11.390177] systemd[1]: nscd.service: Deactivated successfully.770machine # [ 11.397607] dbus-broker-launch[480]: Ready771machine # [ 11.398357] systemd[1]: Stopped Name Service Cache Daemon (nsncd).772machine # [ 11.416616] systemd[1]: Starting Name Service Cache Daemon (nsncd)...773machine # [ 11.495222] systemd[1]: Created slice Slice /user/1001.774machine # [ 11.506358] systemd[1]: Starting User Runtime Directory /run/user/1001...775machine # [ 11.540728] nsncd[550]: Sep 10 02:43:23.079 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"776machine # [ 11.554408] systemd[1]: Started Name Service Cache Daemon (nsncd).777machine # [ 11.560750] systemd[1]: linger-users.service: Deactivated successfully.778machine # [ 11.566278] systemd[1]: Finished linger-users.service.779machine # [ 11.594884] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.780machine # [ 11.602036] systemd[1]: Reached target Host and Network Name Lookups.781machine # [ 11.607128] systemd[1]: Reached target User and Group Name Lookups.782machine # [ 11.622285] systemd[1]: Finished resolvconf update.783machine # [ 11.626143] systemd[1]: Reached target Preparation for Network.784machine # [ 11.636400] systemd[1]: Starting DHCP Client...785machine # [ 11.645399] systemd[1]: Starting Extra networking commands....786machine # [ 11.672455] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.787machine # [ 11.692265] systemd[1]: Started backdoor.service.788machine # [ 11.767013] systemd[1]: Finished User Runtime Directory /run/user/1001.789machine # [ 11.804743] systemd[1]: Starting User Manager for UID 1001...790machine # [ 11.965672] (systemd)[583]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)791machine # [ 12.012115] systemd-logind[483]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.792machine # [ 12.083379] (udev-worker)[372]: Network interface NamePolicy= disabled on kernel command line.793machine # [ 12.125563] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy794machine # connecting to host...795machine: Guest shell says: b'Spawning backdoor root shell...\n'796machine: connected to guest root shell797machine: (connecting took 12.69 seconds)798machine: (finished: waiting for the VM to finish booting, in 13.24 seconds)799machine # [ 12.467631] (udev-worker)[367]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.800machine # [ 12.521210] (udev-worker)[367]: Network interface NamePolicy= disabled on kernel command line.801machine # [ 12.543476] mousedev: PS/2 mouse device common for all mice802machine # [ 12.558788] systemd-logind[483]: Watching system buttons on /dev/input/event0 (gpio-keys)803machine # [ 12.575554] systemd[583]: Queued start job for default target Main User Target.804machine # [ 12.606600] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy805machine # [ 12.628642] systemd[583]: Created slice User Application Slice.806machine # [ 12.644925] systemd[583]: Started Daily Cleanup of User's Temporary Directories.807machine # [ 12.646083] systemd[583]: Reached target Paths.808machine # [ 12.646732] systemd[583]: Reached target Timers.809machine # [ 12.647379] systemd[583]: Starting D-Bus User Message Bus Socket...810machine # [ 12.684495] systemd[583]: Starting Create User Files and Directories...811machine # [ 12.685905] systemd[1]: Condition check resulted in Virtio network device being skipped.812machine # [ 12.687077] systemd[1]: Starting Address configuration of eth1...813machine # [ 12.687946] systemd[583]: Finished Create User Files and Directories.814machine # [ 12.708259] systemd[583]: Listening on D-Bus User Message Bus Socket.815machine # [ 12.714331] systemd[583]: Reached target Sockets.816machine # [ 12.715682] systemd[583]: Reached target Basic System.817machine # [ 12.718188] systemd[1]: Started User Manager for UID 1001.818machine # [ 12.719090] systemd[583]: Starting Run user-specific NixOS activation...819machine # [ 12.720706] systemd-logind[483]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)820machine # [ 12.722839] systemd[583]: Finished Run user-specific NixOS activation.821machine # [ 12.728909] systemd[583]: Reached target Main User Target.822machine # [ 12.732807] systemd[583]: Startup finished in 618ms.823machine # [ 12.766026] dhcpcd[589]: dhcpcd-10.3.2 starting824machine # [ 12.784184] dhcpcd[658]: dev: loaded udev825machine # [ 12.836417] 8021q: 802.1Q VLAN Support v1.8826machine # [ 12.836807] 8021q: adding VLAN 0 to HW filter on device eth1827machine # [ 12.841990] network-addresses-eth1-start[615]: adding address 192.168.1.1/24... done828machine # [ 12.861909] systemd[1]: Finished Extra networking commands..829machine # [ 12.868839] systemd[1]: Reached target Network.830machine # [ 12.875475] systemd[1]: Starting Permit User Sessions...831machine # [ 12.895523] network-addresses-eth1-start[615]: adding address 2001:db8:1::1/64... done832machine # [ 12.933492] cfg80211: Loading compiled-in X.509 certificates for regulatory database833machine # [ 12.954217] systemd[1]: Finished Address configuration of eth1.834machine # [ 12.975548] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'835machine # [ 12.976077] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'836machine # [ 12.982475] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2837machine # [ 12.982831] cfg80211: failed to load regulatory.db838machine # [ 13.019091] systemd[1]: Finished Permit User Sessions.839machine # [ 13.034596] systemd[1]: Started Getty on tty1.840machine # [ 13.035302] systemd[1]: Reached target Login Prompts.841machine # [ 13.416996] 8021q: adding VLAN 0 to HW filter on device eth0842machine # [ 13.413041] dhcpcd[658]: eth0: waiting for carrier843machine # [ 13.423361] dhcpcd[658]: eth0: carrier acquired844machine # [ 13.499953] dhcpcd[658]: DUID 00:01:00:01:32:34:d5:cd:52:54:00:12:34:56845machine # [ 13.501113] dhcpcd[658]: eth0: IAID 00:12:34:56846machine # [ 13.501790] dhcpcd[658]: eth0: adding address fe80::5054:ff:fe12:3456847machine # [ 13.809023] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3848machine # [ 14.158224] systemd[1]: Starting Virtual Console Setup...849machine # [ 14.172113] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.850machine # [ 14.234275] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.851machine # [ 14.235429] systemd[1]: Stopped Virtual Console Setup.852machine # [ 14.239859] systemd[1]: Starting Virtual Console Setup...853machine # [ 14.255231] systemd-logind[483]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)854machine # [ 14.717255] systemd-vconsole-setup[714]: Configuration of first virtual console was skipped, ignoring remaining ones.855machine # [ 14.723548] systemd[1]: Finished Virtual Console Setup.856machine # [ 15.195414] dhcpcd[658]: eth0: soliciting a DHCP lease857machine # [ 15.199028] dhcpcd[658]: eth0: offered 10.0.2.15 from 10.0.2.2858machine # [ 15.208644] dhcpcd[658]: eth0: probing address 10.0.2.15/24859machine # [ 15.937523] dhcpcd[658]: eth0: soliciting an IPv6 router860machine # [ 15.942119] dhcpcd[658]: eth0: Router Advertisement from fe80::2861machine # [ 15.945513] dhcpcd[658]: eth0: adding address fec0::5054:ff:fe12:3456/64862machine # [ 15.948759] dhcpcd[658]: eth0: adding route to fec0::/64863machine # [ 15.951268] dhcpcd[658]: eth0: adding default route via fe80::2864machine # [ 20.084290] dhcpcd[658]: eth0: leased 10.0.2.15 for 86400 seconds865machine # [ 20.088059] dhcpcd[658]: eth0: adding route to 10.0.2.0/24866machine # [ 20.091552] dhcpcd[658]: eth0: adding default route via 10.0.2.2867machine # [ 20.258622] systemd[1]: Started DHCP Client.868machine # [ 20.260736] systemd[1]: Reached target Multi-User System.869machine # [ 20.261596] systemd[1]: Startup finished in 1.030s (kernel) + 5.392s (initrd) + 13.837s (userspace) = 20.260s.870machine: (finished: waiting for unit multi-user.target, in 21.87 seconds)871machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test872machine # Checking switch inhibitors... done873machine # [ 21.576443] nixos[836]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test874machine # [ 21.579164] systemd[1]: Stopped target Local File Systems.875machine # activating the configuration...876machine # [ 21.581529] systemd[1]: Stopped target Remote File Systems.877machine # setting up /etc...878machine # [ 22.048799] systemd[1]: Reload requested from client PID 836 ('.switch-to-conf') (unit backdoor.service)...879machine # [ 22.050393] systemd[1]: Reloading...880machine # [ 22.637709] systemd[1]: Reloading finished in 583 ms.881machine # reloading user units for usertest...882machine # [ 22.692771] systemd[583]: Created slice User Core Session Slice.883machine # [ 22.696799] systemd[583]: Starting D-Bus User Message Bus...884machine # [ 22.765365] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.import1.service'885machine # [ 22.768927] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.machine1.service'886machine # [ 22.772329] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.portable1.service'887machine # [ 22.775886] dbus-broker-launch[906]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'888machine # [ 22.779934] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored889machine # [ 22.783695] dbus-broker-launch[906]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored890machine # [ 22.786152] systemd[583]: Started D-Bus User Message Bus.891machine # [ 22.792654] dbus-broker-launch[906]: Ready892machine # [ 22.898358] systemd[583]: Reexecution requested from client PID 905 ('switch-to-confi')...893machine # [ 22.902305] systemd[583]: Reexecuting.894machine # [ 22.958199] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # [ 23.176179] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy896machine # [ 23.183964] systemd[583]: Reload requested from client PID 905 ('switch-to-confi')...897machine # [ 23.186255] systemd[583]: Reloading...898machine # [ 23.383037] systemd[583]: Reloading finished in 195 ms.899machine # [ 23.396187] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # restarting the following user units: nixos-activation.service901machine # [ 23.407122] systemd[583]: Stopped Run user-specific NixOS activation.902machine # [ 23.409435] systemd[583]: Stopping Run user-specific NixOS activation...903machine # [ 23.412360] systemd[583]: Starting Run user-specific NixOS activation...904machine # [ 23.456156] systemd[583]: Finished Run user-specific NixOS activation.905machine # restarting sysinit-reactivation.target906machine # [ 23.473491] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....907machine # [ 23.590572] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..908machine # [ 23.591864] systemd[1]: Reached target Reactivate sysinit units.909machine # [ 23.596066] systemd[1]: Reached target Local File Systems.910machine # [ 23.599841] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore911machine # [ 23.605194] systemd[1]: Starting Create SUID/SGID Wrappers...912machine # [ 23.606152] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.913machine # [ 23.607399] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67914machine # [ 23.610134] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore915machine # [ 23.623492] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os916machine # [ 23.626816] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os917machine # [ 23.629998] systemd[1]: Reached target Remote File Systems.918machine # [ 23.630831] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os919machine # [ 23.632240] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki920machine # [ 23.921718] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.921machine # [ 23.926295] systemd[1]: Finished Create SUID/SGID Wrappers.922machine # [ 23.929587] systemd[1]: Starting linger-users.service...923machine # [ 23.993095] systemd[1]: linger-users.service: Deactivated successfully.924machine # [ 23.995791] systemd[1]: Finished linger-users.service.925machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service926machine # [ 24.259842] nixos[836]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test927machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test, in 3.26 seconds)928machine: must succeed: rm /etc/NIXOS929machine: (finished: must succeed: rm /etc/NIXOS, in 0.06 seconds)930machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test931machine # this should succeed (config: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test, action: test)932machine # Checking switch inhibitors... done933machine # [ 24.986913] nixos[984]: switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test934machine # [ 24.989736] systemd[1]: Stopped target Remote File Systems.935machine # activating the configuration...936machine # [ 24.992366] systemd[1]: Stopped target Local File Systems.937machine # removing user ‘usertest’938machine # setting up /etc...939machine # [ 25.501307] systemd[1]: Reload requested from client PID 984 ('.switch-to-conf') (unit backdoor.service)...940machine # [ 25.503155] systemd[1]: Reloading...941machine # [ 26.174034] systemd[1]: Reloading finished in 667 ms.942machine # reloading user units for usertest...943machine # [ 26.315898] systemd[583]: Reexecution requested from client PID 1055 ('switch-to-confi')...944machine # [ 26.319701] systemd[583]: Reexecuting.945machine # [ 26.386098] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 26.623071] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy947machine # [ 26.629638] systemd[583]: Reload requested from client PID 1055 ('switch-to-confi')...948machine # [ 26.632155] systemd[583]: Reloading...949machine # [ 26.833708] systemd[583]: Reloading finished in 200 ms.950machine # [ 26.849694] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # restarting the following user units: nixos-activation.service952machine # [ 26.859631] systemd[583]: Stopped Run user-specific NixOS activation.953machine # [ 26.862011] systemd[583]: Stopping Run user-specific NixOS activation...954machine # [ 26.864956] systemd[583]: Starting Run user-specific NixOS activation...955machine # [ 26.916283] systemd[583]: Finished Run user-specific NixOS activation.956machine # restarting sysinit-reactivation.target957machine # [ 26.921231] systemd[1]: Stopped target Reactivate sysinit units.958machine # reloading the following units: dbus-broker.service959machine # [ 26.924954] systemd[1]: Stopping Reactivate sysinit units...960machine # [ 26.925881] systemd[1]: Reached target Reactivate sysinit units.961machine # [ 26.928195] systemd[1]: Reloading D-Bus System Message Bus...962machine # [ 26.929652] dbus-broker-launch[480]: Caught SIGHUP, trigger reload.963machine # [ 26.951007] dbus-broker-launch[480]: Looking up NSS user entry for 'systemd-timesync'...964machine # [ 26.953282] dbus-broker-launch[480]: NSS returned no entry for 'systemd-timesync'965machine # [ 26.954594] dbus-broker-launch[480]: Invalid user-name in /nix/store/p8lzwq9djsvv4pad8njyi3hknk69n4y5-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"966machine # [ 26.993859] systemd[1]: Reloaded D-Bus System Message Bus.967machine # restarting the following units: network-addresses-eth1.service968machine # [ 27.001327] systemd[1]: Stopping Address configuration of eth1...969machine # [ 27.056584] network-addresses-eth1-pre-stop[1068]: deleting address 192.168.1.1/24... done970machine # [ 27.068956] network-addresses-eth1-pre-stop[1068]: deleting address 2001:db8:1::1/64... done971machine # [ 27.079488] systemd[1]: network-addresses-eth1.service: Deactivated successfully.972machine # [ 27.081722] systemd[1]: Stopped Address configuration of eth1.973machine # [ 27.087221] systemd[1]: Starting Address configuration of eth1...974machine # [ 27.167553] network-addresses-eth1-start[1073]: adding address 192.168.1.2/24... done975machine # [ 27.182349] network-addresses-eth1-start[1073]: adding address 2001:db8:1::2/64... done976machine # [ 27.201100] systemd[1]: Finished Address configuration of eth1.977machine # [ 27.206954] systemd[1]: Reached target Remote File Systems.978machine # [ 27.213372] systemd[1]: Starting linger-users.service...979machine # [ 27.219259] systemd[1]: Reached target Local File Systems.980machine # [ 27.221395] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore981machine # [ 27.226813] systemd[1]: Starting Create SUID/SGID Wrappers...982machine # [ 27.227826] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.983machine # [ 27.238555] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67984machine # [ 27.251939] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore985machine # [ 27.254502] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os986machine # [ 27.255864] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os987machine # [ 27.257372] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os988machine # [ 27.258693] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki989machine # [ 27.311315] linger-users-start[1088]: id: ‘usertest’: no such user990machine # [ 27.315512] linger-users-start[1084]: Removing linger for missing user usertest991machine # [ 27.330107] systemd[1]: linger-users.service: Deactivated successfully.992machine # [ 27.333157] systemd[1]: Finished linger-users.service.993machine # [ 27.702944] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.994machine # [ 27.706959] systemd[1]: Finished Create SUID/SGID Wrappers.995machine # [ 27.964223] nixos[984]: finished switching to system configuration /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test996machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration test, in 3.64 seconds)997subtest: pre-switch checks998machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration check999machine # this should succeed (config: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test, action: check)1000machine # Checking switch inhibitors... done1001machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/bin/switch-to-configuration check, in 0.11 seconds)1002machine: must fail: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&11003machine: (finished: must fail: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/specialisation/failingCheck/bin/switch-to-configuration check 2>&1, in 0.06 seconds)1004machine: must fail: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&11005machine: (finished: must fail: /nix/store/jvmh9fi9cb76jsqxgb0fng2iga3i36rz-nixos-system-other-test/specialisation/failingMidCheck/bin/switch-to-configuration check 2>&1, in 0.06 seconds)1006(finished: subtest: pre-switch checks, in 0.23 seconds)1007subtest: switch inhibitors1008machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11009machine # [ 28.793583] nixos[1173]: switching to system configuration /nix/store/zjyyd90f259vvwjp5ldl0rz7z8xj7p2h-nixos-system-machine-test1010machine # [ 28.797131] systemd[1]: Stopped target Local File Systems.1011machine # [ 28.798826] systemd[1]: Stopped target Remote File Systems.1012machine # [ 29.265063] systemd[1]: Reload requested from client PID 1173 ('.switch-to-conf') (unit backdoor.service)...1013machine # [ 29.267128] systemd[1]: Reloading...1014machine # [ 29.890659] systemd[1]: Reloading finished in 620 ms.1015machine # [ 30.054980] systemd[583]: Reexecution requested from client PID 1243 ('switch-to-confi')...1016machine # [ 30.057181] systemd[583]: Reexecuting.1017machine # [ 30.122679] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1018machine # [ 30.319070] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1019machine # [ 30.326397] systemd[583]: Reload requested from client PID 1243 ('switch-to-confi')...1020machine # [ 30.328729] systemd[583]: Reloading...1021machine # [ 30.519057] systemd[583]: Reloading finished in 188 ms.1022machine # [ 30.532219] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1023machine # [ 30.542143] systemd[583]: Stopped Run user-specific NixOS activation.1024machine # [ 30.543253] systemd[583]: Stopping Run user-specific NixOS activation...1025machine # [ 30.547401] systemd[583]: Starting Run user-specific NixOS activation...1026machine # [ 30.596128] systemd[583]: Finished Run user-specific NixOS activation.1027machine # [ 30.600632] systemd[1]: Stopped target Reactivate sysinit units.1028machine # [ 30.605110] systemd[1]: Stopping Reactivate sysinit units...1029machine # [ 30.606481] systemd[1]: Reached target Reactivate sysinit units.1030machine # [ 30.607595] systemd[1]: Reloading D-Bus System Message Bus...1031machine # [ 30.609459] dbus-broker-launch[480]: Caught SIGHUP, trigger reload.1032machine # [ 30.621674] dbus-broker-launch[480]: Looking up NSS user entry for 'systemd-timesync'...1033machine # [ 30.623997] dbus-broker-launch[480]: NSS returned no entry for 'systemd-timesync'1034machine # [ 30.625474] dbus-broker-launch[480]: Invalid user-name in /nix/store/xnm1mc93sr4ddyc8s8wj9j9mpjkna1hq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1035machine # [ 30.657101] systemd[1]: Reloaded D-Bus System Message Bus.1036machine # [ 30.664102] systemd[1]: Stopping Address configuration of eth1...1037machine # [ 30.716995] network-addresses-eth1-pre-stop[1256]: deleting address 192.168.1.2/24... done1038machine # [ 30.729167] network-addresses-eth1-pre-stop[1256]: deleting address 2001:db8:1::2/64... done1039machine # [ 30.739440] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1040machine # [ 30.741962] systemd[1]: Stopped Address configuration of eth1.1041machine # [ 30.747378] systemd[1]: Starting Address configuration of eth1...1042machine # [ 30.820452] network-addresses-eth1-start[1261]: adding address 192.168.1.1/24... done1043machine # [ 30.832881] network-addresses-eth1-start[1261]: adding address 2001:db8:1::1/64... done1044machine # [ 30.849299] systemd[1]: Finished Address configuration of eth1.1045machine # [ 30.854019] systemd[1]: Reached target Local File Systems.1046machine # [ 30.858775] systemd[1]: Reached target Remote File Systems.1047machine # [ 30.862241] systemd[1]: Starting linger-users.service...1048machine # [ 30.873881] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1049machine # [ 30.887111] systemd[1]: Starting Create SUID/SGID Wrappers...1050machine # [ 30.892716] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1051machine # [ 30.894100] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671052machine # [ 30.903510] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1053machine # [ 30.907088] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 30.908777] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1055machine # [ 30.910335] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1056machine # [ 30.912417] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1057machine # [ 30.951629] systemd[1]: linger-users.service: Deactivated successfully.1058machine # [ 30.953530] systemd[1]: Finished linger-users.service.1059machine # [ 31.247465] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1060machine # [ 31.250943] systemd[1]: Finished Create SUID/SGID Wrappers.1061machine # [ 31.507850] nixos[1173]: finished switching to system configuration /nix/store/zjyyd90f259vvwjp5ldl0rz7z8xj7p2h-nixos-system-machine-test1062machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 3.31 seconds)1063machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&11064machine # [ 32.071503] nixos[1330]: switching to system configuration /nix/store/r8ajrprkkfbjl96h6mb2hbiy5kijq7ng-nixos-system-machine-test1065machine # [ 32.075986] systemd[1]: Stopped target Remote File Systems.1066machine # [ 32.077321] systemd[1]: Stopped target Local File Systems.1067machine # [ 32.533660] systemd[1]: Reload requested from client PID 1330 ('.switch-to-conf') (unit backdoor.service)...1068machine # [ 32.535808] systemd[1]: Reloading...1069machine # [ 33.158332] systemd[1]: Reloading finished in 618 ms.1070machine # [ 33.341562] systemd[583]: Reexecution requested from client PID 1400 ('switch-to-confi')...1071machine # [ 33.345990] systemd[583]: Reexecuting.1072machine # [ 33.399000] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1073machine # [ 33.605062] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1074machine # [ 33.611719] systemd[583]: Reload requested from client PID 1400 ('switch-to-confi')...1075machine # [ 33.614866] systemd[583]: Reloading...1076machine # [ 33.818480] systemd[583]: Reloading finished in 203 ms.1077machine # [ 33.833720] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1078machine # [ 33.843166] systemd[583]: Stopped Run user-specific NixOS activation.1079machine # [ 33.844337] systemd[583]: Stopping Run user-specific NixOS activation...1080machine # [ 33.847583] systemd[583]: Starting Run user-specific NixOS activation...1081machine # [ 33.897956] systemd[583]: Finished Run user-specific NixOS activation.1082machine # [ 33.902591] systemd[1]: Stopped target Reactivate sysinit units.1083machine # [ 33.905334] systemd[1]: Stopping Reactivate sysinit units...1084machine # [ 33.906615] systemd[1]: Reached target Reactivate sysinit units.1085machine # [ 33.910874] systemd[1]: Reached target Remote File Systems.1086machine # [ 33.913631] systemd[1]: Starting linger-users.service...1087machine # [ 33.924838] systemd[1]: Reached target Local File Systems.1088machine # [ 33.925983] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1089machine # [ 33.935460] systemd[1]: Starting Create SUID/SGID Wrappers...1090machine # [ 33.937410] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1091machine # [ 33.944590] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671092machine # [ 33.950529] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1093machine # [ 33.953427] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1094machine # [ 33.955470] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1095machine # [ 33.957422] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1096machine # [ 33.959121] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1097machine # [ 34.018551] systemd[1]: linger-users.service: Deactivated successfully.1098machine # [ 34.022220] systemd[1]: Finished linger-users.service.1099machine # [ 34.306399] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1100machine # [ 34.309889] systemd[1]: Finished Create SUID/SGID Wrappers.1101machine # [ 34.566886] nixos[1330]: finished switching to system configuration /nix/store/r8ajrprkkfbjl96h6mb2hbiy5kijq7ng-nixos-system-machine-test1102machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors/bin/switch-to-configuration switch 2>&1, in 3.06 seconds)1103machine: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&11104machine: (finished: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration switch 2>&1, in 0.10 seconds)1105machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&11106machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration boot 2>&1, in 0.07 seconds)1107machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&11108machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_changed/bin/switch-to-configuration dry-activate 2>&1, in 0.70 seconds)1109machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&11110machine # [ 36.009127] nixos[1496]: switching to system configuration /nix/store/47kw9llw49mg7h8c8pk4bvkq4hfp6xxf-nixos-system-machine-test1111machine # [ 36.011814] systemd[1]: Stopped target Local File Systems.1112machine # [ 36.014142] systemd[1]: Stopped target Remote File Systems.1113machine # [ 36.513660] systemd[1]: Reload requested from client PID 1496 ('.switch-to-conf') (unit backdoor.service)...1114machine # [ 36.515535] systemd[1]: Reloading...1115machine # [ 37.117285] systemd[1]: Reloading finished in 599 ms.1116machine # [ 37.268474] systemd[583]: Reexecution requested from client PID 1567 ('switch-to-confi')...1117machine # [ 37.269928] systemd[583]: Reexecuting.1118machine # [ 37.325862] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1119machine # [ 37.522518] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1120machine # [ 37.529785] systemd[583]: Reload requested from client PID 1567 ('switch-to-confi')...1121machine # [ 37.532172] systemd[583]: Reloading...1122machine # [ 37.724415] systemd[583]: Reloading finished in 191 ms.1123machine # [ 37.736244] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1124machine # [ 37.747364] systemd[583]: Stopped Run user-specific NixOS activation.1125machine # [ 37.750040] systemd[583]: Stopping Run user-specific NixOS activation...1126machine # [ 37.753119] systemd[583]: Starting Run user-specific NixOS activation...1127machine # [ 37.803732] systemd[583]: Finished Run user-specific NixOS activation.1128machine # [ 37.808125] systemd[1]: Stopped target Reactivate sysinit units.1129machine # [ 37.810225] systemd[1]: Stopping Reactivate sysinit units...1130machine # [ 37.811876] systemd[1]: Reached target Reactivate sysinit units.1131machine # [ 37.815963] systemd[1]: Reached target Local File Systems.1132machine # [ 37.820509] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1133machine # [ 37.825941] systemd[1]: Starting Create SUID/SGID Wrappers...1134machine # [ 37.826849] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1135machine # [ 37.830445] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671136machine # [ 37.838812] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1137machine # [ 37.846144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1138machine # [ 37.847616] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1139machine # [ 37.851812] systemd[1]: Reached target Remote File Systems.1140machine # [ 37.854162] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1141machine # [ 37.857513] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1142machine # [ 38.202290] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1143machine # [ 38.206950] systemd[1]: Finished Create SUID/SGID Wrappers.1144machine # [ 38.210050] systemd[1]: Starting linger-users.service...1145machine # [ 38.276802] systemd[1]: linger-users.service: Deactivated successfully.1146machine # [ 38.279255] systemd[1]: Finished linger-users.service.1147machine # [ 38.539577] nixos[1496]: finished switching to system configuration /nix/store/47kw9llw49mg7h8c8pk4bvkq4hfp6xxf-nixos-system-machine-test1148machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/inhibitors_new/bin/switch-to-configuration switch 2>&1, in 3.11 seconds)1149machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&11150machine # [ 39.149777] nixos[1638]: switching to system configuration /nix/store/zjyyd90f259vvwjp5ldl0rz7z8xj7p2h-nixos-system-machine-test1151machine # [ 39.152957] systemd[1]: Stopped target Remote File Systems.1152machine # [ 39.155591] systemd[1]: Stopped target Local File Systems.1153machine # [ 39.601606] systemd[1]: Reload requested from client PID 1638 ('.switch-to-conf') (unit backdoor.service)...1154machine # [ 39.603532] systemd[1]: Reloading...1155machine # [ 40.214185] systemd[1]: Reloading finished in 608 ms.1156machine # [ 40.394015] systemd[583]: Reexecution requested from client PID 1708 ('switch-to-confi')...1157machine # [ 40.395494] systemd[583]: Reexecuting.1158machine # [ 40.449700] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 40.641715] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1160machine # [ 40.649354] systemd[583]: Reload requested from client PID 1708 ('switch-to-confi')...1161machine # [ 40.652005] systemd[583]: Reloading...1162machine # [ 40.845053] systemd[583]: Reloading finished in 191 ms.1163machine # [ 40.860245] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1164machine # [ 40.872115] systemd[583]: Stopped Run user-specific NixOS activation.1165machine # [ 40.873194] systemd[583]: Stopping Run user-specific NixOS activation...1166machine # [ 40.877704] systemd[583]: Starting Run user-specific NixOS activation...1167machine # [ 40.929453] systemd[583]: Finished Run user-specific NixOS activation.1168machine # [ 40.934622] systemd[1]: Stopped target Reactivate sysinit units.1169machine # [ 40.938142] systemd[1]: Stopping Reactivate sysinit units...1170machine # [ 40.939105] systemd[1]: Reached target Reactivate sysinit units.1171machine # [ 40.939978] systemd[1]: Reached target Remote File Systems.1172machine # [ 40.944568] systemd[1]: Reached target Local File Systems.1173machine # [ 40.945558] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1174machine # [ 40.953184] systemd[1]: Starting Create SUID/SGID Wrappers...1175machine # [ 40.956263] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1176machine # [ 40.957624] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671177machine # [ 40.959796] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1178machine # [ 40.973810] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1179machine # [ 40.975239] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1180machine # [ 40.977749] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 40.979118] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1182machine # [ 41.318494] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1183machine # [ 41.323786] systemd[1]: Finished Create SUID/SGID Wrappers.1184machine # [ 41.327140] systemd[1]: Starting linger-users.service...1185machine # [ 41.390414] systemd[1]: linger-users.service: Deactivated successfully.1186machine # [ 41.393221] systemd[1]: Finished linger-users.service.1187machine # [ 41.651632] nixos[1638]: finished switching to system configuration /nix/store/zjyyd90f259vvwjp5ldl0rz7z8xj7p2h-nixos-system-machine-test1188machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/no_inhibitors/bin/switch-to-configuration switch 2>&1, in 3.11 seconds)1189(finished: subtest: switch inhibitors, in 13.45 seconds)1190subtest: actions1191machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&11192machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration boot 2>&1, in 0.09 seconds)1193machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration switch 2>&11194machine # [ 42.323721] nixos[1787]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1195machine # [ 42.327360] systemd[1]: Stopped target Local File Systems.1196machine # [ 42.328691] systemd[1]: Stopped target Remote File Systems.1197machine # [ 42.793494] systemd[1]: Reload requested from client PID 1787 ('.switch-to-conf') (unit backdoor.service)...1198machine # [ 42.795701] systemd[1]: Reloading...1199machine # [ 43.457998] systemd[1]: Reloading finished in 658 ms.1200machine # [ 43.641105] systemd[583]: Reexecution requested from client PID 1857 ('switch-to-confi')...1201machine # [ 43.645666] systemd[583]: Reexecuting.1202machine # [ 43.715210] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1203machine # [ 43.925305] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1204machine # [ 43.932932] systemd[583]: Reload requested from client PID 1857 ('switch-to-confi')...1205machine # [ 43.935452] systemd[583]: Reloading...1206machine # [ 44.142866] systemd[583]: Reloading finished in 205 ms.1207machine # [ 44.156178] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1208machine # [ 44.166810] systemd[583]: Stopped Run user-specific NixOS activation.1209machine # [ 44.169296] systemd[583]: Stopping Run user-specific NixOS activation...1210machine # [ 44.172354] systemd[583]: Starting Run user-specific NixOS activation...1211machine # [ 44.221569] systemd[583]: Finished Run user-specific NixOS activation.1212machine # [ 44.226073] systemd[1]: Stopped target Reactivate sysinit units.1213machine # [ 44.228612] systemd[1]: Stopping Reactivate sysinit units...1214machine # [ 44.229565] systemd[1]: Reached target Reactivate sysinit units.1215machine # [ 44.235054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1216machine # [ 44.238332] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1217machine # [ 44.240967] systemd[1]: Reached target Remote File Systems.1218machine # [ 44.243100] systemd[1]: Reached target Local File Systems.1219machine # [ 44.246708] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1220machine # [ 44.254187] systemd[1]: Starting Create SUID/SGID Wrappers...1221machine # [ 44.255165] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1222machine # [ 44.257808] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671223machine # [ 44.262017] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1224machine # [ 44.264147] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1225machine # [ 44.265861] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1226machine # [ 44.605137] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1227machine # [ 44.608708] systemd[1]: Finished Create SUID/SGID Wrappers.1228machine # [ 44.612935] systemd[1]: Starting linger-users.service...1229machine # [ 44.681219] systemd[1]: linger-users.service: Deactivated successfully.1230machine # [ 44.684133] systemd[1]: Finished linger-users.service.1231machine # [ 44.943013] nixos[1787]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1232machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration switch 2>&1, in 3.20 seconds)1233machine: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&11234machine: (finished: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration broken-action 2>&1, in 0.06 seconds)1235machine: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration 2>&11236machine: (finished: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration 2>&1, in 0.04 seconds)1237(finished: subtest: actions, in 3.40 seconds)1238subtest: init interface version1239machine: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&11240machine: (finished: must fail: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/brokenInitInterface/bin/switch-to-configuration test 2>&1, in 0.07 seconds)1241(finished: subtest: init interface version, in 0.07 seconds)1242subtest: systemd restarts1243machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11244machine # [ 45.707406] nixos[1944]: switching to system configuration /nix/store/q47b27pfxsmkpkakin9zfdx8abmcikfp-nixos-system-machine-test1245machine # [ 45.710616] systemd[1]: Stopped target Local File Systems.1246machine # [ 45.713334] systemd[1]: Stopped target Remote File Systems.1247machine # [ 46.265784] systemd[1]: Reexecution requested from client PID 1944 ('.switch-to-conf') (unit backdoor.service)...1248machine # [ 46.271654] systemd[1]: Reexecuting.1249machine # [ 46.500587] systemd[1]: systemd 262-2ff84cf running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1250machine # [ 46.514769] systemd[1]: Detected virtualization qemu.1251machine # [ 46.518161] systemd[1]: Detected architecture arm64.1252machine # [ 46.684493] systemd[1]: bpf-restrict-fs: LSM BPF program attached1253machine # [ 47.308578] systemd[1]: Reload requested from client PID 1944 ('.switch-to-conf') (unit backdoor.service)...1254machine # [ 47.310502] systemd[1]: Reloading...1255machine # [ 47.918322] systemd[1]: Reloading finished in 605 ms.1256machine # [ 48.096438] systemd[583]: Reexecution requested from client PID 2047 ('switch-to-confi')...1257machine # [ 48.100090] systemd[583]: Reexecuting.1258machine # [ 48.150171] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1259machine # [ 48.351531] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1260machine # [ 48.358699] systemd[583]: Reload requested from client PID 2047 ('switch-to-confi')...1261machine # [ 48.362610] systemd[583]: Reloading...1262machine # [ 48.552473] systemd[583]: Reloading finished in 190 ms.1263machine # [ 48.568185] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1264machine # [ 48.578233] systemd[583]: Stopped Run user-specific NixOS activation.1265machine # [ 48.580846] systemd[583]: Stopping Run user-specific NixOS activation...1266machine # [ 48.584134] systemd[583]: Starting Run user-specific NixOS activation...1267machine # [ 48.631164] systemd[583]: Finished Run user-specific NixOS activation.1268machine # [ 48.635946] systemd[1]: Stopped target Reactivate sysinit units.1269machine # [ 48.638943] systemd[1]: Stopping Reactivate sysinit units...1270machine # [ 48.639826] systemd[1]: Reached target Reactivate sysinit units.1271machine # [ 48.642077] systemd[1]: Reached target Local File Systems.1272machine # [ 48.646653] systemd[1]: Reached target Remote File Systems.1273machine # [ 48.648940] systemd[1]: Starting linger-users.service...1274machine # [ 48.660976] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1275machine # [ 48.666633] systemd[1]: Starting Create SUID/SGID Wrappers...1276machine # [ 48.667632] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1277machine # [ 48.673869] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671278machine # [ 48.680229] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1279machine # [ 48.681847] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1280machine # [ 48.683103] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1281machine # [ 48.688589] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1282machine # [ 48.690520] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1283machine # [ 48.753274] systemd[1]: linger-users.service: Deactivated successfully.1284machine # [ 48.755040] systemd[1]: Finished linger-users.service.1285machine # [ 49.028594] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1286machine # [ 49.031144] systemd[1]: Finished Create SUID/SGID Wrappers.1287machine # [ 49.290042] nixos[1944]: finished switching to system configuration /nix/store/q47b27pfxsmkpkakin9zfdx8abmcikfp-nixos-system-machine-test1288machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 4.17 seconds)1289(finished: subtest: systemd restarts, in 4.17 seconds)1290subtest: continuing from an aborted switch1291machine: must succeed: echo dbus-broker.service > /run/nixos/start-list1292machine: (finished: must succeed: echo dbus-broker.service > /run/nixos/start-list, in 0.04 seconds)1293machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&11294machine # [ 49.890312] nixos[2122]: switching to system configuration /nix/store/q47b27pfxsmkpkakin9zfdx8abmcikfp-nixos-system-machine-test1295machine # [ 49.893264] systemd[1]: Stopped target Remote File Systems.1296machine # [ 49.895745] systemd[1]: Stopped target Local File Systems.1297machine # [ 50.336881] systemd[1]: Reload requested from client PID 2122 ('.switch-to-conf') (unit backdoor.service)...1298machine # [ 50.338871] systemd[1]: Reloading...1299machine # [ 50.914806] systemd[1]: Reloading finished in 573 ms.1300machine # [ 51.063384] systemd[583]: Reexecution requested from client PID 2191 ('switch-to-confi')...1301machine # [ 51.065845] systemd[583]: Reexecuting.1302machine # [ 51.130280] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1303machine # [ 51.311605] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1304machine # [ 51.318657] systemd[583]: Reload requested from client PID 2191 ('switch-to-confi')...1305machine # [ 51.320772] systemd[583]: Reloading...1306machine # [ 51.517161] systemd[583]: Reloading finished in 195 ms.1307machine # [ 51.532190] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1308machine # [ 51.542471] systemd[583]: Stopped Run user-specific NixOS activation.1309machine # [ 51.545151] systemd[583]: Stopping Run user-specific NixOS activation...1310machine # [ 51.548309] systemd[583]: Starting Run user-specific NixOS activation...1311machine # [ 51.597794] systemd[583]: Finished Run user-specific NixOS activation.1312machine # [ 51.602489] systemd[1]: Stopped target Reactivate sysinit units.1313machine # [ 51.605490] systemd[1]: Stopping Reactivate sysinit units...1314machine # [ 51.608981] systemd[1]: Reached target Reactivate sysinit units.1315machine # [ 51.609960] systemd[1]: Reached target Remote File Systems.1316machine # [ 51.615122] systemd[1]: Starting linger-users.service...1317machine # [ 51.630297] systemd[1]: Reached target Local File Systems.1318machine # [ 51.631252] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1319machine # [ 51.644474] systemd[1]: Starting Create SUID/SGID Wrappers...1320machine # [ 51.645476] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1321machine # [ 51.646726] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671322machine # [ 51.659356] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1323machine # [ 51.663118] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1324machine # [ 51.666532] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1325machine # [ 51.669265] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1326machine # [ 51.671185] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1327machine # [ 51.731310] systemd[1]: linger-users.service: Deactivated successfully.1328machine # [ 51.734191] systemd[1]: Finished linger-users.service.1329machine # [ 52.037165] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1330machine # [ 52.041103] systemd[1]: Finished Create SUID/SGID Wrappers.1331machine # [ 52.298255] nixos[2122]: finished switching to system configuration /nix/store/q47b27pfxsmkpkakin9zfdx8abmcikfp-nixos-system-machine-test1332machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/modifiedSystemConf/bin/switch-to-configuration test 2>&1, in 2.97 seconds)1333(finished: subtest: continuing from an aborted switch, in 3.01 seconds)1334subtest: aborts on already locked lock file1335??? Warning (UserWarning): execute(): passing a bare int/float as a duration is deprecated. Use datetime.timedelta instead.1336 File "/nix/store/sqpbfna43hn5hr5aj1l73jgim4hg0gwn-nixos-test-driver-1.1/lib/python3.14/site-packages/test_driver/duration.py", line 391337machine # Could not acquire lock1338(finished: subtest: aborts on already locked lock file, in 0.10 seconds)1339subtest: fstab mounts1340machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&11341machine # [ 52.953584] nixos[2267]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1342machine # [ 52.956376] systemd[1]: Stopped target Local File Systems.1343machine # [ 52.958998] systemd[1]: Stopped target Remote File Systems.1344machine # [ 53.444586] systemd[1]: Reexecution requested from client PID 2267 ('.switch-to-conf') (unit backdoor.service)...1345machine # [ 53.449712] systemd[1]: Reexecuting.1346machine # [ 53.662424] systemd[1]: systemd 262-2ff84cf running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -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)1347machine # [ 53.676204] systemd[1]: Detected virtualization qemu.1348machine # [ 53.677777] systemd[1]: Detected architecture arm64.1349machine # [ 53.790660] systemd[1]: bpf-restrict-fs: LSM BPF program attached1350machine # [ 54.411776] systemd[1]: Reload requested from client PID 2267 ('.switch-to-conf') (unit backdoor.service)...1351machine # [ 54.414074] systemd[1]: Reloading...1352machine # [ 55.055403] systemd[1]: Reloading finished in 638 ms.1353machine # [ 55.213870] systemd[583]: Reexecution requested from client PID 2370 ('switch-to-confi')...1354machine # [ 55.215362] systemd[583]: Reexecuting.1355machine # [ 55.279113] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1356machine # [ 55.483894] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1357machine # [ 55.490662] systemd[583]: Reload requested from client PID 2370 ('switch-to-confi')...1358machine # [ 55.493393] systemd[583]: Reloading...1359machine # [ 55.693291] systemd[583]: Reloading finished in 198 ms.1360machine # [ 55.708253] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1361machine # [ 55.719678] systemd[583]: Stopped Run user-specific NixOS activation.1362machine # [ 55.722455] systemd[583]: Stopping Run user-specific NixOS activation...1363machine # [ 55.725452] systemd[583]: Starting Run user-specific NixOS activation...1364machine # [ 55.778603] systemd[583]: Finished Run user-specific NixOS activation.1365machine # [ 55.783200] systemd[1]: Stopped target Reactivate sysinit units.1366machine # [ 55.785484] systemd[1]: Stopping Reactivate sysinit units...1367machine # [ 55.786411] systemd[1]: Reached target Reactivate sysinit units.1368machine # [ 55.791369] systemd[1]: Reached target Remote File Systems.1369machine # [ 55.797528] systemd[1]: Starting linger-users.service...1370machine # [ 55.803478] systemd[1]: Reached target Local File Systems.1371machine # [ 55.812354] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1372machine # [ 55.814068] systemd[1]: Starting Create SUID/SGID Wrappers...1373machine # [ 55.814940] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1374machine # [ 55.828278] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671375machine # [ 55.830588] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1376machine # [ 55.833722] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1377machine # [ 55.835041] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1378machine # [ 55.838203] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1379machine # [ 55.840366] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1380machine # [ 55.907812] systemd[1]: linger-users.service: Deactivated successfully.1381machine # [ 55.911444] systemd[1]: Finished linger-users.service.1382machine # [ 56.200837] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1383machine # [ 56.203576] systemd[1]: Finished Create SUID/SGID Wrappers.1384machine # [ 56.462258] nixos[2267]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1385machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 4.06 seconds)1386machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11387machine # [ 57.097357] nixos[2441]: switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1388machine # [ 57.101108] systemd[1]: Stopped target Local File Systems.1389machine # [ 57.102696] systemd[1]: Stopped target Remote File Systems.1390machine # [ 57.615745] systemd[1]: Reload requested from client PID 2441 ('.switch-to-conf') (unit backdoor.service)...1391machine # [ 57.618046] systemd[1]: Reloading...1392machine # [ 58.175362] systemd[1]: Reloading finished in 555 ms.1393machine # [ 58.334644] systemd[583]: Reexecution requested from client PID 2510 ('switch-to-confi')...1394machine # [ 58.339547] systemd[583]: Reexecuting.1395machine # [ 58.398515] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 58.586745] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 58.593947] systemd[583]: Reload requested from client PID 2510 ('switch-to-confi')...1398machine # [ 58.596672] systemd[583]: Reloading...1399machine # [ 58.793009] systemd[583]: Reloading finished in 195 ms.1400machine # [ 58.808211] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1401machine # [ 58.820879] systemd[583]: Stopped Run user-specific NixOS activation.1402machine # [ 58.823494] systemd[583]: Stopping Run user-specific NixOS activation...1403machine # [ 58.827209] systemd[583]: Starting Run user-specific NixOS activation...1404machine # [ 58.877637] systemd[583]: Finished Run user-specific NixOS activation.1405machine # [ 58.882740] systemd[1]: Stopped target Reactivate sysinit units.1406machine # [ 58.885017] systemd[1]: Stopping Reactivate sysinit units...1407machine # [ 58.887213] systemd[1]: Reached target Reactivate sysinit units.1408machine # [ 58.896473] systemd[1]: Mounting /test...1409machine # [ 58.897291] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1410machine # [ 58.902715] systemd[1]: Starting Create SUID/SGID Wrappers...1411machine # [ 58.907019] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671412machine # [ 58.920372] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1413machine # [ 58.922135] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 58.923403] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1415machine # [ 58.929930] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1416machine # [ 58.932674] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1417machine # [ 58.937091] systemd[1]: Reached target Remote File Systems.1418machine # [ 58.972762] systemd[1]: Mounted /test.1419machine # [ 58.978827] systemd[1]: Reached target Local File Systems.1420machine # [ 58.981516] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1421machine # [ 59.308598] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1422machine # [ 59.312559] systemd[1]: Finished Create SUID/SGID Wrappers.1423machine # [ 59.316939] systemd[1]: Starting linger-users.service...1424machine # [ 59.383417] systemd[1]: linger-users.service: Deactivated successfully.1425machine # [ 59.385867] systemd[1]: Finished linger-users.service.1426machine # [ 59.646158] nixos[2441]: finished switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1427machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1428machine: must succeed: systemctl stop test.mount1429machine # [ 59.714466] systemd[1]: Stopped target Local File Systems.1430machine # [ 59.721615] systemd[1]: Unmounting /test...1431machine # [ 59.779395] systemd[1]: test.mount: Deactivated successfully.1432machine # [ 59.784367] systemd[1]: Unmounted /test.1433machine: (finished: must succeed: systemctl stop test.mount, in 0.14 seconds)1434machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11435machine # [ 60.352394] nixos[2589]: switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1436machine # [ 60.355356] systemd[1]: Stopped target Remote File Systems.1437machine # [ 60.832707] systemd[1]: Reload requested from client PID 2589 ('.switch-to-conf') (unit backdoor.service)...1438machine # [ 60.834959] systemd[1]: Reloading...1439machine # [ 61.475588] systemd[1]: Reloading finished in 638 ms.1440machine # [ 61.627581] systemd[583]: Reexecution requested from client PID 2658 ('switch-to-confi')...1441machine # [ 61.631250] systemd[583]: Reexecuting.1442machine # [ 61.678160] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1443machine # [ 61.882200] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1444machine # [ 61.889429] systemd[583]: Reload requested from client PID 2658 ('switch-to-confi')...1445machine # [ 61.892144] systemd[583]: Reloading...1446machine # [ 62.094192] systemd[583]: Reloading finished in 200 ms.1447machine # [ 62.108206] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1448machine # [ 62.120107] systemd[583]: Stopped Run user-specific NixOS activation.1449machine # [ 62.121250] systemd[583]: Stopping Run user-specific NixOS activation...1450machine # [ 62.124595] systemd[583]: Starting Run user-specific NixOS activation...1451machine # [ 62.171944] systemd[583]: Finished Run user-specific NixOS activation.1452machine # [ 62.176791] systemd[1]: Stopped target Reactivate sysinit units.1453machine # [ 62.177930] systemd[1]: Stopping Reactivate sysinit units...1454machine # [ 62.178796] systemd[1]: Reached target Reactivate sysinit units.1455machine # [ 62.188564] systemd[1]: Mounting /test...1456machine # [ 62.189400] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1457machine # [ 62.196859] systemd[1]: Starting Create SUID/SGID Wrappers...1458machine # [ 62.197831] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671459machine # [ 62.207561] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1460machine # [ 62.220412] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 62.221782] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1462machine # [ 62.223049] systemd[1]: Reached target Remote File Systems.1463machine # [ 62.226383] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1464machine # [ 62.228666] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1465machine # [ 62.264420] systemd[1]: Mounted /test.1466machine # [ 62.265231] systemd[1]: Reached target Local File Systems.1467machine # [ 62.266077] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1468machine # [ 62.606322] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1469machine # [ 62.610095] systemd[1]: Finished Create SUID/SGID Wrappers.1470machine # [ 62.615042] systemd[1]: Starting linger-users.service...1471machine # [ 62.680761] systemd[1]: linger-users.service: Deactivated successfully.1472machine # [ 62.683728] systemd[1]: Finished linger-users.service.1473machine # [ 62.942006] nixos[2589]: finished switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1474machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 3.15 seconds)1475machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&11476machine # [ 63.516487] nixos[2731]: switching to system configuration /nix/store/nz36g92ip5bkh4ynyv4m73wy8hxw36k9-nixos-system-machine-test1477machine # [ 63.519017] systemd[1]: Stopped target Remote File Systems.1478machine # [ 63.521346] systemd[1]: Stopped target Local File Systems.1479machine # [ 64.020674] systemd[1]: Reload requested from client PID 2731 ('.switch-to-conf') (unit backdoor.service)...1480machine # [ 64.022684] systemd[1]: Reloading...1481machine # [ 64.586554] systemd[1]: Reloading finished in 561 ms.1482machine # [ 64.744711] systemd[583]: Reexecution requested from client PID 2800 ('switch-to-confi')...1483machine # [ 64.748902] systemd[583]: Reexecuting.1484machine # [ 64.810933] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 65.015750] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1486machine # [ 65.023818] systemd[583]: Reload requested from client PID 2800 ('switch-to-confi')...1487machine # [ 65.028196] systemd[583]: Reloading...1488machine # [ 65.219970] systemd[583]: Reloading finished in 192 ms.1489machine # [ 65.232186] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1490machine # [ 65.241833] systemd[583]: Stopped Run user-specific NixOS activation.1491machine # [ 65.244350] systemd[583]: Stopping Run user-specific NixOS activation...1492machine # [ 65.247399] systemd[583]: Starting Run user-specific NixOS activation...1493machine # [ 65.295926] systemd[583]: Finished Run user-specific NixOS activation.1494machine # [ 65.300829] systemd[1]: Stopped target Reactivate sysinit units.1495machine # [ 65.301801] systemd[1]: Stopping Reactivate sysinit units...1496machine # [ 65.302593] systemd[1]: Reached target Reactivate sysinit units.1497machine # [ 65.307274] systemd[1]: Reloading /test...1498machine # [ 65.347205] systemd[1]: Reloaded /test.1499machine # [ 65.350883] systemd[1]: Reached target Remote File Systems.1500machine # [ 65.353941] systemd[1]: Reached target Local File Systems.1501machine # [ 65.356943] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1502machine # [ 65.362285] systemd[1]: Starting Create SUID/SGID Wrappers...1503machine # [ 65.363205] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1504machine # [ 65.366400] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671505machine # [ 65.376229] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1506machine # [ 65.377966] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 65.379310] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 65.388515] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1509machine # [ 65.391168] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1510machine # [ 65.724992] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1511machine # [ 65.728697] systemd[1]: Finished Create SUID/SGID Wrappers.1512machine # [ 65.732949] systemd[1]: Starting linger-users.service...1513machine # [ 65.804232] systemd[1]: linger-users.service: Deactivated successfully.1514machine # [ 65.806573] systemd[1]: Finished linger-users.service.1515machine # [ 66.066531] nixos[2731]: finished switching to system configuration /nix/store/nz36g92ip5bkh4ynyv4m73wy8hxw36k9-nixos-system-machine-test1516machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMountOptsModified/bin/switch-to-configuration test 2>&1, in 3.13 seconds)1517machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&11518machine # [ 66.668242] nixos[2873]: switching to system configuration /nix/store/zfn20yrd4234anh8jkqx9hhzmygl0awp-nixos-system-machine-test1519machine # [ 66.672327] systemd[1]: Stopped target Local File Systems.1520machine # [ 66.673580] systemd[1]: Stopped target Remote File Systems.1521machine # [ 67.199667] systemd[1]: Reload requested from client PID 2873 ('.switch-to-conf') (unit backdoor.service)...1522machine # [ 67.201970] systemd[1]: Reloading...1523machine # [ 67.814269] systemd[1]: Reloading finished in 609 ms.1524machine # [ 67.967888] systemd[583]: Reexecution requested from client PID 2942 ('switch-to-confi')...1525machine # [ 67.970507] systemd[583]: Reexecuting.1526machine # [ 68.027202] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1527machine # [ 68.223182] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1528machine # [ 68.230103] systemd[583]: Reload requested from client PID 2942 ('switch-to-confi')...1529machine # [ 68.232602] systemd[583]: Reloading...1530machine # [ 68.425237] systemd[583]: Reloading finished in 191 ms.1531machine # [ 68.440198] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1532machine # [ 68.449801] systemd[583]: Stopped Run user-specific NixOS activation.1533machine # [ 68.452230] systemd[583]: Stopping Run user-specific NixOS activation...1534machine # [ 68.455205] systemd[583]: Starting Run user-specific NixOS activation...1535machine # [ 68.503440] systemd[583]: Finished Run user-specific NixOS activation.1536machine # [ 68.507960] systemd[1]: Stopped target Reactivate sysinit units.1537machine # [ 68.509431] systemd[1]: Stopping Reactivate sysinit units...1538machine # [ 68.510238] systemd[1]: Reached target Reactivate sysinit units.1539machine # [ 68.514727] systemd[1]: Unmounting /test...1540machine # [ 68.559516] systemd[1]: test.mount: Deactivated successfully.1541machine # [ 68.561134] systemd[1]: Unmounted /test.1542machine # [ 68.566602] systemd[1]: Mounting /test...1543machine # [ 68.608781] systemd[1]: Mounted /test.1544machine # [ 68.612869] systemd[1]: Reached target Remote File Systems.1545machine # [ 68.615492] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1546machine # [ 68.618760] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1547machine # [ 68.626102] systemd[1]: Starting linger-users.service...1548machine # [ 68.630170] systemd[1]: Reached target Local File Systems.1549machine # [ 68.639278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1550machine # [ 68.644628] systemd[1]: Starting Create SUID/SGID Wrappers...1551machine # [ 68.645559] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1552machine # [ 68.653025] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671553machine # [ 68.659341] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1554machine # [ 68.662967] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 68.665006] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 68.728849] systemd[1]: linger-users.service: Deactivated successfully.1557machine # [ 68.730056] systemd[1]: Finished linger-users.service.1558machine # [ 69.024207] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1559machine # [ 69.027448] systemd[1]: Finished Create SUID/SGID Wrappers.1560machine # [ 69.285881] nixos[2873]: finished switching to system configuration /nix/store/zfn20yrd4234anh8jkqx9hhzmygl0awp-nixos-system-machine-test1561machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMountDevModified/bin/switch-to-configuration test 2>&1, in 3.22 seconds)1562machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&11563machine # [ 69.887357] nixos[3017]: switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1564machine # [ 69.890281] systemd[1]: Stopped target Local File Systems.1565machine # [ 69.892816] systemd[1]: Stopped target Remote File Systems.1566machine # [ 70.359645] systemd[1]: Reload requested from client PID 3017 ('.switch-to-conf') (unit backdoor.service)...1567machine # [ 70.361977] systemd[1]: Reloading...1568machine # [ 70.957079] systemd[1]: Reloading finished in 593 ms.1569machine # [ 71.105573] systemd[583]: Reexecution requested from client PID 3086 ('switch-to-confi')...1570machine # [ 71.106954] systemd[583]: Reexecuting.1571machine # [ 71.178725] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1572machine # [ 71.379789] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1573machine # [ 71.386831] systemd[583]: Reload requested from client PID 3086 ('switch-to-confi')...1574machine # [ 71.391084] systemd[583]: Reloading...1575machine # [ 71.588928] systemd[583]: Reloading finished in 198 ms.1576machine # [ 71.604210] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1577machine # [ 71.616124] systemd[583]: Stopped Run user-specific NixOS activation.1578machine # [ 71.617191] systemd[583]: Stopping Run user-specific NixOS activation...1579machine # [ 71.620402] systemd[583]: Starting Run user-specific NixOS activation...1580machine # [ 71.669084] systemd[583]: Finished Run user-specific NixOS activation.1581machine # [ 71.674156] systemd[1]: Stopped target Reactivate sysinit units.1582machine # [ 71.677430] systemd[1]: Stopping Reactivate sysinit units...1583machine # [ 71.678547] systemd[1]: Reached target Reactivate sysinit units.1584machine # [ 71.681216] systemd[1]: Unmounting /test...1585machine # [ 71.725813] systemd[1]: test.mount: Deactivated successfully.1586machine # [ 71.728341] systemd[1]: Unmounted /test.1587machine # [ 71.733943] systemd[1]: Mounting /test...1588machine # [ 71.775655] systemd[1]: Mounted /test.1589machine # [ 71.783281] systemd[1]: Reached target Remote File Systems.1590machine # [ 71.788736] systemd[1]: Starting linger-users.service...1591machine # [ 71.792282] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1592machine # [ 71.793726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1593machine # [ 71.802071] systemd[1]: Reached target Local File Systems.1594machine # [ 71.808140] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1595machine # [ 71.813689] systemd[1]: Starting Create SUID/SGID Wrappers...1596machine # [ 71.814792] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1597machine # [ 71.822361] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671598machine # [ 71.828977] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1599machine # [ 71.831038] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1600machine # [ 71.834168] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1601machine # [ 71.899655] systemd[1]: linger-users.service: Deactivated successfully.1602machine # [ 71.902527] systemd[1]: Finished linger-users.service.1603machine # [ 72.185739] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1604machine # [ 72.189014] systemd[1]: Finished Create SUID/SGID Wrappers.1605machine # [ 72.447781] nixos[3017]: finished switching to system configuration /nix/store/jy3b5n57b1r7kdn5pcw3djh99r40hzlc-nixos-system-machine-test1606machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/addedMount/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1607machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&11608machine # [ 73.021728] nixos[3161]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1609machine # [ 73.024877] systemd[1]: Stopped target Remote File Systems.1610machine # [ 73.028507] systemd[1]: Stopped target Local File Systems.1611machine # [ 73.031472] systemd[1]: Unmounting /test...1612machine # [ 73.072978] systemd[1]: test.mount: Deactivated successfully.1613machine # [ 73.076695] systemd[1]: Unmounted /test.1614machine # [ 73.546284] systemd[1]: Reload requested from client PID 3161 ('.switch-to-conf') (unit backdoor.service)...1615machine # [ 73.548546] systemd[1]: Reloading...1616machine # [ 74.157247] systemd[1]: Reloading finished in 606 ms.1617machine # [ 74.302786] systemd[583]: Reexecution requested from client PID 3232 ('switch-to-confi')...1618machine # [ 74.305098] systemd[583]: Reexecuting.1619machine # [ 74.371517] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1620machine # [ 74.572372] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1621machine # [ 74.578865] systemd[583]: Reload requested from client PID 3232 ('switch-to-confi')...1622machine # [ 74.581287] systemd[583]: Reloading...1623machine # [ 74.768493] systemd[583]: Reloading finished in 185 ms.1624machine # [ 74.780191] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1625machine # [ 74.790995] systemd[583]: Stopped Run user-specific NixOS activation.1626machine # [ 74.792272] systemd[583]: Stopping Run user-specific NixOS activation...1627machine # [ 74.795125] systemd[583]: Starting Run user-specific NixOS activation...1628machine # [ 74.843674] systemd[583]: Finished Run user-specific NixOS activation.1629machine # [ 74.847748] systemd[1]: Stopped target Reactivate sysinit units.1630machine # [ 74.849617] systemd[1]: Stopping Reactivate sysinit units...1631machine # [ 74.850475] systemd[1]: Reached target Reactivate sysinit units.1632machine # [ 74.853561] systemd[1]: Reached target Local File Systems.1633machine # [ 74.856617] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1634machine # [ 74.861829] systemd[1]: Starting Create SUID/SGID Wrappers...1635machine # [ 74.862854] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1636machine # [ 74.866298] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671637machine # [ 74.876738] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1638machine # [ 74.878458] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1639machine # [ 74.879731] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1640machine # [ 74.886732] systemd[1]: Reached target Remote File Systems.1641machine # [ 74.888700] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1642machine # [ 74.890085] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1643machine # [ 75.255508] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1644machine # [ 75.258163] systemd[1]: Finished Create SUID/SGID Wrappers.1645machine # [ 75.264655] systemd[1]: Starting linger-users.service...1646machine # [ 75.334941] systemd[1]: linger-users.service: Deactivated successfully.1647machine # [ 75.338037] systemd[1]: Finished linger-users.service.1648machine # [ 75.596319] nixos[3161]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1649machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.15 seconds)1650machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&11651machine # [ 76.168681] nixos[3303]: switching to system configuration /nix/store/a4dpa8dp4lk7anq64iykh3ls4rccpcmr-nixos-system-machine-test1652machine # [ 76.171183] systemd[1]: Stopped target Remote File Systems.1653machine # [ 76.173699] systemd[1]: Stopped target Local File Systems.1654machine # [ 76.675629] systemd[1]: Reload requested from client PID 3303 ('.switch-to-conf') (unit backdoor.service)...1655machine # [ 76.677903] systemd[1]: Reloading...1656machine # [ 77.272985] systemd[1]: Reloading finished in 593 ms.1657machine # [ 77.428689] systemd[583]: Reexecution requested from client PID 3372 ('switch-to-confi')...1658machine # [ 77.433186] systemd[583]: Reexecuting.1659machine # [ 77.486011] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1660machine # [ 77.679204] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1661machine # [ 77.686693] systemd[583]: Reload requested from client PID 3372 ('switch-to-confi')...1662machine # [ 77.689305] systemd[583]: Reloading...1663machine # [ 77.879736] systemd[583]: Reloading finished in 189 ms.1664machine # [ 77.896174] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1665machine # [ 77.905577] systemd[583]: Stopped Run user-specific NixOS activation.1666machine # [ 77.907900] systemd[583]: Stopping Run user-specific NixOS activation...1667machine # [ 77.910999] systemd[583]: Starting Run user-specific NixOS activation...1668machine # [ 77.959008] systemd[583]: Finished Run user-specific NixOS activation.1669machine # [ 77.963145] systemd[1]: Stopped target Reactivate sysinit units.1670machine # [ 77.965446] systemd[1]: Stopping Reactivate sysinit units...1671machine # [ 77.968776] systemd[1]: Reached target Reactivate sysinit units.1672machine # [ 77.969765] systemd[1]: Reached target Remote File Systems.1673machine # [ 77.973646] systemd[1]: Starting linger-users.service...1674machine # [ 77.976502] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1675machine # [ 77.980649] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1676machine # [ 77.982078] systemd[1]: Reached target Local File Systems.1677machine # [ 77.982902] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1678machine # [ 77.989820] systemd[1]: Starting Create SUID/SGID Wrappers...1679machine # [ 77.996516] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1680machine # [ 78.004503] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671681machine # [ 78.006833] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1682machine # [ 78.013699] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1683machine # [ 78.015539] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1684machine # [ 78.079365] systemd[1]: linger-users.service: Deactivated successfully.1685machine # [ 78.082566] systemd[1]: Finished linger-users.service.1686machine # [ 78.369942] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1687machine # [ 78.373484] systemd[1]: Finished Create SUID/SGID Wrappers.1688machine # [ 78.632902] nixos[3303]: finished switching to system configuration /nix/store/a4dpa8dp4lk7anq64iykh3ls4rccpcmr-nixos-system-machine-test1689machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/storeMountModified/bin/switch-to-configuration test 2>&1, in 3.03 seconds)1690machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&11691machine # [ 79.236927] nixos[3443]: switching to system configuration /nix/store/b7kf0g268j4hff8a7aidm37hrarcb5y7-nixos-system-machine-test1692machine # [ 79.240289] systemd[1]: Stopped target Remote File Systems.1693machine # [ 79.243762] systemd[1]: Stopped target Local File Systems.1694machine # [ 79.808770] systemd[1]: Reload requested from client PID 3443 ('.switch-to-conf') (unit backdoor.service)...1695machine # [ 79.810971] systemd[1]: Reloading...1696machine # [ 80.443337] systemd[1]: Reloading finished in 629 ms.1697machine # [ 80.594661] systemd[583]: Reexecution requested from client PID 3512 ('switch-to-confi')...1698machine # [ 80.596816] systemd[583]: Reexecuting.1699machine # [ 80.672872] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1700machine # [ 80.875586] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1701machine # [ 80.882471] systemd[583]: Reload requested from client PID 3512 ('switch-to-confi')...1702machine # [ 80.885095] systemd[583]: Reloading...1703machine # [ 81.087016] systemd[583]: Reloading finished in 200 ms.1704machine # [ 81.100217] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1705machine # [ 81.110824] systemd[583]: Stopped Run user-specific NixOS activation.1706machine # [ 81.113667] systemd[583]: Stopping Run user-specific NixOS activation...1707machine # [ 81.117500] systemd[583]: Starting Run user-specific NixOS activation...1708machine # [ 81.169912] systemd[583]: Finished Run user-specific NixOS activation.1709machine # [ 81.175777] systemd[1]: Stopped target Reactivate sysinit units.1710machine # [ 81.178893] systemd[1]: Stopping Reactivate sysinit units...1711machine # [ 81.179872] systemd[1]: Reached target Reactivate sysinit units.1712machine # [ 81.185256] systemd[1]: Reached target Remote File Systems.1713machine # [ 81.186254] systemd[1]: Set up automount testauto.automount.1714machine # [ 81.187104] systemd[1]: Reached target Local File Systems.1715machine # [ 81.190192] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1716machine # [ 81.193936] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1717machine # [ 81.199343] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1718machine # [ 81.207922] systemd[1]: Starting Create SUID/SGID Wrappers...1719machine # [ 81.211115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1720machine # [ 81.217985] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671721machine # [ 81.224151] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1722machine # [ 81.225877] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1723machine # [ 81.227212] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1724machine # [ 81.584551] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1725machine # [ 81.588758] systemd[1]: Finished Create SUID/SGID Wrappers.1726machine # [ 81.592960] systemd[1]: Starting linger-users.service...1727machine # [ 81.661542] systemd[1]: linger-users.service: Deactivated successfully.1728machine # [ 81.664830] systemd[1]: Finished linger-users.service.1729machine # [ 81.924372] nixos[3443]: finished switching to system configuration /nix/store/b7kf0g268j4hff8a7aidm37hrarcb5y7-nixos-system-machine-test1730machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/automount/bin/switch-to-configuration test 2>&1, in 3.29 seconds)1731machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&11732machine # [ 82.553652] nixos[3583]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1733machine # [ 82.557572] systemd[1]: Stopped target Local File Systems.1734machine # [ 82.561187] systemd[1]: testauto.automount: Deactivated successfully.1735machine # [ 82.562351] systemd[1]: Unset automount testauto.automount.1736machine # [ 82.563180] systemd[1]: Stopped target Remote File Systems.1737machine # [ 83.035497] systemd[1]: Reload requested from client PID 3583 ('.switch-to-conf') (unit backdoor.service)...1738machine # [ 83.037898] systemd[1]: Reloading...1739machine # [ 83.671962] systemd[1]: Reloading finished in 631 ms.1740machine # [ 83.827769] systemd[583]: Reexecution requested from client PID 3652 ('switch-to-confi')...1741machine # [ 83.831813] systemd[583]: Reexecuting.1742machine # [ 83.890617] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1743machine # [ 84.097410] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1744machine # [ 84.104788] systemd[583]: Reload requested from client PID 3652 ('switch-to-confi')...1745machine # [ 84.107506] systemd[583]: Reloading...1746machine # [ 84.311397] systemd[583]: Reloading finished in 202 ms.1747machine # [ 84.328250] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1748machine # [ 84.338896] systemd[583]: Stopped Run user-specific NixOS activation.1749machine # [ 84.341698] systemd[583]: Stopping Run user-specific NixOS activation...1750machine # [ 84.345848] systemd[583]: Starting Run user-specific NixOS activation...1751machine # [ 84.397636] systemd[583]: Finished Run user-specific NixOS activation.1752machine # [ 84.402608] systemd[1]: Stopped target Reactivate sysinit units.1753machine # [ 84.406198] systemd[1]: Stopping Reactivate sysinit units...1754machine # [ 84.407713] systemd[1]: Reached target Reactivate sysinit units.1755machine # [ 84.410410] systemd[1]: Reached target Remote File Systems.1756machine # [ 84.411376] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1757machine # [ 84.413120] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1758machine # [ 84.420089] systemd[1]: Starting linger-users.service...1759machine # [ 84.430738] systemd[1]: Reached target Local File Systems.1760machine # [ 84.433690] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1761machine # [ 84.442766] systemd[1]: Starting Create SUID/SGID Wrappers...1762machine # [ 84.443759] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1763machine # [ 84.446475] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671764machine # [ 84.456591] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1765machine # [ 84.458371] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1766machine # [ 84.459646] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1767machine # [ 84.523583] systemd[1]: linger-users.service: Deactivated successfully.1768machine # [ 84.526265] systemd[1]: Finished linger-users.service.1769machine # [ 84.838809] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1770machine # [ 84.842501] systemd[1]: Finished Create SUID/SGID Wrappers.1771machine # [ 85.099808] nixos[3583]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1772machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1773(finished: subtest: fstab mounts, in 32.70 seconds)1774subtest: swaps1775machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&11776machine # [ 85.773287] nixos[3723]: switching to system configuration /nix/store/avh29x9i54smgb62f6rl4nnh9abkd381-nixos-system-machine-test1777machine # [ 85.776686] systemd[1]: Stopped target Local File Systems.1778machine # [ 85.778157] systemd[1]: Stopped target Remote File Systems.1779machine # [ 86.346292] systemd[1]: Reload requested from client PID 3723 ('.switch-to-conf') (unit backdoor.service)...1780machine # [ 86.348421] systemd[1]: Reloading...1781machine # [ 87.045532] systemd[1]: Reloading finished in 694 ms.1782machine # [ 87.191249] systemd[583]: Reexecution requested from client PID 3792 ('switch-to-confi')...1783machine # [ 87.195004] systemd[583]: Reexecuting.1784machine # [ 87.258761] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1785machine # [ 87.474948] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1786machine # [ 87.482262] systemd[583]: Reload requested from client PID 3792 ('switch-to-confi')...1787machine # [ 87.485069] systemd[583]: Reloading...1788machine # [ 87.690811] systemd[583]: Reloading finished in 204 ms.1789machine # [ 87.704222] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1790machine # [ 87.715423] systemd[583]: Stopped Run user-specific NixOS activation.1791machine # [ 87.718174] systemd[583]: Stopping Run user-specific NixOS activation...1792machine # [ 87.721464] systemd[583]: Starting Run user-specific NixOS activation...1793machine # [ 87.774057] systemd[583]: Finished Run user-specific NixOS activation.1794machine # [ 87.778600] systemd[1]: Stopped target Reactivate sysinit units.1795machine # [ 87.780145] systemd[1]: Stopping Reactivate sysinit units...1796machine # [ 87.780995] systemd[1]: Reached target Reactivate sysinit units.1797machine # [ 87.784783] systemd[1]: Reached target Remote File Systems.1798machine # [ 87.792638] systemd[1]: Starting Initialisation of swap device /swapfile...1799machine # [ 87.802874] systemd[1]: Reached target Local File Systems.1800machine # [ 87.803784] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1801machine # [ 87.810525] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1802machine # [ 87.820165] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1803machine # [ 87.821956] systemd[1]: Starting Create SUID/SGID Wrappers...1804machine # [ 87.822961] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1805machine # [ 87.829034] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671806machine # [ 87.831877] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1807machine # [ 87.833943] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1808machine # [ 87.835603] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1809machine # [ 87.923216] mkswap-swapfile-start[3804]: Creating swap file using dd and mkswap.1810machine # [ 87.958518] mkswap-swapfile-start[3823]: 1+0 records in1811machine # [ 87.959424] mkswap-swapfile-start[3823]: 1+0 records out1812machine # [ 87.960388] mkswap-swapfile-start[3823]: 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00308732 s, 340 MB/s1813machine # [ 87.986777] mkswap-swapfile-start[3826]: Setting up swapspace version 1, size = 1020 KiB (1044480 bytes)1814machine # [ 87.988577] mkswap-swapfile-start[3826]: no label, UUID=4f1c8093-5102-49df-b11c-143b460e62b51815machine # [ 87.995520] systemd[1]: mkswap-swapfile.service: Deactivated successfully.1816machine # [ 88.000855] systemd[1]: Finished Initialisation of swap device /swapfile.1817machine # [ 88.003001] systemd[1]: mkswap-swapfile.service: Consumed 50ms CPU time over 202ms wall clock time, 3.4M memory peak, 1M written to disk.1818machine # [ 88.007987] systemd[1]: Activating swap /swapfile...1819machine # [ 88.075863] Adding 1020k swap on /swapfile. Priority:-2 extents:1 across:1020k1820machine # [ 88.067814] systemd[1]: Activated swap /swapfile.1821machine # [ 88.281188] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1822machine # [ 88.286694] systemd[1]: Finished Create SUID/SGID Wrappers.1823machine # [ 88.290042] systemd[1]: Starting linger-users.service...1824machine # [ 88.360654] systemd[1]: linger-users.service: Deactivated successfully.1825machine # [ 88.363930] systemd[1]: Finished linger-users.service.1826machine # [ 88.623229] nixos[3723]: finished switching to system configuration /nix/store/avh29x9i54smgb62f6rl4nnh9abkd381-nixos-system-machine-test1827machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/specialisation/swap/bin/switch-to-configuration test 2>&1, in 3.52 seconds)1828machine: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&11829machine # [ 89.302358] systemd[1]: swapfile.swap: Deactivated successfully.1830machine # [ 89.305655] nixos[3877]: switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1831machine # [ 89.307361] systemd[1]: Stopped target Remote File Systems.1832machine # [ 89.310013] systemd[1]: Stopped target Local File Systems.1833machine # [ 89.851114] systemd[1]: Reload requested from client PID 3877 ('.switch-to-conf') (unit backdoor.service)...1834machine # [ 89.853394] systemd[1]: Reloading...1835machine # [ 90.502953] systemd[1]: Reloading finished in 645 ms.1836machine # [ 90.662583] systemd[583]: Reexecution requested from client PID 3946 ('switch-to-confi')...1837machine # [ 90.664928] systemd[583]: Reexecuting.1838machine # [ 90.734578] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1839machine # [ 90.946276] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1840machine # [ 90.952880] systemd[583]: Reload requested from client PID 3946 ('switch-to-confi')...1841machine # [ 90.955395] systemd[583]: Reloading...1842machine # [ 91.161414] systemd[583]: Reloading finished in 204 ms.1843machine # [ 91.176184] systemd[583]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1844machine # [ 91.187006] systemd[583]: Stopped Run user-specific NixOS activation.1845machine # [ 91.189523] systemd[583]: Stopping Run user-specific NixOS activation...1846machine # [ 91.192637] systemd[583]: Starting Run user-specific NixOS activation...1847machine # [ 91.244922] systemd[583]: Finished Run user-specific NixOS activation.1848machine # [ 91.249441] systemd[1]: Stopped target Reactivate sysinit units.1849machine # [ 91.251986] systemd[1]: Stopping Reactivate sysinit units...1850machine # [ 91.253975] systemd[1]: Reached target Reactivate sysinit units.1851machine # [ 91.256160] systemd[1]: Reached target Remote File Systems.1852machine # [ 91.258992] systemd[1]: Reached target Local File Systems.1853machine # [ 91.262476] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1854machine # [ 91.266739] systemd[1]: Starting Create SUID/SGID Wrappers...1855machine # [ 91.267660] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1856machine # [ 91.271355] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671857machine # [ 91.280937] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1858machine # [ 91.288928] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1859machine # [ 91.290323] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1860machine # [ 91.291564] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1861machine # [ 91.293071] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1862machine # [ 91.633964] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1863machine # [ 91.638704] systemd[1]: Finished Create SUID/SGID Wrappers.1864machine # [ 91.641445] systemd[1]: Starting linger-users.service...1865machine # [ 91.707052] systemd[1]: linger-users.service: Deactivated successfully.1866machine # [ 91.709815] systemd[1]: Finished linger-users.service.1867machine # [ 91.968127] nixos[3877]: finished switching to system configuration /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test1868machine: (finished: must succeed: /nix/store/6iwp28hmvyb2qpwgs19r2kxla5zy2hyx-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1869(finished: subtest: swaps, in 6.87 seconds)1870(finished: run the VM test script, in 92.84 seconds)1871test script finished in 92.93s1872cleanup1873kill QemuMachine (pid 45)1874machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)1875(finished: cleanup, in 0.01 seconds)1876additionally exposed symbols:1877 machine, other,1878 vlan1,1879 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_ssh