vm-test-run-switch-test-units
checks.aarch64-linux.switchTest-units
· build #24
· 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.vkmuAPSuiK', 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: 13a0ee68-0f95-4a0f-a9cb-2c9bb155f68417machine # 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.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Wed Sep 2 12:31:51 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/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/init regInfo=/nix/store/kn17z5aw4zlddw0gnsj5qfh14fszg4xz-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/kn17z5aw4zlddw0gnsj5qfh14fszg4xz-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 74886 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.000027] arm-pv: using stolen time PV105machine # [ 0.000408] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000577] Console: colour dummy device 80x25107machine # [ 0.000584] printk: legacy console [tty0] enabled108machine # [ 0.000772] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000778] pid_max: default: 32768 minimum: 301110machine # [ 0.000844] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.000963] landlock: Up and running.112machine # [ 0.000966] Yama: becoming mindful.113machine # [ 0.001404] LSM support for eBPF active114machine # [ 0.001535] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001555] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002799] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003521] rcu: Hierarchical SRCU implementation.118machine # [ 0.003525] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004664] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004753] EFI services will not be available.121machine # [ 0.004860] smp: Bringing up secondary CPUs ...122machine # [ 0.004868] smp: Brought up 1 node, 1 CPU123machine # [ 0.004871] SMP: Total of 1 processors activated.124machine # [ 0.004874] CPU: All CPU(s) started at EL1125machine # [ 0.004885] CPU features: detected: Branch Target Identification126machine # [ 0.004890] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004893] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004897] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.004900] CPU features: detected: Common not Private translations130machine # [ 0.004904] CPU features: detected: CRC32 instructions131machine # [ 0.004906] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.004910] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.004913] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.004916] CPU features: detected: E0PD135machine # [ 0.004919] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.004922] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.004925] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.004928] CPU features: detected: Fine Grained Traps139machine # [ 0.004931] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.004936] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.004939] CPU features: detected: LSE atomic instructions142machine # [ 0.004942] CPU features: detected: Privileged Access Never143machine # [ 0.004945] CPU features: detected: PMUv3144machine # [ 0.004948] CPU features: detected: RAS Extension Support145machine # [ 0.004950] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.004953] CPU features: detected: Random Number Generator147machine # [ 0.004956] CPU features: detected: Speculation barrier (SB)148machine # [ 0.004959] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.004962] CPU features: detected: TLB range maintenance instructions150machine # [ 0.004966] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.005002] alternatives: applying system-wide alternatives152machine # [ 0.007883] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008099] Memory: 894272K/1048576K available (24448K kernel code, 7090K rwdata, 26344K rodata, 4736K init, 1107K bss, 112964K reserved, 32768K cma-reserved)154machine # [ 0.008421] devtmpfs: initialized155machine # [ 0.010118] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010139] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010303] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010308] 0 pages in range for non-PLT usage159machine # [ 0.010309] 508288 pages in range for PLT usage160machine # [ 0.010413] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011232] DMI not present or invalid.162machine # [ 0.014254] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.016480] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.016629] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.016788] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.016808] audit: initializing netlink subsys (disabled)167machine # [ 0.017364] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.017366] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.017369] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.017372] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.017376] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.017398] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.017408] cpuidle: using governor ladder174machine # [ 0.017413] cpuidle: using governor menu175machine # [ 0.017592] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.017606] ASID allocator initialised with 65536 entries177machine # [ 0.018750] Serial: AMBA PL011 UART driver178machine # [ 0.023867] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.024025] printk: console [ttyAMA0] enabled180machine # [ 0.146276] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.146292] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.146298] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.146302] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.146307] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.146311] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.146315] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.146320] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.153657] fbcon: Taking over console189machine # [ 0.153671] ACPI: Interpreter disabled.190machine # [ 0.155521] iommu: Default domain type: Translated191machine # [ 0.155531] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.157189] SCSI subsystem initialized193machine # [ 0.162569] usbcore: registered new interface driver usbfs194machine # [ 0.162605] usbcore: registered new interface driver hub195machine # [ 0.162621] usbcore: registered new device driver usb196machine # [ 0.162875] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.162881] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.162891] PTP clock support registered199machine # [ 0.162936] EDAC MC: Ver: 3.0.0200machine # [ 0.167544] scmi_core: SCMI protocol bus registered201machine # [ 0.168482] FPGA manager framework202machine # [ 0.169405] vgaarb: loaded203machine # [ 0.170003] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.176190] VFS: Disk quotas dquot_6.6.0205machine # [ 0.176234] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.178101] netfs: FS-Cache loaded207machine # [ 0.178215] pnp: PnP ACPI: disabled208machine # [ 0.182077] NET: Registered PF_INET protocol family209machine # [ 0.182222] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.210444] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.210482] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.210504] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.210549] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.210624] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.210693] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.210748] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.210773] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.210869] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.210908] NET: Registered PF_XDP protocol family220machine # [ 0.210925] PCI: CLS 0 bytes, default 64221machine # [ 0.211149] Trying to unpack rootfs image as initramfs...222machine # [ 0.227550] kvm [1]: HYP mode not available223machine # [ 0.318480] Initialise system trusted keyrings224machine # [ 0.319202] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.320421] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.321186] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.341750] Key type asymmetric registered228machine # [ 0.341765] Asymmetric key parser 'x509' registered229machine # [ 0.341826] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.350064] io scheduler mq-deadline registered231machine # [ 0.350080] io scheduler kyber registered232machine # [ 0.358139] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.359487] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.359858] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.359875] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.359891] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.359899] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.359927] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.359961] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.360047] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.360064] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.360070] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.360076] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.360081] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.360140] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.360598] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.360788] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.360804] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.360835] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.360851] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.361306] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.361490] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.361507] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.361536] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.361989] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.390226] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.390254] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.390285] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.390777] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.390974] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.390991] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.391021] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.391496] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.391681] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.391697] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.391727] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.392183] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.392368] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.392385] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.392414] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.392877] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.393064] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.393080] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.393110] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.393126] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.393591] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.393782] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.393812] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.394288] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.394477] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.394508] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.394898] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.395095] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.395344] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.395625] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.395643] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.395673] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.396146] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.396332] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.396348] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.396377] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.396957] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.396969] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.396974] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.397019] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.397066] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.397113] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.397159] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.397205] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.397252] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.397299] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.397346] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.397392] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.397454] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.397500] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.397522] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.397543] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.397564] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.397586] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.397609] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.397631] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.397653] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.397675] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.397697] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.397718] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.397741] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.397762] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.397783] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.397804] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.397825] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.397847] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.397868] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.397889] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.397911] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.397933] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.397959] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.397969] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.397973] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.463202] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.483337] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.485414] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.496523] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.506194] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.509963] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.511991] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.514004] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.524758] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.535033] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.537058] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.540909] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.559225] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.561579] msm_serial: driver initialized343machine # [ 0.561736] SuperH (H)SCI(F) driver initialized344machine # [ 0.561787] STM32 USART driver initialized345machine # [ 0.590159] loop: module loaded346machine # [ 0.590309] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.591052] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.602565] megasas: 07.734.00.00-rc1349machine # [ 0.603428] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.605496] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.605521] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.610430] Using buffer write method353machine # [ 0.610492] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.612097] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.612120] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.613999] Using buffer write method357machine # [ 0.622047] Concatenating MTD devices:358machine # [ 0.622051] (0): "0.flash"359machine # [ 0.622055] (1): "0.flash"360machine # [ 0.622059] into device "0.flash"361machine # [ 0.840778] Freeing initrd memory: 26104K362machine # [ 0.846584] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.850165] thunder_xcv, ver 1.0364machine # [ 0.850199] thunder_bgx, ver 1.0365machine # [ 0.850221] nicpf, ver 1.0366machine # [ 0.850756] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.850763] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.850790] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.850798] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.850823] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.850828] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.850850] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.850856] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.851001] sky2: driver version 1.30375machine # [ 0.852536] usbcore: registered new interface driver usb-storage376machine # [ 0.852686] usbcore: registered new interface driver usbserial_generic377machine # [ 0.852699] usbserial: USB Serial support registered for generic378machine # [ 0.853269] hv_vmbus: registering driver hyperv_keyboard379machine # [ 0.854544] ehci-pci 0000:00:0a.0: EHCI Host Controller380machine # [ 0.854570] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1381machine # [ 0.854733] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000382machine # [ 0.866459] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00383machine # [ 0.866780] hub 1-0:1.0: USB hub found384machine # [ 0.866795] hub 1-0:1.0: 6 ports detected385machine # [ 0.869787] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 0.869820] rtc-pl031 9010000.pl031: setting system clock to 2026-09-06T17:54:39 UTC (1788717279)387machine # [ 0.871871] i2c_dev: i2c /dev entries driver388machine # [ 0.874976] sdhci: Secure Digital Host Controller Interface driver389machine # [ 0.874988] sdhci: Copyright(c) Pierre Ossman390machine # [ 0.875262] Synopsys Designware Multimedia Card Interface Driver391machine # [ 0.875616] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 0.877192] hid: raw HID events driver (C) Jiri Kosina393machine # [ 0.877435] usbcore: registered new interface driver usbhid394machine # [ 0.877442] usbhid: USB HID core driver395machine # [ 0.882998] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 0.884454] drop_monitor: Initializing network drop monitor service397machine # [ 0.884563] NET: Registered PF_INET6 protocol family398machine # [ 0.887571] Segment Routing with IPv6399machine # [ 0.887592] In-situ OAM (IOAM) with IPv6400machine # [ 0.887631] NET: Registered PF_PACKET protocol family401machine # [ 0.889283] 9pnet: Installing 9P2000 support402machine # [ 0.892090] Key type dns_resolver registered403machine # [ 0.898434] registered taskstats version 1404machine # [ 0.898597] Loading compiled-in X.509 certificates405machine # [ 0.906824] Demotion targets for Node 0: null406machine # [ 0.906924] Key type .fscrypt registered407machine # [ 0.906931] Key type fscrypt-provisioning registered408machine # [ 0.907027] ima: No TPM chip found, activating TPM-bypass!409machine # [ 0.907046] ima: Allocated hash algorithm: sha1410machine # [ 0.907072] ima: No architecture policies found411machine # [ 0.910916] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 0.928874] clk: Disabling unused clocks413machine # [ 0.928899] PM: genpd: Disabling unused power domains414machine # [ 0.933087] Freeing unused kernel memory: 4736K415machine # [ 0.933278] Run /init as init process416machine # [ 0.948144] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.114076] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.266192] 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.282881] systemd[1]: systemd 262-9457f81 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.294863] systemd[1]: Detected virtualization qemu.421machine # [ 1.294955] systemd[1]: Detected architecture arm64.422machine # [ 1.294978] systemd[1]: Running in initrd.423machine # [ 1.295846] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.296114] systemd[1]: Hostname set to <machine>.425machine # [ 1.350262] 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.443604] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.474067] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.496507] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.504227] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.505400] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.506755] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.507782] systemd[1]: Reached target Path Units.433machine # [ 1.508621] systemd[1]: Reached target Slice Units.434machine # [ 1.509406] systemd[1]: Reached target Swaps.435machine # [ 1.510147] systemd[1]: Reached target Timer Units.436machine # [ 1.511072] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.512260] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.513346] systemd[1]: Listening on Journal Sockets.439machine # [ 1.514344] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.514368] systemd[1]: Reached target Socket Units.441machine # [ 1.517374] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.525804] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.527096] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.542421] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.558176] systemd[1]: Starting Journal Service...446machine # [ 1.560330] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.561131] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.574414] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.594544] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.595472] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.451machine # [ 1.606175] systemd[1]: Finished Load Kernel Module 9pnet_virtio.452machine # [ 1.606476] systemd[1]: Mounted Kernel Configuration File System.453machine # [ 1.610781] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...454machine # [ 1.621633] 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/input2455machine # [ 1.621754] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0456machine # [ 1.646155] systemd-journald[73]: Collecting audit messages is disabled.457machine # [ 1.654738] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.656888] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.459machine # [ 1.659293] systemd[1]: Starting Create Static Device Nodes in /dev...460machine # [ 1.670056] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev461machine # [ 1.677751] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0462machine # [ 1.677993] [drm] features: -virgl +edid -resource_blob -host_visible463machine # [ 1.690783] [drm] features: -context_init464machine # [ 1.691600] [drm] number of scanouts: 1465machine # [ 1.691619] [drm] number of cap sets: 0466machine # [ 1.698345] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.698359] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.718554] systemd[1]: Finished Create Static Device Nodes in /dev.469machine # [ 1.719720] systemd[1]: Reached target Preparation for Local File Systems.470machine # [ 1.720605] systemd[1]: Reached target Local File Systems.471machine # [ 1.726726] systemd[1]: Starting Rule-based Manager for Device Events and Files...472machine # [ 1.727883] Console: switching to colour frame buffer device 160x50473machine # [ 1.750565] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device474machine # [ 1.767185] systemd[1]: Finished Load Kernel Modules.475machine # [ 1.770090] systemd[1]: Starting Apply Kernel Variables...476machine # [ 1.809661] systemd[1]: Started Journal Service.477machine # [ 1.804293] systemd-modules-load[74]: Inserted module 'dm_mod'478machine # [ 1.805511] systemd-modules-load[74]: Module 'virtio_balloon' is built in479machine # [ 1.812465] systemd-modules-load[74]: Module 'virtio_console' is built in480machine # [ 1.816803] systemd-modules-load[74]: Inserted module 'virtio_gpu'481machine # [ 1.824340] systemd-modules-load[74]: Module 'virtio_rng' is built in482machine # [ 1.832313] systemd[1]: Starting Create System Files and Directories...483machine # [ 1.834229] systemd-udevd[80]: Using default interface naming scheme 'v261'.484machine # [ 1.835347] systemd[1]: Finished Apply Kernel Variables.485machine # [ 1.850041] systemd[1]: Finished Create System Files and Directories.486machine # [ 1.866318] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 1.942225] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.000472] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.003563] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.610233] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.611216] systemd[1]: Reached target System Initialization.492machine # [ 2.616263] systemd[1]: Reached target Basic System.493machine # [ 2.756181] (udev-worker)[92]: Network interface NamePolicy= disabled on kernel command line.494machine # [ 2.769665] (udev-worker)[101]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.495machine # [ 2.784381] (udev-worker)[101]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 2.835736] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 2.838768] systemd[1]: Reached target Initrd Root Device.498machine # [ 2.842177] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 2.885955] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 2.893469] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 2.899079] systemd[1]: Mounting /sysroot...502machine # [ 2.952698] EXT4-fs (vda): mounted filesystem 13a0ee68-0f95-4a0f-a9cb-2c9bb155f684 r/w with ordered data mode. Quota mode: none.503machine # [ 2.939658] systemd[1]: Mounted /sysroot.504machine # [ 2.941050] systemd[1]: Reached target Initrd Root File System.505machine # [ 2.944761] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 2.968325] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 2.975092] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 2.979912] systemd[1]: Reloading...509machine # [ 3.158487] systemd[1]: Reloading finished in 177 ms.510machine # [ 3.193936] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.197717] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.200651] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.212782] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.226532] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.227548] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.231809] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.234448] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.579211] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.594089] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.599530] systemd[1]: Mounting /sysroot/run...521machine # [ 3.618573] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.638661] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.671246] systemd[1]: Mounted /sysroot/nix/.rw-store.524machine # [ 3.678914] systemd[1]: Mounted /sysroot/nix/.ro-store.525machine # [ 3.698896] systemd[1]: Mounted /sysroot/run.526machine # [ 3.703245] systemd[1]: Mounted /sysroot/tmp/shared.527machine # [ 3.706986] systemd[1]: Mounted /sysroot/tmp/xchg.528machine # [ 3.715028] systemd[1]: Starting rw-sysroot-nix-store.service...529machine # [ 3.738968] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.740384] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 4.098275] (udev-worker)[101]: mtd0ro: Failed to find and pin callout binary "/nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/lib/udev/mtd_probe": No such file or directory532machine # [ 4.104839] (udev-worker)[101]: mtd0ro: /nix/store/x4i8f5p0japrw9j9zrdx7z9lf5387qn3-systemd-262-9457f81/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.124951] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.534machine # [ 4.129417] systemd[1]: Stopped Virtual Console Setup.535machine # [ 4.130900] systemd[1]: Stopping Virtual Console Setup...536machine # [ 4.134533] systemd[1]: Starting Virtual Console Setup...537machine # [ 4.146384] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.538machine # [ 4.147845] systemd[1]: Stopped Virtual Console Setup.539machine # [ 4.151178] systemd[1]: Starting Virtual Console Setup...540machine # [ 4.174097] systemd-vconsole-setup[155]: Configuration of first virtual console was skipped, ignoring remaining ones.541machine # [ 4.176621] systemd[1]: Finished Virtual Console Setup.542machine # [ 4.582642] systemd[1]: Mounting /sysroot/nix/store...543machine # [ 4.645406] systemd[1]: Mounted /sysroot/nix/store.544machine # [ 4.650785] systemd[1]: Reached target Initrd File Systems.545machine # [ 4.653609] systemd[1]: Starting Find NixOS closure...546machine # [ 4.661598] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...547machine # [ 4.706648] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.548machine # [ 4.710468] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.549machine # [ 4.725299] systemd[1]: Finished Find NixOS closure.550machine # [ 4.728190] systemd[1]: Reached target Initrd Default Target.551machine # [ 4.729909] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...552machine # [ 4.763113] systemd[1]: Stopped target Initrd Default Target.553machine # [ 4.765262] systemd[1]: Stopped target Basic System.554machine # [ 4.766779] systemd[1]: Stopped target Initrd Root Device.555machine # [ 4.772387] systemd[1]: Stopped target Path Units.556machine # [ 4.774377] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.557machine # [ 4.776261] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.558machine # [ 4.779676] systemd[1]: Stopped target Slice Units.559machine # [ 4.784217] systemd[1]: Stopped target Socket Units.560machine # [ 4.785314] systemd[1]: Stopped target System Initialization.561machine # [ 4.786449] systemd[1]: Stopped target Swaps.562machine # [ 4.792187] systemd[1]: Stopped target Timer Units.563machine # [ 4.793258] systemd[1]: dbus.socket: Deactivated successfully.564machine # [ 4.794361] systemd[1]: Closed D-Bus System Message Bus Socket.565machine # [ 4.795486] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.566machine # [ 4.799479] systemd[1]: Stopped Find NixOS closure.567machine # [ 4.804836] systemd[1]: Starting Load Kernel Module 9pnet_virtio...568machine # [ 4.805932] systemd[1]: Starting rw-sysroot-nix-store.service...569machine # [ 4.806922] systemd[1]: systemd-sysctl.service: Deactivated successfully.570machine # [ 4.820829] systemd[1]: Stopped Apply Kernel Variables.571machine # [ 4.823228] systemd[1]: systemd-modules-load.service: Deactivated successfully.572machine # [ 4.828426] systemd[1]: Stopped Load Kernel Modules.573machine # [ 4.829342] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.574machine # [ 4.830520] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.575machine # [ 4.831668] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.576machine # [ 4.844395] systemd[1]: Stopped Create System Files and Directories.577machine # [ 4.847859] systemd[1]: Stopped target Local File Systems.578machine # [ 4.850885] systemd[1]: Stopped target Preparation for Local File Systems.579machine # [ 4.851906] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.580machine # [ 4.856494] systemd[1]: Stopped Coldplug All udev Devices.581machine # [ 4.857365] systemd[1]: Stopping Rule-based Manager for Device Events and Files...582machine # [ 4.858366] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.583machine # [ 4.859332] systemd[1]: Stopped Virtual Console Setup.584machine # [ 4.861713] systemd[1]: initrd-cleanup.service: Deactivated successfully.585machine # [ 4.862778] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.586machine # [ 4.863683] systemd[1]: systemd-udevd.service: Deactivated successfully.587machine # [ 4.864809] systemd[1]: Stopped Rule-based Manager for Device Events and Files.588machine # [ 4.865815] systemd[1]: systemd-udevd.service: Consumed 1.435s CPU time over 3.134s wall clock time, 22.3M memory peak.589machine # [ 4.867212] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.590machine # [ 4.871854] systemd[1]: Finished rw-sysroot-nix-store.service.591machine # [ 4.872867] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.592machine # [ 4.876441] systemd[1]: Finished Load Kernel Module 9pnet_virtio.593machine # [ 4.877355] systemd[1]: Starting Cleanup udev Database...594machine # [ 4.880245] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.595machine # [ 4.881355] systemd[1]: Stopped Create Static Device Nodes in /dev.596machine # [ 4.884103] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.597machine # [ 4.885232] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.598machine # [ 4.888192] systemd[1]: kmod-static-nodes.service: Deactivated successfully.599machine # [ 4.889187] systemd[1]: Stopped Create List of Static Device Nodes.600machine # [ 4.915552] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.601machine # [ 4.920616] systemd[1]: Finished Cleanup udev Database.602machine # [ 4.921387] systemd[1]: Reached target Switch Root.603machine # [ 4.922070] systemd[1]: Starting NixOS Activation...604machine # [ 5.081174] initrd-nixos-activation-start[180]: booting system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test605machine # [ 5.144566] initrd-nixos-activation-start[180]: running activation script...606machine # [ 5.552509] initrd-nixos-activation-start[203]: setting up /etc...607machine # [ 5.813842] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.608machine # [ 5.816733] systemd[1]: Finished NixOS Activation.609machine # [ 5.820255] systemd[1]: Starting Switch Root...610machine # [ 5.839902] systemd[1]: Switching root.611machine # [ 6.036610] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).612machine # [ 6.627963] systemd[1]: systemd 262-9457f81 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.639846] systemd[1]: Detected virtualization qemu.614machine # [ 6.642619] systemd[1]: Detected architecture arm64.615machine # [ 6.646127] systemd[1]: Detected first boot.616machine # [ 6.652558] systemd[1]: Initializing machine ID from random generator.617machine # [ 6.815866] systemd[1]: bpf-restrict-fs: LSM BPF program attached618machine # [ 6.979161] systemd[1]: Applying preset policy.619machine # [ 7.463299] systemd[1]: Populated /etc with preset unit settings.620machine # [ 7.965854] systemd[1]: initrd-switch-root.service: Deactivated successfully.621machine # [ 7.967514] systemd[1]: Stopped initrd-switch-root.service.622machine # [ 7.968966] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.623machine # [ 7.972430] systemd[1]: Created slice Slice /system/getty.624machine # [ 7.974450] systemd[1]: Created slice User and Session Slice.625machine # [ 7.976202] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.626machine # [ 7.978295] systemd[1]: Started Forward Password Requests to Wall Directory Watch.627machine # [ 7.980111] systemd[1]: Expecting device /dev/hvc0...628machine # [ 7.981568] systemd[1]: Expecting device /dev/ttyAMA0...629machine # [ 7.983558] systemd[1]: Reached target Local Encrypted Volumes.630machine # [ 7.985457] systemd[1]: Stopped target initrd-fs.target.631machine # [ 7.987358] systemd[1]: Stopped target initrd-root-fs.target.632machine # [ 7.989210] systemd[1]: Stopped target initrd-switch-root.target.633machine # [ 7.991207] systemd[1]: Reached target Virtual Machines and Containers.634machine # [ 7.993167] systemd[1]: Reached target Path Units.635machine # [ 7.994976] systemd[1]: Reached target Remote File Systems.636machine # [ 7.996763] systemd[1]: Reached target Slice Units.637machine # [ 7.998535] systemd[1]: Reached target Swaps.638machine # [ 8.002350] systemd[1]: Listening on Query the User Interactively for a Password.639machine # [ 8.007061] systemd[1]: Listening on Process Core Dump Socket.640machine # [ 8.010836] systemd[1]: Listening on Credential Encryption/Decryption.641machine # [ 8.014542] systemd[1]: Listening on Factory Reset Management.642machine # [ 8.016449] systemd[1]: Listening on Hostname Service Socket.643machine # [ 8.021439] systemd[1]: Starting Journal Log Access Socket...644machine # [ 8.024278] systemd[1]: Listening on Journal Audit Socket.645machine # [ 8.028323] systemd[1]: Listening on Console Output Muting Service Socket.646machine # [ 8.030617] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.647machine # [ 8.032922] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os648machine # [ 8.035391] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki649machine # [ 8.045650] systemd[1]: Listening on Disk Repartitioning Service Socket.650machine # [ 8.047156] systemd[1]: Listening on udev Varlink Socket.651machine # [ 8.051175] systemd[1]: Mounting Huge Pages File System...652machine # [ 8.055996] systemd[1]: Mounting POSIX Message Queue File System...653machine # [ 8.063548] systemd[1]: Mounting Kernel Debug File System...654machine # [ 8.074872] systemd[1]: Mounting Kernel Trace File System...655machine # [ 8.085916] systemd[1]: Starting Create List of Static Device Nodes...656machine # [ 8.097657] systemd[1]: Starting Load Kernel Module 9pnet_virtio...657machine # [ 8.099503] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs658machine # [ 8.112493] systemd[1]: Mounting Kernel Configuration File System...659machine # [ 8.114201] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm660machine # [ 8.116286] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore661machine # [ 8.136304] systemd[1]: Starting Load Kernel Module fuse...662machine # [ 8.138806] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67663machine # [ 8.178549] systemd[1]: Starting Journal Service...664machine # [ 8.200885] systemd[1]: Starting Load Kernel Modules...665machine # [ 8.231609] fuse: init (API version 7.45)666machine # [ 8.232336] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...667machine # [ 8.248018] systemd[1]: Starting Remount Root and Kernel File Systems...668machine # [ 8.254195] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os669machine # [ 8.281152] systemd[1]: Starting Coldplug All udev Devices...670machine # [ 8.308128] systemd[1]: Listening on Journal Log Access Socket.671machine # [ 8.312884] systemd[1]: Mounted Huge Pages File System.672machine # [ 8.317849] systemd[1]: Mounted POSIX Message Queue File System.673machine # [ 8.322928] systemd[1]: Mounted Kernel Debug File System.674machine # [ 8.326739] systemd[1]: Mounted Kernel Trace File System.675machine # [ 8.331986] systemd[1]: Finished Create List of Static Device Nodes.676machine # [ 8.337631] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.677machine # [ 8.343236] systemd[1]: Finished Load Kernel Module 9pnet_virtio.678machine # [ 8.351527] systemd[1]: Mounted Kernel Configuration File System.679machine # [ 8.359822] systemd[1]: modprobe@fuse.service: Deactivated successfully.680machine # [ 8.364066] systemd[1]: Finished Load Kernel Module fuse.681machine # [ 8.371594] systemd[1]: Finished Load Kernel Modules.682machine # [ 8.380420] systemd-journald[275]: Collecting audit messages is enabled.683machine # [ 8.390191] systemd[1]: Mounting FUSE Control File System...684machine # [ 8.381342] systemd[1]: Queued start job for default target Multi-User System.685machine # [ 8.382837] systemd[1]: systemd-journald.service: Deactivated successfully.686machine # [ 8.403559] systemd[1]: Starting Firewall...687machine # [ 8.389197] systemd-modules-load[276]: Module 'atkbd' is built in688machine # [ 8.397666] systemd-modules-load[276]: Module 'loop' is built in689machine # [ 8.418463] systemd[1]: Starting Apply Kernel Variables...690machine # [ 8.426318] EXT4-fs (vda): re-mounted 13a0ee68-0f95-4a0f-a9cb-2c9bb155f684.691machine # [ 8.455019] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...692machine # [ 8.453969] systemd-oomd[277]: No swap; memory pressure usage will be degraded693machine # [ 8.470845] systemd[1]: Started Journal Service.694machine # [ 8.469982] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.695machine # [ 8.474106] systemd[1]: Finished Remount Root and Kernel File Systems.696machine # [ 8.541073] systemd[1]: Listening on Disk Image Download Service Socket.697machine # [ 8.554087] systemd[1]: Starting Flush Journal to Persistent Storage...698machine # [ 8.559923] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore699machine # [ 8.569853] systemd[1]: Starting Load/Save OS Random Seed...700machine # [ 8.575839] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os701machine # [ 8.607933] systemd[1]: Mounted FUSE Control File System.702machine # [ 8.619060] systemd[1]: Finished Apply Kernel Variables.703machine # [ 8.627932] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.704machine # [ 8.658279] systemd-journald[275]: Received client request to flush runtime journal.705machine # [ 8.729046] systemd[1]: Starting Create Static Device Nodes in /dev...706machine # [ 8.735743] systemd[1]: Finished Load/Save OS Random Seed.707machine # [ 8.741504] systemd[1]: Reached target First Boot Complete.708machine # [ 8.742416] systemd[1]: Finished Flush Journal to Persistent Storage.709machine # [ 8.769970] systemd[1]: Finished Create Static Device Nodes in /dev.710machine # [ 8.771150] systemd[1]: Reached target Preparation for Local File Systems.711machine # [ 8.774918] systemd[1]: Starting Rule-based Manager for Device Events and Files...712machine # [ 8.888372] systemd-udevd[317]: Using default interface naming scheme 'v261'.713machine # [ 8.957225] systemd[1]: Mounting /run/wrappers...714machine # [ 9.014975] systemd[1]: Mounted /run/wrappers.715machine # [ 9.017721] systemd[1]: Reached target Local File Systems.716machine # [ 9.026234] systemd[1]: Listening on Boot Loader Control Service Socket.717machine # [ 9.031808] systemd[1]: Starting register-nix-paths.service...718machine # [ 9.038180] systemd[1]: Starting Create SUID/SGID Wrappers...719machine # [ 9.042527] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.720machine # [ 9.054980] systemd[1]: Starting Save Transient machine-id to Disk...721machine # [ 9.089794] systemd[1]: Starting Create System Files and Directories...722machine # [ 9.100687] systemd[1]: Started Rule-based Manager for Device Events and Files.723machine # [ 9.197586] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.724machine # [ 9.207110] systemd[1]: Finished Save Transient machine-id to Disk.725machine # [ 9.301530] systemd[1]: Finished Create System Files and Directories.726machine # [ 9.312655] systemd[1]: Starting Rebuild Journal Catalog...727machine # [ 9.324841] systemd[1]: Starting Record System Boot/Shutdown in UTMP...728machine # [ 9.433396] systemd[1]: Finished Record System Boot/Shutdown in UTMP.729machine # [ 9.483784] systemd[1]: Finished Rebuild Journal Catalog.730machine # [ 9.491681] systemd[1]: Starting Update is Completed...731machine # [ 9.581091] systemd[1]: Finished Update is Completed.732machine # [ 9.893925] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.733machine # [ 9.898780] systemd[1]: Finished Create SUID/SGID Wrappers.734machine # [ 10.585259] systemd[1]: Finished Coldplug All udev Devices.735machine # [ 10.594562] systemd[1]: Finished Firewall.736machine # [ 10.641806] systemd[1]: Finished register-nix-paths.service.737machine # [ 10.642816] systemd[1]: Reached target System Initialization.738machine # [ 10.643676] systemd[1]: Started Discard unused filesystem blocks once a week.739machine # [ 10.647652] systemd[1]: Started Daily Cleanup of Temporary Directories.740machine # [ 10.652396] systemd[1]: Reached target Timer Units.741machine # [ 10.653180] systemd[1]: Listening on D-Bus System Message Bus Socket.742machine # [ 10.654157] systemd[1]: Listening on Nix Daemon Socket.743machine # [ 10.654946] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.744machine # [ 10.663070] systemd[1]: Reached target Socket Units.745machine # [ 10.663872] systemd[1]: Reached target Basic System.746machine # [ 10.668720] systemd[1]: Starting Import lastlog data into lastlog2 database...747machine # [ 10.670652] systemd[1]: Starting Name Service Cache Daemon (nsncd)...748machine # [ 10.680963] systemd[1]: Starting Post-Boot Actions...749machine # [ 10.716444] systemd[1]: Started Reset console on configuration changes.750machine # [ 10.740179] systemd[1]: Starting resolvconf update...751machine # [ 10.819221] systemd[1]: Starting D-Bus System Message Bus...752machine # [ 10.822593] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs753machine # [ 10.841672] systemd[1]: Finished Post-Boot Actions.754machine # [ 10.867447] nsncd[484]: Sep 06 17:54:49.513 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"755machine # [ 10.880567] systemd[1]: Started Name Service Cache Daemon (nsncd).756machine # [ 10.913350] systemd[1]: Reached target Host and Network Name Lookups.757machine # [ 10.914517] systemd[1]: Reached target User and Group Name Lookups.758machine # [ 10.934430] systemd[1]: Starting User Login Management...759machine # [ 11.003337] systemd[1]: Finished Import lastlog data into lastlog2 database.760machine # [ 11.119229] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...761machine # [ 11.134573] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'762machine # [ 11.140606] dbus-broker-launch[489]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"763machine # [ 11.191831] systemd[1]: Started D-Bus System Message Bus.764machine # [ 11.199846] systemd-logind[505]: New seat seat0.765machine # [ 11.206669] systemd[1]: Started User Login Management.766machine # [ 11.216559] systemd[1]: Starting linger-users.service...767machine # [ 11.250225] dbus-broker-launch[489]: Ready768machine # [ 11.259240] systemd[1]: Stopped target Host and Network Name Lookups.769machine # [ 11.266942] systemd[1]: Stopping Host and Network Name Lookups...770machine # [ 11.273342] systemd[1]: Stopped target User and Group Name Lookups.771machine # [ 11.277309] systemd[1]: Stopping User and Group Name Lookups...772machine # [ 11.282212] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...773machine # [ 11.288403] systemd[1]: nscd.service: Deactivated successfully.774machine # [ 11.289328] systemd[1]: Stopped Name Service Cache Daemon (nsncd).775machine # [ 11.329531] systemd[1]: Starting Name Service Cache Daemon (nsncd)...776machine # [ 11.385177] systemd[1]: Created slice Slice /user/1001.777machine # [ 11.408670] systemd[1]: Starting User Runtime Directory /run/user/1001...778machine # [ 11.449095] nsncd[558]: Sep 06 17:54:50.096 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"779machine # [ 11.457572] systemd[1]: Started Name Service Cache Daemon (nsncd).780machine # [ 11.459790] systemd[1]: linger-users.service: Deactivated successfully.781machine # [ 11.467749] systemd[1]: Finished linger-users.service.782machine # [ 11.469635] systemd[1]: Finished resolvconf update.783machine # [ 11.491341] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.784machine # [ 11.502583] systemd[1]: Reached target Preparation for Network.785machine # [ 11.506707] systemd[1]: Reached target Host and Network Name Lookups.786machine # [ 11.513229] systemd[1]: Reached target User and Group Name Lookups.787machine # [ 11.524933] systemd[1]: Starting DHCP Client...788machine # [ 11.533179] systemd[1]: Starting Extra networking commands....789machine # [ 11.553800] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.790machine # [ 11.582323] systemd[1]: Started backdoor.service.791machine # [ 11.666120] systemd[1]: Finished User Runtime Directory /run/user/1001.792machine # [ 11.709237] systemd[1]: Starting User Manager for UID 1001...793machine # [ 11.909687] (systemd)[589]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)794machine # connecting to host...795machine # [ 11.936746] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line.796machine # [ 11.957050] systemd-logind[505]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.797machine # [ 11.984470] (udev-worker)[350]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.798machine # [ 11.986669] (udev-worker)[350]: Network interface NamePolicy= disabled on kernel command line.799machine # [ 12.037430] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy800machine: Guest shell says: b'Spawning backdoor root shell...\n'801machine: connected to guest root shell802machine: (connecting took 12.47 seconds)803machine: (finished: waiting for the VM to finish booting, in 12.90 seconds)804machine # [ 12.179953] systemd[1]: Condition check resulted in Virtio network device being skipped.805machine # [ 12.193408] systemd[1]: Starting Address configuration of eth1...806machine # [ 12.301634] systemd[589]: Queued start job for default target Main User Target.807machine # [ 12.310363] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy808machine # [ 12.354229] systemd[589]: Created slice User Application Slice.809machine # [ 12.366852] systemd[589]: Started Daily Cleanup of User's Temporary Directories.810machine # [ 12.383211] systemd[589]: Reached target Paths.811machine # [ 12.402523] systemd[589]: Reached target Timers.812machine # [ 12.422748] systemd[589]: Starting D-Bus User Message Bus Socket...813machine # [ 12.438682] systemd[589]: Starting Create User Files and Directories...814machine # [ 12.458358] systemd[589]: Finished Create User Files and Directories.815machine # [ 12.489796] dhcpcd[596]: dhcpcd-10.3.2 starting816machine # [ 12.521542] systemd[589]: Listening on D-Bus User Message Bus Socket.817machine # [ 12.522445] systemd[589]: Reached target Sockets.818machine # [ 12.523078] systemd[589]: Reached target Basic System.819machine # [ 12.523756] systemd[1]: Started User Manager for UID 1001.820machine # [ 12.589314] dhcpcd[672]: dev: loaded udev821machine # [ 12.605910] systemd[589]: Starting Run user-specific NixOS activation...822machine # [ 12.640948] 8021q: 802.1Q VLAN Support v1.8823machine # [ 12.647762] 8021q: adding VLAN 0 to HW filter on device eth1824machine # [ 12.637798] systemd[589]: Finished Run user-specific NixOS activation.825machine # [ 12.638782] systemd[589]: Reached target Main User Target.826machine # [ 12.639522] systemd[589]: Startup finished in 585ms.827machine # [ 12.724401] network-addresses-eth1-start[623]: adding address 192.168.1.1/24... done828machine # [ 12.748439] cfg80211: Loading compiled-in X.509 certificates for regulatory database829machine # [ 12.758162] systemd[1]: Finished Extra networking commands..[ 12.783094] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'830machine # [ 12.783549] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'831machine # [ 12.788232] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2832machine # [ 12.788542] cfg80211: failed to load regulatory.db833machine # 834machine # [ 12.789066] systemd[1]: Reached target Network.835machine # [ 12.789754] systemd[1]: Starting Permit User Sessions...836machine # [ 12.791059] systemd-logind[505]: Watching system buttons on /dev/input/event0 (gpio-keys)837machine # [ 12.833335] network-addresses-eth1-start[623]: adding address 2001:db8:1::1/64... done838machine # [ 12.871456] mousedev: PS/2 mouse device common for all mice839machine # [ 12.867918] systemd[1]: Finished Address configuration of eth1.840machine # [ 12.898091] systemd[1]: Finished Permit User Sessions.841machine # [ 12.902853] systemd[1]: Started Getty on tty1.842machine # [ 12.912985] systemd[1]: Reached target Login Prompts.843machine # [ 13.015364] systemd-logind[505]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)844machine # [ 13.081860] 8021q: adding VLAN 0 to HW filter on device eth0845machine # [ 13.070209] dhcpcd[672]: eth0: waiting for carrier846machine # [ 13.080308] dhcpcd[672]: libudev: received NULL device847machine # [ 13.081196] dhcpcd[672]: libudev: received NULL device848machine # [ 13.082051] dhcpcd[672]: eth0: carrier acquired849machine # [ 13.152120] dhcpcd[672]: DUID 00:01:00:01:32:30:65:6b:52:54:00:12:34:56850machine # [ 13.153062] dhcpcd[672]: eth0: IAID 00:12:34:56851machine # [ 13.153690] dhcpcd[672]: eth0: adding address fe80::5054:ff:fe12:3456852machine # [ 13.237850] dhcpcd[672]: eth0: soliciting a DHCP lease853machine # [ 13.249008] dhcpcd[672]: eth0: offered 10.0.2.15 from 10.0.2.2854machine # [ 13.259702] dhcpcd[672]: eth0: probing address 10.0.2.15/24855machine # [ 13.659609] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3856machine # [ 13.942251] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.857machine # [ 13.974270] systemd[1]: Starting Virtual Console Setup...858machine # [ 14.012659] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.859machine # [ 14.016430] systemd[1]: Stopped Virtual Console Setup.860machine # [ 14.019726] systemd[1]: Starting Virtual Console Setup...861machine # [ 14.033030] systemd-logind[505]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)862machine # [ 14.440463] systemd-vconsole-setup[719]: Configuration of first virtual console was skipped, ignoring remaining ones.863machine # [ 14.445973] systemd[1]: Finished Virtual Console Setup.864machine # [ 15.817967] dhcpcd[672]: eth0: soliciting an IPv6 router865machine # [ 15.821376] dhcpcd[672]: eth0: Router Advertisement from fe80::2866machine # [ 15.823935] dhcpcd[672]: eth0: adding address fec0::5054:ff:fe12:3456/64867machine # [ 15.827000] dhcpcd[672]: eth0: adding route to fec0::/64868machine # [ 15.829339] dhcpcd[672]: eth0: adding default route via fe80::2869machine # [ 18.538683] dhcpcd[672]: eth0: leased 10.0.2.15 for 86400 seconds870machine # [ 18.538886] dhcpcd[672]: eth0: adding route to 10.0.2.0/24871machine # [ 18.538957] dhcpcd[672]: eth0: adding default route via 10.0.2.2872machine # [ 18.659882] systemd[1]: Started DHCP Client.873machine # [ 18.662056] systemd[1]: Reached target Multi-User System.874machine # [ 18.662840] systemd[1]: Startup finished in 919ms (kernel) + 5.221s (initrd) + 12.520s (userspace) = 18.661s.875machine: (finished: waiting for unit multi-user.target, in 20.37 seconds)876machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test877machine # Checking switch inhibitors... done878machine # [ 20.195127] nixos[832]: switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test879machine # activating the configuration...880machine # [ 20.199277] systemd[1]: Stopped target Local File Systems.881machine # [ 20.200391] systemd[1]: Stopped target Remote File Systems.882machine # setting up /etc...883machine # [ 20.641511] systemd[1]: Reload requested from client PID 832 ('.switch-to-conf') (unit backdoor.service)...884machine # [ 20.643350] systemd[1]: Reloading...885machine # [ 21.206052] systemd[1]: Reloading finished in 559 ms.886machine # reloading user units for usertest...887machine # [ 21.253778] systemd[589]: Created slice User Core Session Slice.888machine # [ 21.257132] systemd[589]: Starting D-Bus User Message Bus...889machine # [ 21.317195] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.import1.service'890machine # [ 21.320039] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.machine1.service'891machine # [ 21.323143] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.portable1.service'892machine # [ 21.326558] dbus-broker-launch[902]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'893machine # [ 21.330170] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored894machine # [ 21.332691] dbus-broker-launch[902]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored895machine # [ 21.334928] systemd[589]: Started D-Bus User Message Bus.896machine # [ 21.340798] dbus-broker-launch[902]: Ready897machine # [ 21.446038] systemd[589]: Reexecution requested from client PID 901 ('switch-to-confi')...898machine # [ 21.447313] systemd[589]: Reexecuting.899machine # [ 21.509974] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy900machine # [ 21.697504] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy901machine # [ 21.703995] systemd[589]: Reload requested from client PID 901 ('switch-to-confi')...902machine # [ 21.706452] systemd[589]: Reloading...903machine # [ 21.889880] systemd[589]: Reloading finished in 180 ms.904machine # [ 21.904147] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy905machine # restarting the following user units: nixos-activation.service906machine # [ 21.914156] systemd[589]: Stopped Run user-specific NixOS activation.907machine # [ 21.916346] systemd[589]: Stopping Run user-specific NixOS activation...908machine # [ 21.919076] systemd[589]: Starting Run user-specific NixOS activation...909machine # [ 21.962754] systemd[589]: Finished Run user-specific NixOS activation.910machine # restarting sysinit-reactivation.target911machine # [ 21.978429] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....912machine # [ 22.083537] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..913machine # [ 22.085651] systemd[1]: Reached target Reactivate sysinit units.914machine # [ 22.088885] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os915machine # [ 22.091104] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki916machine # [ 22.096001] systemd[1]: Reached target Local File Systems.917machine # [ 22.097046] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore918machine # [ 22.101075] systemd[1]: Starting Create SUID/SGID Wrappers...919machine # [ 22.101899] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.920machine # [ 22.103079] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67921machine # [ 22.107355] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore922machine # [ 22.116364] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os923machine # [ 22.117602] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os924machine # [ 22.118783] systemd[1]: Reached target Remote File Systems.925machine # [ 22.416606] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.926machine # [ 22.420176] systemd[1]: Finished Create SUID/SGID Wrappers.927machine # [ 22.422848] systemd[1]: Starting linger-users.service...928machine # [ 22.480612] systemd[1]: linger-users.service: Deactivated successfully.929machine # [ 22.482547] systemd[1]: Finished linger-users.service.930machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service931machine # [ 22.743948] nixos[832]: finished switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test932machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test, in 3.14 seconds)933machine: must succeed: rm /etc/NIXOS934machine: (finished: must succeed: rm /etc/NIXOS, in 0.07 seconds)935machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test936machine # this should succeed (config: /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test, action: test)937machine # Checking switch inhibitors... done938machine # [ 23.459940] nixos[979]: switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test939machine # [ 23.462700] systemd[1]: Stopped target Remote File Systems.940machine # activating the configuration...941machine # [ 23.465353] systemd[1]: Stopped target Local File Systems.942machine # removing user ‘usertest’943machine # setting up /etc...944machine # [ 23.960219] systemd[1]: Reload requested from client PID 979 ('.switch-to-conf') (unit backdoor.service)...945machine # [ 23.962313] systemd[1]: Reloading...946machine # [ 24.718577] systemd[1]: Reloading finished in 753 ms.947machine # reloading user units for usertest...948machine # [ 24.965701] systemd[589]: Reexecution requested from client PID 1051 ('switch-to-confi')...949machine # [ 24.969978] systemd[589]: Reexecuting.950machine # [ 25.026864] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 25.291700] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy952machine # [ 25.304875] systemd[589]: Reload requested from client PID 1051 ('switch-to-confi')...953machine # [ 25.307255] systemd[589]: Reloading...954machine # [ 25.557584] systemd[589]: Reloading finished in 247 ms.955machine # [ 25.576973] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy956machine # restarting the following user units: nixos-activation.service957machine # [ 25.598918] systemd[589]: Stopped Run user-specific NixOS activation.958machine # [ 25.599989] systemd[589]: Stopping Run user-specific NixOS activation...959machine # [ 25.603607] systemd[589]: Starting Run user-specific NixOS activation...960machine # [ 25.664437] systemd[589]: Finished Run user-specific NixOS activation.961machine # restarting sysinit-reactivation.target962machine # [ 25.674664] systemd[1]: Stopped target Reactivate sysinit units.963machine # reloading the following units: dbus-broker.service964machine # [ 25.681446] systemd[1]: Stopping Reactivate sysinit units...965machine # [ 25.682881] systemd[1]: Reached target Reactivate sysinit units.966machine # [ 25.686679] systemd[1]: Reloading D-Bus System Message Bus...967machine # [ 25.690995] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.968machine # [ 25.719066] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...969machine # [ 25.721718] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'970machine # [ 25.723030] dbus-broker-launch[489]: Invalid user-name in /nix/store/2d37a0ag8593x6bsc14irprkj2vpg80c-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"971machine # [ 25.770952] systemd[1]: Reloaded D-Bus System Message Bus.972machine # restarting the following units: network-addresses-eth1.service973machine # [ 25.784978] systemd[1]: Stopping Address configuration of eth1...974machine # [ 25.850570] network-addresses-eth1-pre-stop[1063]: deleting address 192.168.1.1/24... done975machine # [ 25.863130] network-addresses-eth1-pre-stop[1063]: deleting address 2001:db8:1::1/64... done976machine # [ 25.874407] systemd[1]: network-addresses-eth1.service: Deactivated successfully.977machine # [ 25.879395] systemd[1]: Stopped Address configuration of eth1.978machine # [ 25.882817] systemd[1]: Starting Address configuration of eth1...979machine # [ 25.964635] network-addresses-eth1-start[1068]: adding address 192.168.1.2/24... done980machine # [ 25.979535] network-addresses-eth1-start[1068]: adding address 2001:db8:1::2/64... done981machine # [ 25.996600] systemd[1]: Finished Address configuration of eth1.982machine # [ 25.999881] systemd[1]: Reached target Local File Systems.983machine # [ 26.005713] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os984machine # [ 26.010197] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki985machine # [ 26.011656] systemd[1]: Reached target Remote File Systems.986machine # [ 26.015997] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore987machine # [ 26.027072] systemd[1]: Starting Create SUID/SGID Wrappers...988machine # [ 26.031246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.989machine # [ 26.035312] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67990machine # [ 26.042916] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore991machine # [ 26.044812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os992machine # [ 26.046049] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os993machine # [ 26.523515] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.994machine # [ 26.527206] systemd[1]: Finished Create SUID/SGID Wrappers.995machine # [ 26.531605] systemd[1]: Starting linger-users.service...996machine # [ 26.581880] linger-users-start[1141]: id: ‘usertest’: no such user997machine # [ 26.584645] linger-users-start[1139]: Removing linger for missing user usertest998machine # [ 26.594649] systemd[1]: linger-users.service: Deactivated successfully.999machine # [ 26.597646] systemd[1]: Finished linger-users.service.1000machine # [ 26.857285] nixos[979]: finished switching to system configuration /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test1001machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/fkrnrx1kb4pvplbfnsgm5jx77m1hvwvw-nixos-system-other-test/bin/switch-to-configuration test, in 4.04 seconds)1002machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&11003machine # [ 27.531391] nixos[1147]: switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test1004machine # [ 27.535003] systemd[1]: Stopped target Local File Systems.1005machine # [ 27.536504] systemd[1]: Stopped target Remote File Systems.1006machine # [ 28.012894] systemd[1]: Reload requested from client PID 1147 ('.switch-to-conf') (unit backdoor.service)...1007machine # [ 28.014899] systemd[1]: Reloading...1008machine # [ 28.650151] systemd[1]: Reloading finished in 632 ms.1009machine # [ 28.796655] systemd[589]: Reexecution requested from client PID 1216 ('switch-to-confi')...1010machine # [ 28.801204] systemd[589]: Reexecuting.1011machine # [ 28.845426] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 29.034224] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1013machine # [ 29.040469] systemd[589]: Reload requested from client PID 1216 ('switch-to-confi')...1014machine # [ 29.042937] systemd[589]: Reloading...1015machine # [ 29.225689] systemd[589]: Reloading finished in 181 ms.1016machine # [ 29.240145] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1017machine # [ 29.249731] systemd[589]: Stopped Run user-specific NixOS activation.1018machine # [ 29.252068] systemd[589]: Stopping Run user-specific NixOS activation...1019machine # [ 29.254859] systemd[589]: Starting Run user-specific NixOS activation...1020machine # [ 29.299705] systemd[589]: Finished Run user-specific NixOS activation.1021machine # [ 29.303883] systemd[1]: Stopped target Reactivate sysinit units.1022machine # [ 29.305571] systemd[1]: Stopping Reactivate sysinit units...1023machine # [ 29.306386] systemd[1]: Reached target Reactivate sysinit units.1024machine # [ 29.309234] systemd[1]: Reloading D-Bus System Message Bus...1025machine # [ 29.311273] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.1026machine # [ 29.324894] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...1027machine # [ 29.326982] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'1028machine # [ 29.328143] dbus-broker-launch[489]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1029machine # [ 29.353247] systemd[1]: Reloaded D-Bus System Message Bus.1030machine # [ 29.359555] systemd[1]: Stopping Address configuration of eth1...1031machine # [ 29.406387] network-addresses-eth1-pre-stop[1228]: deleting address 192.168.1.2/24... done1032machine # [ 29.423298] network-addresses-eth1-pre-stop[1228]: deleting address 2001:db8:1::2/64... done1033machine # [ 29.435473] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1034machine # [ 29.439527] systemd[1]: Stopped Address configuration of eth1.1035machine # [ 29.442056] systemd[1]: Starting Address configuration of eth1...1036machine # [ 29.526378] network-addresses-eth1-start[1233]: adding address 192.168.1.1/24... done1037machine # [ 29.538162] network-addresses-eth1-start[1233]: adding address 2001:db8:1::1/64... done1038machine # [ 29.554034] systemd[1]: Finished Address configuration of eth1.1039machine # [ 29.557921] systemd[1]: Reached target Local File Systems.1040machine # [ 29.560937] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1041machine # [ 29.566444] systemd[1]: Starting Create SUID/SGID Wrappers...1042machine # [ 29.567348] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1043machine # [ 29.570447] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671044machine # [ 29.576168] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1045machine # [ 29.584536] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1046machine # [ 29.586265] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1047machine # [ 29.587422] systemd[1]: Reached target Remote File Systems.1048machine # [ 29.589426] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1049machine # [ 29.590739] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1050machine # [ 29.951891] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1051machine # [ 29.956583] systemd[1]: Finished Create SUID/SGID Wrappers.1052machine # [ 29.959472] systemd[1]: Starting linger-users.service...1053machine # [ 30.014689] systemd[1]: linger-users.service: Deactivated successfully.1054machine # [ 30.018150] systemd[1]: Finished linger-users.service.1055machine # [ 30.275513] nixos[1147]: finished switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test1056machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.42 seconds)1057subtest: socket-activated services1058machine: must fail: [ -S /run/test.sock ]1059machine: (finished: must fail: [ -S /run/test.sock ], in 0.04 seconds)1060machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11061machine # [ 31.088520] nixos[1306]: switching to system configuration /nix/store/8cc6k60gpdvyzkxmn35phjz384pfgk56-nixos-system-machine-test1062machine # [ 31.091184] systemd[1]: Stopped target Local File Systems.1063machine # [ 31.093602] systemd[1]: Stopped target Remote File Systems.1064machine # [ 31.650920] systemd[1]: Reload requested from client PID 1306 ('.switch-to-conf') (unit backdoor.service)...1065machine # [ 31.653163] systemd[1]: Reloading...1066machine # [ 32.325811] systemd[1]: Reloading finished in 670 ms.1067machine # [ 32.481964] systemd[589]: Reexecution requested from client PID 1375 ('switch-to-confi')...1068machine # [ 32.485792] systemd[589]: Reexecuting.1069machine # [ 32.544174] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1070machine # [ 32.768728] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1071machine # [ 32.779716] systemd[589]: Reload requested from client PID 1375 ('switch-to-confi')...1072machine # [ 32.781759] systemd[589]: Reloading...1073machine # [ 33.011555] systemd[589]: Reloading finished in 228 ms.1074machine # [ 33.024215] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1075machine # [ 33.034569] systemd[589]: Stopped Run user-specific NixOS activation.1076machine # [ 33.037080] systemd[589]: Stopping Run user-specific NixOS activation...1077machine # [ 33.040078] systemd[589]: Starting Run user-specific NixOS activation...1078machine # [ 33.085903] systemd[589]: Finished Run user-specific NixOS activation.1079machine # [ 33.090157] systemd[1]: Stopped target Reactivate sysinit units.1080machine # [ 33.092806] systemd[1]: Stopping Reactivate sysinit units...1081machine # [ 33.093686] systemd[1]: Reached target Reactivate sysinit units.1082machine # [ 33.094513] systemd[1]: Reached target Local File Systems.1083machine # [ 33.097838] systemd[1]: Listening on socket-activated.socket.1084machine # [ 33.100809] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1085machine # [ 33.102121] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1086machine # [ 33.103384] systemd[1]: Reached target Remote File Systems.1087machine # [ 33.111880] systemd[1]: Starting linger-users.service...1088machine # [ 33.114765] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1089machine # [ 33.123288] systemd[1]: Starting Create SUID/SGID Wrappers...1090machine # [ 33.130174] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1091machine # [ 33.131474] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671092machine # [ 33.140216] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1093machine # [ 33.141841] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1094machine # [ 33.143065] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1095machine # [ 33.205294] systemd[1]: linger-users.service: Deactivated successfully.1096machine # [ 33.208106] systemd[1]: Finished linger-users.service.1097machine # [ 33.472454] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1098machine # [ 33.475562] systemd[1]: Finished Create SUID/SGID Wrappers.1099machine # [ 33.734001] nixos[1306]: finished switching to system configuration /nix/store/8cc6k60gpdvyzkxmn35phjz384pfgk56-nixos-system-machine-test1100machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 3.41 seconds)1101machine: must succeed: [ -S /run/test.sock ]1102machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1103machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11104machine # [ 34.376482] nixos[1450]: switching to system configuration /nix/store/wb9gx8d2jw5xc6z1dq2xgdhcchqb8gsb-nixos-system-machine-test1105machine # [ 34.379567] systemd[1]: Stopped target Local File Systems.1106machine # [ 34.380871] systemd[1]: Stopped target Remote File Systems.1107machine # [ 34.912481] systemd[1]: Reload requested from client PID 1450 ('.switch-to-conf') (unit backdoor.service)...1108machine # [ 34.914533] systemd[1]: Reloading...1109machine # [ 35.597448] systemd[1]: Reloading finished in 680 ms.1110machine # [ 35.740404] systemd[589]: Reexecution requested from client PID 1519 ('switch-to-confi')...1111machine # [ 35.743916] systemd[589]: Reexecuting.1112machine # [ 35.805753] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1113machine # [ 35.994325] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1114machine # [ 36.000841] systemd[589]: Reload requested from client PID 1519 ('switch-to-confi')...1115machine # [ 36.003007] systemd[589]: Reloading...1116machine # [ 36.216980] systemd[589]: Reloading finished in 212 ms.1117machine # [ 36.232133] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1118machine # [ 36.241772] systemd[589]: Stopped Run user-specific NixOS activation.1119machine # [ 36.243937] systemd[589]: Stopping Run user-specific NixOS activation...1120machine # [ 36.246705] systemd[589]: Starting Run user-specific NixOS activation...1121machine # [ 36.291730] systemd[589]: Finished Run user-specific NixOS activation.1122machine # [ 36.295968] systemd[1]: Stopped target Reactivate sysinit units.1123machine # [ 36.297867] systemd[1]: Stopping Reactivate sysinit units...1124machine # [ 36.299022] systemd[1]: Reached target Reactivate sysinit units.1125machine # [ 36.302158] systemd[1]: Reached target Local File Systems.1126machine # [ 36.304602] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1127machine # [ 36.309675] systemd[1]: Starting Create SUID/SGID Wrappers...1128machine # [ 36.310507] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1129machine # [ 36.311727] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671130machine # [ 36.315386] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1131machine # [ 36.327280] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 36.329929] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1133machine # [ 36.332209] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1134machine # [ 36.333518] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1135machine # [ 36.334740] systemd[1]: Reached target Remote File Systems.1136machine # [ 36.633267] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1137machine # [ 36.635452] systemd[1]: Finished Create SUID/SGID Wrappers.1138machine # [ 36.640568] systemd[1]: Starting linger-users.service...1139machine # [ 36.697312] systemd[1]: linger-users.service: Deactivated successfully.1140machine # [ 36.699698] systemd[1]: Finished linger-users.service.1141machine # [ 36.958798] nixos[1450]: finished switching to system configuration /nix/store/wb9gx8d2jw5xc6z1dq2xgdhcchqb8gsb-nixos-system-machine-test1142machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1143machine: must succeed: [ -S /run/test.sock ]1144machine: (finished: must succeed: [ -S /run/test.sock ], in 0.04 seconds)1145machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1146machine # [ 37.069340] systemd[1]: Started A socket-activated service.1147machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.79 seconds)1148machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11149machine # [ 38.360078] nixos[1598]: switching to system configuration /nix/store/8cc6k60gpdvyzkxmn35phjz384pfgk56-nixos-system-machine-test1150machine # [ 38.363255] systemd[1]: Stopped target Local File Systems.1151machine # [ 38.364434] systemd[1]: Stopped target Remote File Systems.1152machine # [ 38.793870] systemd[1]: Reload requested from client PID 1598 ('.switch-to-conf') (unit backdoor.service)...1153machine # [ 38.795678] systemd[1]: Reloading...1154machine # [ 39.370383] systemd[1]: Reloading finished in 572 ms.1155machine # [ 39.543653] systemd[589]: Reexecution requested from client PID 1668 ('switch-to-confi')...1156machine # [ 39.547452] systemd[589]: Reexecuting.1157machine # [ 39.605413] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1158machine # [ 39.788730] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1159machine # [ 39.795313] systemd[589]: Reload requested from client PID 1668 ('switch-to-confi')...1160machine # [ 39.797102] systemd[589]: Reloading...1161machine # [ 39.980038] systemd[589]: Reloading finished in 182 ms.1162machine # [ 39.996138] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1163machine # [ 40.005682] systemd[589]: Stopped Run user-specific NixOS activation.1164machine # [ 40.006650] systemd[589]: Stopping Run user-specific NixOS activation...1165machine # [ 40.009299] systemd[589]: Starting Run user-specific NixOS activation...1166machine # [ 40.050619] systemd[589]: Finished Run user-specific NixOS activation.1167machine # [ 40.054239] systemd[1]: Stopped target Reactivate sysinit units.1168machine # [ 40.058981] systemd[1]: Stopping Reactivate sysinit units...1169machine # [ 40.059868] systemd[1]: Reached target Reactivate sysinit units.1170machine # [ 40.061791] systemd[1]: Stopping A socket-activated service...1171machine # [ 40.063569] systemd[1]: socket-activated.service: Deactivated successfully.1172machine # [ 40.065540] systemd[1]: Stopped A socket-activated service.1173machine # [ 40.070200] systemd[1]: Started A socket-activated service.1174machine # [ 40.073599] systemd[1]: Reached target Local File Systems.1175machine # [ 40.081219] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1176machine # [ 40.088499] systemd[1]: Starting Create SUID/SGID Wrappers...1177machine # [ 40.089406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1178machine # [ 40.090594] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671179machine # [ 40.101454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1180machine # [ 40.103316] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 40.108329] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1182machine # [ 40.109510] systemd[1]: Reached target Remote File Systems.1183machine # [ 40.110283] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1184machine # [ 40.111511] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1185machine # [ 40.442302] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1186machine # [ 40.446440] systemd[1]: Finished Create SUID/SGID Wrappers.1187machine # [ 40.448655] systemd[1]: Starting linger-users.service...1188machine # [ 40.513740] systemd[1]: linger-users.service: Deactivated successfully.1189machine # [ 40.517335] systemd[1]: Finished linger-users.service.1190machine # [ 40.775228] nixos[1598]: finished switching to system configuration /nix/store/8cc6k60gpdvyzkxmn35phjz384pfgk56-nixos-system-machine-test1191machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 2.99 seconds)1192machine: must succeed: [ -S /run/test.sock ]1193machine: (finished: must succeed: [ -S /run/test.sock ], in 0.04 seconds)1194machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1195machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.54 seconds)1196machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11197machine # [ 41.982700] nixos[1748]: switching to system configuration /nix/store/3jykkwp8avbhk2gdzxsc71xrci93n6s3-nixos-system-machine-test1198machine # [ 41.987179] systemd[1]: Stopped target Local File Systems.1199machine # [ 41.991982] systemd[1]: Stopped target Remote File Systems.1200machine # [ 41.993035] systemd[1]: Stopping A socket-activated service...1201machine # [ 41.995099] systemd[1]: socket-activated.service: Deactivated successfully.1202machine # [ 41.996924] systemd[1]: Stopped A socket-activated service.1203machine # [ 41.998819] systemd[1]: socket-activated.socket: Deactivated successfully.1204machine # [ 41.999794] systemd[1]: Closed socket-activated.socket.1205machine # [ 42.488737] systemd[1]: Reload requested from client PID 1748 ('.switch-to-conf') (unit backdoor.service)...1206machine # [ 42.490676] systemd[1]: Reloading...1207machine # [ 43.141855] systemd[1]: Reloading finished in 648 ms.1208machine # [ 43.300376] systemd[589]: Reexecution requested from client PID 1818 ('switch-to-confi')...1209machine # [ 43.301695] systemd[589]: Reexecuting.1210machine # [ 43.365594] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1211machine # [ 43.563941] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1212machine # [ 43.570358] systemd[589]: Reload requested from client PID 1818 ('switch-to-confi')...1213machine # [ 43.572513] systemd[589]: Reloading...1214machine # [ 43.760688] systemd[589]: Reloading finished in 186 ms.1215machine # [ 43.776138] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1216machine # [ 43.785639] systemd[589]: Stopped Run user-specific NixOS activation.1217machine # [ 43.786583] systemd[589]: Stopping Run user-specific NixOS activation...1218machine # [ 43.789354] systemd[589]: Starting Run user-specific NixOS activation...1219machine # [ 43.837156] systemd[589]: Finished Run user-specific NixOS activation.1220machine # [ 43.840974] systemd[1]: Stopped target Reactivate sysinit units.1221machine # [ 43.843814] systemd[1]: Stopping Reactivate sysinit units...1222machine # [ 43.845043] systemd[1]: Reached target Reactivate sysinit units.1223machine # [ 43.847211] systemd[1]: Reached target Local File Systems.1224machine # [ 43.849633] systemd[1]: Listening on socket-activated.socket.1225machine # [ 43.852242] systemd[1]: Reached target Remote File Systems.1226machine # [ 43.858193] systemd[1]: Starting linger-users.service...1227machine # [ 43.866579] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1228machine # [ 43.871682] systemd[1]: Starting Create SUID/SGID Wrappers...1229machine # [ 43.874285] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1230machine # [ 43.875499] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671231machine # [ 43.885173] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1232machine # [ 43.887721] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1233machine # [ 43.890322] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1234machine # [ 43.892534] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1235machine # [ 43.894159] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1236machine # [ 43.951399] systemd[1]: linger-users.service: Deactivated successfully.1237machine # [ 43.954574] systemd[1]: Finished linger-users.service.1238machine # [ 44.218624] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1239machine # [ 44.221982] systemd[1]: Finished Create SUID/SGID Wrappers.1240machine # [ 44.483587] nixos[1748]: finished switching to system configuration /nix/store/3jykkwp8avbhk2gdzxsc71xrci93n6s3-nixos-system-machine-test1241machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 3.13 seconds)1242machine: must succeed: [ -S /run/test.sock ]1243machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1244machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1245machine # [ 44.575672] systemd[1]: Started A socket-activated service.1246machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.68 seconds)1247machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11248machine # [ 46.013260] nixos[1897]: switching to system configuration /nix/store/16czpz180bcr9872gyc6hc0ypzw9lybj-nixos-system-machine-test1249machine # [ 46.016933] systemd[1]: Stopped target Local File Systems.1250machine # [ 46.018390] systemd[1]: Stopped target Remote File Systems.1251machine # [ 46.653622] systemd[1]: Reload requested from client PID 1897 ('.switch-to-conf') (unit backdoor.service)...1252machine # [ 46.655591] systemd[1]: Reloading...1253machine # [ 47.294539] systemd[1]: Reloading finished in 636 ms.1254machine # [ 47.440412] systemd[589]: Reexecution requested from client PID 1968 ('switch-to-confi')...1255machine # [ 47.443700] systemd[589]: Reexecuting.1256machine # [ 47.493274] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 47.669028] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1258machine # [ 47.675545] systemd[589]: Reload requested from client PID 1968 ('switch-to-confi')...1259machine # [ 47.679025] systemd[589]: Reloading...1260machine # [ 47.868851] systemd[589]: Reloading finished in 190 ms.1261machine # [ 47.884148] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1262machine # [ 47.893123] systemd[589]: Stopped Run user-specific NixOS activation.1263machine # [ 47.895508] systemd[589]: Stopping Run user-specific NixOS activation...1264machine # [ 47.898468] systemd[589]: Starting Run user-specific NixOS activation...1265machine # [ 47.945138] systemd[589]: Finished Run user-specific NixOS activation.1266machine # [ 47.949413] systemd[1]: Stopped target Reactivate sysinit units.1267machine # [ 47.952525] systemd[1]: Stopping Reactivate sysinit units...1268machine # [ 47.953464] systemd[1]: Reached target Reactivate sysinit units.1269machine # [ 47.956293] systemd[1]: Reloading A socket-activated service...1270machine # [ 47.990283] systemd[1]: Reloaded A socket-activated service.1271machine # [ 47.994605] systemd[1]: Reached target Remote File Systems.1272machine # [ 47.997884] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1273machine # [ 48.001504] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1274machine # [ 48.003189] systemd[1]: Reached target Local File Systems.1275machine # [ 48.007246] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1276machine # [ 48.013810] systemd[1]: Starting Create SUID/SGID Wrappers...1277machine # [ 48.014696] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1278machine # [ 48.015908] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671279machine # [ 48.021491] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1280machine # [ 48.026894] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1281machine # [ 48.028513] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1282machine # [ 48.348964] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1283machine # [ 48.352286] systemd[1]: Finished Create SUID/SGID Wrappers.1284machine # [ 48.356251] systemd[1]: Starting linger-users.service...1285machine # [ 48.415650] systemd[1]: linger-users.service: Deactivated successfully.1286machine # [ 48.417772] systemd[1]: Finished linger-users.service.1287machine # [ 48.676637] nixos[1897]: finished switching to system configuration /nix/store/16czpz180bcr9872gyc6hc0ypzw9lybj-nixos-system-machine-test1288machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 3.48 seconds)1289machine: must succeed: [ -S /run/test.sock ]1290machine: (finished: must succeed: [ -S /run/test.sock ], in 0.04 seconds)1291machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1292machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.55 seconds)1293machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11294machine # [ 49.889322] nixos[2047]: switching to system configuration /nix/store/mg4myr7ackxqafx1bbvzg4lvf56fb9zf-nixos-system-machine-test1295machine # [ 49.892321] systemd[1]: socket-activated.socket: Deactivated successfully.1296machine # [ 49.894158] systemd[1]: Closed socket-activated.socket.1297machine # [ 49.895621] systemd[1]: Stopped target Remote File Systems.1298machine # [ 49.897613] systemd[1]: Stopped target Local File Systems.1299machine # [ 50.386041] systemd[1]: Reload requested from client PID 2047 ('.switch-to-conf') (unit backdoor.service)...1300machine # [ 50.387984] systemd[1]: Reloading...1301machine # [ 51.028954] systemd[1]: Reloading finished in 638 ms.1302machine # [ 51.173993] systemd[589]: Reexecution requested from client PID 2117 ('switch-to-confi')...1303machine # [ 51.175395] systemd[589]: Reexecuting.1304machine # [ 51.237570] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1305machine # [ 51.425643] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1306machine # [ 51.431762] systemd[589]: Reload requested from client PID 2117 ('switch-to-confi')...1307machine # [ 51.434386] systemd[589]: Reloading...1308machine # [ 51.619581] systemd[589]: Reloading finished in 184 ms.1309machine # [ 51.632144] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1310machine # [ 51.640835] systemd[589]: Stopped Run user-specific NixOS activation.1311machine # [ 51.643093] systemd[589]: Stopping Run user-specific NixOS activation...1312machine # [ 51.645869] systemd[589]: Starting Run user-specific NixOS activation...1313machine # [ 51.690816] systemd[589]: Finished Run user-specific NixOS activation.1314machine # [ 51.694817] systemd[1]: Stopped target Reactivate sysinit units.1315machine # [ 51.695771] systemd[1]: Stopping Reactivate sysinit units...1316machine # [ 51.697145] systemd[1]: Reached target Reactivate sysinit units.1317machine # [ 51.702701] systemd[1]: Stopping A socket-activated service...1318machine # [ 51.705531] systemd[1]: socket-activated.service: Deactivated successfully.1319machine # [ 51.708412] systemd[1]: Stopped A socket-activated service.1320machine # [ 51.712329] systemd[1]: Started A socket-activated service.1321machine # [ 51.716474] systemd[1]: Reached target Local File Systems.1322machine # [ 51.718111] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1323machine # [ 51.721783] systemd[1]: Starting Create SUID/SGID Wrappers...1324machine # [ 51.722669] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1325machine # [ 51.723868] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671326machine # [ 51.739866] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1327machine # [ 51.745113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1328machine # [ 51.747712] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1329machine # [ 51.752423] systemd[1]: Reached target Remote File Systems.1330machine # [ 51.754230] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1331machine # [ 51.756790] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1332machine # [ 52.093262] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1333machine # [ 52.097741] systemd[1]: Finished Create SUID/SGID Wrappers.1334machine # [ 52.100585] systemd[1]: Starting linger-users.service...1335machine # [ 52.158808] systemd[1]: linger-users.service: Deactivated successfully.1336machine # [ 52.159803] systemd[1]: Finished linger-users.service.1337machine # [ 52.418526] nixos[2047]: finished switching to system configuration /nix/store/mg4myr7ackxqafx1bbvzg4lvf56fb9zf-nixos-system-machine-test1338machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1339machine: must succeed: systemctl is-active socket-activated.service1340machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.05 seconds)1341machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11342machine # [ 53.017229] nixos[2193]: switching to system configuration /nix/store/3jykkwp8avbhk2gdzxsc71xrci93n6s3-nixos-system-machine-test1343machine # [ 53.019863] systemd[1]: Stopped target Remote File Systems.1344machine # [ 53.023746] systemd[1]: Stopping A socket-activated service...1345machine # [ 53.026898] systemd[1]: Stopped target Local File Systems.1346machine # [ 53.029479] systemd[1]: socket-activated.service: Deactivated successfully.1347machine # [ 53.031549] systemd[1]: Stopped A socket-activated service.1348machine # [ 53.454063] systemd[1]: Reload requested from client PID 2193 ('.switch-to-conf') (unit backdoor.service)...1349machine # [ 53.455961] systemd[1]: Reloading...1350machine # [ 54.032927] systemd[1]: Reloading finished in 574 ms.1351machine # [ 54.179168] systemd[589]: Reexecution requested from client PID 2263 ('switch-to-confi')...1352machine # [ 54.181431] systemd[589]: Reexecuting.1353machine # [ 54.246046] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1354machine # [ 54.438419] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1355machine # [ 54.445479] systemd[589]: Reload requested from client PID 2263 ('switch-to-confi')...1356machine # [ 54.447695] systemd[589]: Reloading...1357machine # [ 54.655829] systemd[589]: Reloading finished in 206 ms.1358machine # [ 54.668164] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1359machine # [ 54.678483] systemd[589]: Stopped Run user-specific NixOS activation.1360machine # [ 54.679535] systemd[589]: Stopping Run user-specific NixOS activation...1361machine # [ 54.682705] systemd[589]: Starting Run user-specific NixOS activation...1362machine # [ 54.730406] systemd[589]: Finished Run user-specific NixOS activation.1363machine # [ 54.734948] systemd[1]: Stopped target Reactivate sysinit units.1364machine # [ 54.736955] systemd[1]: Stopping Reactivate sysinit units...1365machine # [ 54.738367] systemd[1]: Reached target Reactivate sysinit units.1366machine # [ 54.742307] systemd[1]: Reached target Remote File Systems.1367machine # [ 54.749995] systemd[1]: Starting linger-users.service...1368machine # [ 54.753833] systemd[1]: Listening on socket-activated.socket.1369machine # [ 54.755773] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1370machine # [ 54.763746] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1371machine # [ 54.767276] systemd[1]: Reached target Local File Systems.1372machine # [ 54.768787] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1373machine # [ 54.777551] systemd[1]: Starting Create SUID/SGID Wrappers...1374machine # [ 54.780676] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1375machine # [ 54.783743] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671376machine # [ 54.788939] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1377machine # [ 54.790895] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1378machine # [ 54.793631] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1379machine # [ 54.850224] systemd[1]: linger-users.service: Deactivated successfully.1380machine # [ 54.853780] systemd[1]: Finished linger-users.service.1381machine # [ 55.118539] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1382machine # [ 55.121909] systemd[1]: Finished Create SUID/SGID Wrappers.1383machine # [ 55.377671] nixos[2193]: finished switching to system configuration /nix/store/3jykkwp8avbhk2gdzxsc71xrci93n6s3-nixos-system-machine-test1384machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 2.90 seconds)1385machine: must succeed: [ -S /run/test.sock ]1386machine: (finished: must succeed: [ -S /run/test.sock ], in 0.04 seconds)1387machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1388machine # [ 55.468294] systemd[1]: Started A socket-activated service.1389machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.68 seconds)1390(finished: subtest: socket-activated services, in 25.82 seconds)1391subtest: socket-activated services with Accept=yes1392machine: must fail: [ -S /run/accept-test.sock ]1393machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.04 seconds)1394machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11395machine # [ 56.748554] nixos[2344]: switching to system configuration /nix/store/2shdbjcgfwrjw7kjjmi3qqxzwqp09hr4-nixos-system-machine-test1396machine # [ 56.751326] systemd[1]: Stopped target Local File Systems.1397machine # [ 56.753549] systemd[1]: Stopped target Remote File Systems.1398machine # [ 56.755144] systemd[1]: socket-activated.socket: Deactivated successfully.1399machine # [ 56.759466] systemd[1]: Closed socket-activated.socket.1400machine # [ 56.760931] systemd[1]: Stopping A socket-activated service...1401machine # [ 56.763657] systemd[1]: socket-activated.service: Deactivated successfully.1402machine # [ 56.766072] systemd[1]: Stopped A socket-activated service.1403machine # [ 57.265574] systemd[1]: Reload requested from client PID 2344 ('.switch-to-conf') (unit backdoor.service)...1404machine # [ 57.267460] systemd[1]: Reloading...1405machine # [ 57.918782] systemd[1]: Reloading finished in 648 ms.1406machine # [ 58.062248] systemd[589]: Reexecution requested from client PID 2413 ('switch-to-confi')...1407machine # [ 58.065878] systemd[589]: Reexecuting.1408machine # [ 58.121342] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1409machine # [ 58.304984] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1410machine # [ 58.311711] systemd[589]: Reload requested from client PID 2413 ('switch-to-confi')...1411machine # [ 58.315569] systemd[589]: Reloading...1412machine # [ 58.499324] systemd[589]: Reloading finished in 184 ms.1413machine # [ 58.512150] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1414machine # [ 58.520918] systemd[589]: Stopped Run user-specific NixOS activation.1415machine # [ 58.523210] systemd[589]: Stopping Run user-specific NixOS activation...1416machine # [ 58.526067] systemd[589]: Starting Run user-specific NixOS activation...1417machine # [ 58.573502] systemd[589]: Finished Run user-specific NixOS activation.1418machine # [ 58.577490] systemd[1]: Stopped target Reactivate sysinit units.1419machine # [ 58.579354] systemd[1]: Stopping Reactivate sysinit units...1420machine # [ 58.580963] systemd[1]: Reached target Reactivate sysinit units.1421machine # [ 58.584503] systemd[1]: Reached target Local File Systems.1422machine # [ 58.585381] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1423machine # [ 58.591984] systemd[1]: Starting Create SUID/SGID Wrappers...1424machine # [ 58.593048] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1425machine # [ 58.594242] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671426machine # [ 58.605250] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1427machine # [ 58.606864] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1428machine # [ 58.609423] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1429machine # [ 58.611618] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1430machine # [ 58.615683] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1431machine # [ 58.617472] systemd[1]: Reached target Remote File Systems.1432machine # [ 58.903052] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1433machine # [ 58.905881] systemd[1]: Finished Create SUID/SGID Wrappers.1434machine # [ 58.910833] systemd[1]: Listening on accept-socket.socket.1435machine # [ 58.914294] systemd[1]: Starting linger-users.service...1436machine # [ 58.971142] systemd[1]: linger-users.service: Deactivated successfully.1437machine # [ 58.973895] systemd[1]: Finished linger-users.service.1438machine # [ 59.233278] nixos[2344]: finished switching to system configuration /nix/store/2shdbjcgfwrjw7kjjmi3qqxzwqp09hr4-nixos-system-machine-test1439machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 3.10 seconds)1440machine: must succeed: [ -S /run/accept-test.sock ]1441machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.04 seconds)1442machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1443machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.03 seconds)1444machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1445machine # [ 59.334988] systemd[1]: Created slice Slice /system/accept-socket.1446machine # [ 59.344204] systemd[1]: Started A per-connection socket-activated service (PID 2488/UID 0).1447machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.07 seconds)1448machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11449machine # [ 59.974463] nixos[2500]: switching to system configuration /nix/store/is0z7qqg4qgg65xwnf2ik9ryqfg07d4k-nixos-system-machine-test1450machine # [ 59.978748] systemd[1]: Stopped target Local File Systems.1451machine # [ 59.980299] systemd[1]: Stopped target Remote File Systems.1452machine # [ 59.981167] systemd[1]: Stopping A per-connection socket-activated service (PID 2488/UID 0)...1453machine # [ 59.985079] systemd[1]: accept-socket@0-1-2488_2489-0.service: Deactivated successfully.1454machine # [ 59.988363] systemd[1]: Stopped A per-connection socket-activated service (PID 2488/UID 0).1455machine # [ 59.991714] systemd[1]: accept-socket.socket: Deactivated successfully.1456machine # [ 59.993821] systemd[1]: Closed accept-socket.socket.1457machine # [ 60.499544] systemd[1]: Reload requested from client PID 2500 ('.switch-to-conf') (unit backdoor.service)...1458machine # [ 60.501717] systemd[1]: Reloading...1459machine # [ 61.142130] systemd[1]: Reloading finished in 638 ms.1460machine # [ 61.308086] systemd[589]: Reexecution requested from client PID 2571 ('switch-to-confi')...1461machine # [ 61.312532] systemd[589]: Reexecuting.1462machine # [ 61.385068] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1463machine # [ 61.614540] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1464machine # [ 61.621582] systemd[589]: Reload requested from client PID 2571 ('switch-to-confi')...1465machine # [ 61.624184] systemd[589]: Reloading...1466machine # [ 61.853117] systemd[589]: Reloading finished in 227 ms.1467machine # [ 61.868465] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1468machine # [ 61.881247] systemd[589]: Stopped Run user-specific NixOS activation.1469machine # [ 61.883709] systemd[589]: Stopping Run user-specific NixOS activation...1470machine # [ 61.886795] systemd[589]: Starting Run user-specific NixOS activation...1471machine # [ 61.936319] systemd[589]: Finished Run user-specific NixOS activation.1472machine # [ 61.940542] systemd[1]: Stopped target Reactivate sysinit units.1473machine # [ 61.942567] systemd[1]: Stopping Reactivate sysinit units...1474machine # [ 61.944630] systemd[1]: Reached target Reactivate sysinit units.1475machine # [ 61.949008] systemd[1]: Listening on accept-socket.socket.1476machine # [ 61.951255] systemd[1]: Reached target Local File Systems.1477machine # [ 61.955674] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1478machine # [ 61.960616] systemd[1]: Starting Create SUID/SGID Wrappers...1479machine # [ 61.961512] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1480machine # [ 61.962732] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671481machine # [ 61.967489] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1482machine # [ 61.978058] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1483machine # [ 61.982301] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1484machine # [ 61.983520] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1485machine # [ 61.984973] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1486machine # [ 61.986287] systemd[1]: Reached target Remote File Systems.1487machine # [ 62.313318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1488machine # [ 62.318121] systemd[1]: Finished Create SUID/SGID Wrappers.1489machine # [ 62.320881] systemd[1]: Starting linger-users.service...1490machine # [ 62.385667] systemd[1]: linger-users.service: Deactivated successfully.1491machine # [ 62.388310] systemd[1]: Finished linger-users.service.1492machine # [ 62.646479] nixos[2500]: finished switching to system configuration /nix/store/is0z7qqg4qgg65xwnf2ik9ryqfg07d4k-nixos-system-machine-test1493machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 3.27 seconds)1494machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1495machine # [ 62.722895] systemd[1]: Started A per-connection socket-activated service (PID 2642/UID 0).1496machine # [ 62.776960] systemd[1]: accept-socket@1-2-2642_2643-0.service: Deactivated successfully.1497machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.12 seconds)1498(finished: subtest: socket-activated services with Accept=yes, in 6.67 seconds)1499subtest: mounts1500machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11501machine # [ 63.404533] nixos[2649]: switching to system configuration /nix/store/7pmz9jrh1qfx4m5zgjadk5sjdnfmh3r7-nixos-system-machine-test1502machine # [ 63.409868] systemd[1]: Stopped target Local File Systems.1503machine # [ 63.410711] systemd[1]: Stopped target Remote File Systems.1504machine # [ 63.411485] systemd[1]: accept-socket.socket: Deactivated successfully.1505machine # [ 63.412651] systemd[1]: Closed accept-socket.socket.1506machine # [ 63.920143] systemd[1]: Reload requested from client PID 2649 ('.switch-to-conf') (unit backdoor.service)...1507machine # [ 63.922106] systemd[1]: Reloading...1508machine # [ 64.589725] systemd[1]: Reloading finished in 664 ms.1509machine # [ 64.732268] systemd[589]: Reexecution requested from client PID 2718 ('switch-to-confi')...1510machine # [ 64.736134] systemd[589]: Reexecuting.1511machine # [ 64.785550] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1512machine # [ 64.981385] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1513machine # [ 64.987981] systemd[589]: Reload requested from client PID 2718 ('switch-to-confi')...1514machine # [ 64.990148] systemd[589]: Reloading...1515machine # [ 65.182291] systemd[589]: Reloading finished in 190 ms.1516machine # [ 65.196144] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1517machine # [ 65.206648] systemd[589]: Stopped Run user-specific NixOS activation.1518machine # [ 65.207624] systemd[589]: Stopping Run user-specific NixOS activation...1519machine # [ 65.210449] systemd[589]: Starting Run user-specific NixOS activation...1520machine # [ 65.256933] systemd[589]: Finished Run user-specific NixOS activation.1521machine # [ 65.261194] systemd[1]: Stopped target Reactivate sysinit units.1522machine # [ 65.262128] systemd[1]: Stopping Reactivate sysinit units...1523machine # [ 65.263744] systemd[1]: Reached target Reactivate sysinit units.1524machine # [ 65.271642] systemd[1]: Mounting Testmount...1525machine # [ 65.273246] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1526machine # [ 65.278060] systemd[1]: Starting Create SUID/SGID Wrappers...1527machine # [ 65.278922] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671528machine # [ 65.281477] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1529machine # [ 65.293784] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1530machine # [ 65.295028] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1531machine # [ 65.299972] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1532machine # [ 65.303666] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1533machine # [ 65.305853] systemd[1]: Reached target Remote File Systems.1534machine # [ 65.335670] systemd[1]: Mounted Testmount.1535machine # [ 65.341836] systemd[1]: Reached target Local File Systems.1536machine # [ 65.342719] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1537machine # [ 65.640279] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1538machine # [ 65.644801] systemd[1]: Finished Create SUID/SGID Wrappers.1539machine # [ 65.647997] systemd[1]: Starting linger-users.service...1540machine # [ 65.707525] systemd[1]: linger-users.service: Deactivated successfully.1541machine # [ 65.709964] systemd[1]: Finished linger-users.service.1542machine # [ 65.970179] nixos[2649]: finished switching to system configuration /nix/store/7pmz9jrh1qfx4m5zgjadk5sjdnfmh3r7-nixos-system-machine-test1543machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 3.20 seconds)1544machine: must succeed: mount | grep 'on /testmount'1545machine: (finished: must succeed: mount | grep 'on /testmount', in 0.07 seconds)1546machine: must succeed: systemctl stop testmount.mount1547machine # [ 66.105246] systemd[1]: Unmounting Testmount...1548machine # [ 66.161463] systemd[1]: testmount.mount: Deactivated successfully.1549machine # [ 66.164551] systemd[1]: Unmounted Testmount.1550machine: (finished: must succeed: systemctl stop testmount.mount, in 0.13 seconds)1551machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11552machine # [ 66.724649] nixos[2802]: switching to system configuration /nix/store/7pmz9jrh1qfx4m5zgjadk5sjdnfmh3r7-nixos-system-machine-test1553machine # [ 66.728770] systemd[1]: Stopped target Remote File Systems.1554machine # [ 66.729936] systemd[1]: Stopped target Local File Systems.1555machine # [ 67.181886] systemd[1]: Reload requested from client PID 2802 ('.switch-to-conf') (unit backdoor.service)...1556machine # [ 67.183838] systemd[1]: Reloading...1557machine # [ 67.788758] systemd[1]: Reloading finished in 602 ms.1558machine # [ 67.933238] systemd[589]: Reexecution requested from client PID 2871 ('switch-to-confi')...1559machine # [ 67.936733] systemd[589]: Reexecuting.1560machine # [ 67.997783] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1561machine # [ 68.192835] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1562machine # [ 68.199284] systemd[589]: Reload requested from client PID 2871 ('switch-to-confi')...1563machine # [ 68.202767] systemd[589]: Reloading...1564machine # [ 68.389602] systemd[589]: Reloading finished in 187 ms.1565machine # [ 68.404157] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1566machine # [ 68.413875] systemd[589]: Stopped Run user-specific NixOS activation.1567machine # [ 68.416125] systemd[589]: Stopping Run user-specific NixOS activation...1568machine # [ 68.418991] systemd[589]: Starting Run user-specific NixOS activation...1569machine # [ 68.465178] systemd[589]: Finished Run user-specific NixOS activation.1570machine # [ 68.469255] systemd[1]: Stopped target Reactivate sysinit units.1571machine # [ 68.470219] systemd[1]: Stopping Reactivate sysinit units...1572machine # [ 68.471007] systemd[1]: Reached target Reactivate sysinit units.1573machine # [ 68.479408] systemd[1]: Mounting Testmount...1574machine # [ 68.484379] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1575machine # [ 68.485756] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1576machine # [ 68.493285] systemd[1]: Reached target Remote File Systems.1577machine # [ 68.494155] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1578machine # [ 68.500940] systemd[1]: Starting Create SUID/SGID Wrappers...1579machine # [ 68.501838] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671580machine # [ 68.503918] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1581machine # [ 68.509750] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1582machine # [ 68.511589] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1583machine # [ 68.540448] systemd[1]: Mounted Testmount.1584machine # [ 68.542868] systemd[1]: Reached target Local File Systems.1585machine # [ 68.544229] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1586machine # [ 68.850741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1587machine # [ 68.854657] systemd[1]: Finished Create SUID/SGID Wrappers.1588machine # [ 68.857559] systemd[1]: Starting linger-users.service...1589machine # [ 68.914563] systemd[1]: linger-users.service: Deactivated successfully.1590machine # [ 68.917032] systemd[1]: Finished linger-users.service.1591machine # [ 69.176577] nixos[2802]: finished switching to system configuration /nix/store/7pmz9jrh1qfx4m5zgjadk5sjdnfmh3r7-nixos-system-machine-test1592machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 3.01 seconds)1593machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11594machine # [ 69.855016] nixos[2944]: switching to system configuration /nix/store/qld6r2s5f58w0bs71766s6w48jg5azw0-nixos-system-machine-test1595machine # [ 69.857941] systemd[1]: Stopped target Remote File Systems.1596machine # [ 69.860050] systemd[1]: Stopped target Local File Systems.1597machine # [ 70.365307] systemd[1]: Reload requested from client PID 2944 ('.switch-to-conf') (unit backdoor.service)...1598machine # [ 70.367253] systemd[1]: Reloading...1599machine # [ 70.994044] systemd[1]: Reloading finished in 624 ms.1600machine # [ 71.134833] systemd[589]: Reexecution requested from client PID 3013 ('switch-to-confi')...1601machine # [ 71.138156] systemd[589]: Reexecuting.1602machine # [ 71.189717] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1603machine # [ 71.380859] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1604machine # [ 71.387283] systemd[589]: Reload requested from client PID 3013 ('switch-to-confi')...1605machine # [ 71.390730] systemd[589]: Reloading...1606machine # [ 71.578151] systemd[589]: Reloading finished in 188 ms.1607machine # [ 71.592141] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1608machine # [ 71.600983] systemd[589]: Stopped Run user-specific NixOS activation.1609machine # [ 71.603192] systemd[589]: Stopping Run user-specific NixOS activation...1610machine # [ 71.605771] systemd[589]: Starting Run user-specific NixOS activation...1611machine # [ 71.652210] systemd[589]: Finished Run user-specific NixOS activation.1612machine # [ 71.656072] systemd[1]: Stopped target Reactivate sysinit units.1613machine # [ 71.658979] systemd[1]: Stopping Reactivate sysinit units...1614machine # [ 71.660390] systemd[1]: Reached target Reactivate sysinit units.1615machine # [ 71.662457] systemd[1]: Reloading Testmount...1616machine # [ 71.701057] systemd[1]: Reloaded Testmount.1617machine # [ 71.707597] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1618machine # [ 71.711097] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1619machine # [ 71.715389] systemd[1]: Reached target Remote File Systems.1620machine # [ 71.717888] systemd[1]: Starting linger-users.service...1621machine # [ 71.720291] systemd[1]: Reached target Local File Systems.1622machine # [ 71.731162] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1623machine # [ 71.735692] systemd[1]: Starting Create SUID/SGID Wrappers...1624machine # [ 71.736717] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1625machine # [ 71.738242] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671626machine # [ 71.741992] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1627machine # [ 71.743893] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1628machine # [ 71.748872] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1629machine # [ 71.803750] systemd[1]: linger-users.service: Deactivated successfully.1630machine # [ 71.807080] systemd[1]: Finished linger-users.service.1631machine # [ 72.078385] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1632machine # [ 72.081558] systemd[1]: Finished Create SUID/SGID Wrappers.1633machine # [ 72.338914] nixos[2944]: finished switching to system configuration /nix/store/qld6r2s5f58w0bs71766s6w48jg5azw0-nixos-system-machine-test1634machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 3.17 seconds)1635machine: must succeed: mount | grep 'on /testmount'1636machine: (finished: must succeed: mount | grep 'on /testmount', in 0.07 seconds)1637machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11638machine # [ 73.049655] nixos[3091]: switching to system configuration /nix/store/ipl66hfjanm66ylzzdwx2agwvzxhfaq9-nixos-system-machine-test1639machine # [ 73.052298] systemd[1]: Stopped target Remote File Systems.1640machine # [ 73.054020] systemd[1]: Stopped target Local File Systems.1641machine # [ 73.555229] systemd[1]: Reload requested from client PID 3091 ('.switch-to-conf') (unit backdoor.service)...1642machine # [ 73.557370] systemd[1]: Reloading...1643machine # [ 74.199900] systemd[1]: Reloading finished in 640 ms.1644machine # [ 74.343516] systemd[589]: Reexecution requested from client PID 3160 ('switch-to-confi')...1645machine # [ 74.346355] systemd[589]: Reexecuting.1646machine # [ 74.405692] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1647machine # [ 74.600545] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1648machine # [ 74.607326] systemd[589]: Reload requested from client PID 3160 ('switch-to-confi')...1649machine # [ 74.610499] systemd[589]: Reloading...1650machine # [ 74.800684] systemd[589]: Reloading finished in 191 ms.1651machine # [ 74.817557] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1652machine # [ 74.824974] systemd[589]: Stopped Run user-specific NixOS activation.1653machine # [ 74.827230] systemd[589]: Stopping Run user-specific NixOS activation...1654machine # [ 74.829962] systemd[589]: Starting Run user-specific NixOS activation...1655machine # [ 74.877383] systemd[589]: Finished Run user-specific NixOS activation.1656machine # [ 74.881794] systemd[1]: Stopped target Reactivate sysinit units.1657machine # [ 74.884611] systemd[1]: Stopping Reactivate sysinit units...1658machine # [ 74.885500] systemd[1]: Reached target Reactivate sysinit units.1659machine # [ 74.887956] systemd[1]: Unmounting Testmount...1660machine # [ 74.926092] systemd[1]: testmount.mount: Deactivated successfully.1661machine # [ 74.928059] systemd[1]: Unmounted Testmount.1662machine # [ 74.933865] systemd[1]: Mounting Testmount...1663machine # [ 74.974078] systemd[1]: Mounted Testmount.1664machine # [ 74.982356] systemd[1]: Reached target Remote File Systems.1665machine # [ 74.987186] systemd[1]: Starting linger-users.service...1666machine # [ 74.990428] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1667machine # [ 74.992677] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1668machine # [ 74.993982] systemd[1]: Reached target Local File Systems.1669machine # [ 75.003051] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1670machine # [ 75.008337] systemd[1]: Starting Create SUID/SGID Wrappers...1671machine # [ 75.009259] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1672machine # [ 75.010467] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671673machine # [ 75.016653] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1674machine # [ 75.020692] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1675machine # [ 75.024678] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1676machine # [ 75.082458] systemd[1]: linger-users.service: Deactivated successfully.1677machine # [ 75.084240] systemd[1]: Finished linger-users.service.1678machine # [ 75.464930] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1679machine # [ 75.468440] systemd[1]: Finished Create SUID/SGID Wrappers.1680machine # [ 75.728534] nixos[3091]: finished switching to system configuration /nix/store/ipl66hfjanm66ylzzdwx2agwvzxhfaq9-nixos-system-machine-test1681machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 3.31 seconds)1682machine: must succeed: mount | grep 'on /testmount'1683machine: (finished: must succeed: mount | grep 'on /testmount', in 0.07 seconds)1684(finished: subtest: mounts, in 13.03 seconds)1685subtest: timers1686machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11687machine # [ 76.396255] nixos[3240]: switching to system configuration /nix/store/4zv8m6d65ygrjx7wrl828bhkx2ffihlr-nixos-system-machine-test1688machine # [ 76.400957] systemd[1]: Stopped target Local File Systems.1689machine # [ 76.401804] systemd[1]: Stopped target Remote File Systems.1690machine # [ 76.404403] systemd[1]: Unmounting Testmount...1691machine # [ 76.441634] systemd[1]: testmount.mount: Deactivated successfully.1692machine # [ 76.444103] systemd[1]: Unmounted Testmount.1693machine # [ 76.942311] systemd[1]: Reload requested from client PID 3240 ('.switch-to-conf') (unit backdoor.service)...1694machine # [ 76.944421] systemd[1]: Reloading...1695machine # [ 77.631011] systemd[1]: Reloading finished in 684 ms.1696machine # [ 77.784451] systemd[589]: Reexecution requested from client PID 3311 ('switch-to-confi')...1697machine # [ 77.787956] systemd[589]: Reexecuting.1698machine # [ 77.849956] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1699machine # [ 78.047836] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1700machine # [ 78.054471] systemd[589]: Reload requested from client PID 3311 ('switch-to-confi')...1701machine # [ 78.056881] systemd[589]: Reloading...1702machine # [ 78.249859] systemd[589]: Reloading finished in 191 ms.1703machine # [ 78.264160] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1704machine # [ 78.274586] systemd[589]: Stopped Run user-specific NixOS activation.1705machine # [ 78.276992] systemd[589]: Stopping Run user-specific NixOS activation...1706machine # [ 78.279851] systemd[589]: Starting Run user-specific NixOS activation...1707machine # [ 78.326996] systemd[589]: Finished Run user-specific NixOS activation.1708machine # [ 78.331141] systemd[1]: Stopped target Reactivate sysinit units.1709machine # [ 78.333427] systemd[1]: Stopping Reactivate sysinit units...1710machine # [ 78.334297] systemd[1]: Reached target Reactivate sysinit units.1711machine # [ 78.337049] systemd[1]: Started test-timer.timer.1712machine # [ 78.340719] systemd[1]: Reached target Local File Systems.1713machine # [ 78.342958] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1714machine # [ 78.345370] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1715machine # [ 78.349430] systemd[1]: Reached target Remote File Systems.1716machine # [ 78.354026] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1717machine # [ 78.360716] systemd[1]: Starting Create SUID/SGID Wrappers...1718machine # [ 78.361638] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1719machine # [ 78.362861] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671720machine # [ 78.365322] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1721machine # [ 78.367306] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1722machine # [ 78.369236] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1723machine # [ 78.689095] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1724machine # [ 78.692141] systemd[1]: Finished Create SUID/SGID Wrappers.1725machine # [ 78.696693] systemd[1]: Starting linger-users.service...1726machine # [ 78.755398] systemd[1]: linger-users.service: Deactivated successfully.1727machine # [ 78.758440] systemd[1]: Finished linger-users.service.1728machine # [ 79.017562] nixos[3240]: finished switching to system configuration /nix/store/4zv8m6d65ygrjx7wrl828bhkx2ffihlr-nixos-system-machine-test1729machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 3.22 seconds)1730machine: must succeed: systemctl show test-timer.timer1731machine: (finished: must succeed: systemctl show test-timer.timer, in 0.09 seconds)1732machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11733machine # [ 79.754127] nixos[3385]: switching to system configuration /nix/store/af5v26rv1j0ng04d2di9ikyhf70pi7ba-nixos-system-machine-test1734machine # [ 79.756770] systemd[1]: Stopped target Local File Systems.1735machine # [ 79.758972] systemd[1]: Stopped target Remote File Systems.1736machine # [ 80.273159] systemd[1]: Reload requested from client PID 3385 ('.switch-to-conf') (unit backdoor.service)...1737machine # [ 80.275119] systemd[1]: Reloading...1738machine # [ 81.053843] systemd[1]: Reloading finished in 776 ms.1739machine # [ 81.223827] systemd[589]: Reexecution requested from client PID 3454 ('switch-to-confi')...1740machine # [ 81.226264] systemd[589]: Reexecuting.1741machine # [ 81.274559] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1742machine # [ 81.486892] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1743machine # [ 81.493712] systemd[589]: Reload requested from client PID 3454 ('switch-to-confi')...1744machine # [ 81.496358] systemd[589]: Reloading...1745machine # [ 81.690552] systemd[589]: Reloading finished in 192 ms.1746machine # [ 81.708171] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1747machine # [ 81.718925] systemd[589]: Stopped Run user-specific NixOS activation.1748machine # [ 81.719995] systemd[589]: Stopping Run user-specific NixOS activation...1749machine # [ 81.723020] systemd[589]: Starting Run user-specific NixOS activation...1750machine # [ 81.773123] systemd[589]: Finished Run user-specific NixOS activation.1751machine # [ 81.777796] systemd[1]: Stopped target Reactivate sysinit units.1752machine # [ 81.779651] systemd[1]: Stopping Reactivate sysinit units...1753machine # [ 81.782551] systemd[1]: Reached target Reactivate sysinit units.1754machine # [ 81.783432] systemd[1]: test-timer.timer: Deactivated successfully.1755machine # [ 81.785590] systemd[1]: Stopped test-timer.timer.1756machine # [ 81.789302] systemd[1]: Stopping test-timer.timer...1757machine # [ 81.792450] systemd[1]: Started test-timer.timer.1758machine # [ 81.793253] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1759machine # [ 81.794625] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1760machine # [ 81.797872] systemd[1]: Reached target Remote File Systems.1761machine # [ 81.801372] systemd[1]: Starting linger-users.service...1762machine # [ 81.813377] systemd[1]: Reached target Local File Systems.1763machine # [ 81.819584] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1764machine # [ 81.828547] systemd[1]: Starting Create SUID/SGID Wrappers...1765machine # [ 81.833042] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1766machine # [ 81.835249] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671767machine # [ 81.841983] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1768machine # [ 81.846628] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1769machine # [ 81.848789] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1770machine # [ 81.913914] systemd[1]: linger-users.service: Deactivated successfully.1771machine # [ 81.917562] systemd[1]: Finished linger-users.service.1772machine # [ 82.243731] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1773machine # [ 82.247177] systemd[1]: Finished Create SUID/SGID Wrappers.1774machine # [ 82.505065] nixos[3385]: finished switching to system configuration /nix/store/af5v26rv1j0ng04d2di9ikyhf70pi7ba-nixos-system-machine-test1775machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 3.40 seconds)1776machine: must succeed: systemctl show test-timer.timer1777machine: (finished: must succeed: systemctl show test-timer.timer, in 0.10 seconds)1778(finished: subtest: timers, in 6.81 seconds)1779subtest: targets1780machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11781machine # [ 83.229164] nixos[3528]: switching to system configuration /nix/store/i3wdpmhqhpsdsmygirg65hg0mfg3qkcq-nixos-system-machine-test1782machine # [ 83.233270] systemd[1]: test-timer.timer: Deactivated successfully.1783machine # [ 83.235122] systemd[1]: Stopped test-timer.timer.1784machine # [ 83.235918] systemd[1]: Stopped target Local File Systems.1785machine # [ 83.237600] systemd[1]: Stopped target Remote File Systems.1786machine # [ 83.778462] systemd[1]: Reload requested from client PID 3528 ('.switch-to-conf') (unit backdoor.service)...1787machine # [ 83.780509] systemd[1]: Reloading...1788machine # [ 84.481420] systemd[1]: Reloading finished in 698 ms.1789machine # [ 84.691994] systemd[589]: Reexecution requested from client PID 3597 ('switch-to-confi')...1790machine # [ 84.696551] systemd[589]: Reexecuting.1791machine # [ 84.758689] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1792machine # [ 84.955771] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1793machine # [ 84.962812] systemd[589]: Reload requested from client PID 3597 ('switch-to-confi')...1794machine # [ 84.966062] systemd[589]: Reloading...1795machine # [ 85.175679] systemd[589]: Reloading finished in 210 ms.1796machine # [ 85.188331] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1797machine # [ 85.199922] systemd[589]: Stopped Run user-specific NixOS activation.1798machine # [ 85.202362] systemd[589]: Stopping Run user-specific NixOS activation...1799machine # [ 85.205223] systemd[589]: Starting Run user-specific NixOS activation...1800machine # [ 85.253585] systemd[589]: Finished Run user-specific NixOS activation.1801machine # [ 85.258147] systemd[1]: Stopped target Reactivate sysinit units.1802machine # [ 85.259074] systemd[1]: Stopping Reactivate sysinit units...1803machine # [ 85.259870] systemd[1]: Reached target Reactivate sysinit units.1804machine # [ 85.262039] systemd[1]: Reached target Local File Systems.1805machine # [ 85.267437] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1806machine # [ 85.274139] systemd[1]: Starting Create SUID/SGID Wrappers...1807machine # [ 85.276911] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1808machine # [ 85.279395] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671809machine # [ 85.283320] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1810machine # [ 85.289058] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1811machine # [ 85.290413] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1812machine # [ 85.297815] systemd[1]: Reached target Remote File Systems.1813machine # [ 85.298744] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1814machine # [ 85.300060] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1815machine # [ 85.606480] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1816machine # [ 85.609262] systemd[1]: Finished Create SUID/SGID Wrappers.1817machine # [ 85.614329] systemd[1]: Starting linger-users.service...1818machine # [ 85.693017] systemd[1]: linger-users.service: Deactivated successfully.1819machine # [ 85.697464] systemd[1]: Finished linger-users.service.1820machine # [ 85.957075] nixos[3528]: finished switching to system configuration /nix/store/i3wdpmhqhpsdsmygirg65hg0mfg3qkcq-nixos-system-machine-test1821machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 3.36 seconds)1822machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11823machine # [ 86.739872] nixos[3668]: switching to system configuration /nix/store/crhi4wh7wck7livz0mgs5hpw7k4qrwrw-nixos-system-machine-test1824machine # [ 86.743515] systemd[1]: Stopped target Local File Systems.1825machine # [ 86.744870] systemd[1]: Stopped target Remote File Systems.1826machine # [ 87.285074] systemd[1]: Reload requested from client PID 3668 ('.switch-to-conf') (unit backdoor.service)...1827machine # [ 87.287110] systemd[1]: Reloading...1828machine # [ 88.002329] systemd[1]: Reloading finished in 711 ms.1829machine # [ 88.158780] systemd[589]: Reexecution requested from client PID 3737 ('switch-to-confi')...1830machine # [ 88.161120] systemd[589]: Reexecuting.1831machine # [ 88.226172] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1832machine # [ 88.417260] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1833machine # [ 88.423844] systemd[589]: Reload requested from client PID 3737 ('switch-to-confi')...1834machine # [ 88.426611] systemd[589]: Reloading...1835machine # [ 88.619816] systemd[589]: Reloading finished in 192 ms.1836machine # [ 88.632162] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1837machine # [ 88.642563] systemd[589]: Stopped Run user-specific NixOS activation.1838machine # [ 88.645089] systemd[589]: Stopping Run user-specific NixOS activation...1839machine # [ 88.648099] systemd[589]: Starting Run user-specific NixOS activation...1840machine # [ 88.694850] systemd[589]: Finished Run user-specific NixOS activation.1841machine # [ 88.698970] systemd[1]: Stopped target Reactivate sysinit units.1842machine # [ 88.701620] systemd[1]: Stopping Reactivate sysinit units...1843machine # [ 88.702506] systemd[1]: Reached target Reactivate sysinit units.1844machine # [ 88.706461] systemd[1]: Reached target Remote File Systems.1845machine # [ 88.709338] systemd[1]: Reached target test-target.target.1846machine # [ 88.714472] systemd[1]: Starting linger-users.service...1847machine # [ 88.719406] systemd[1]: Reached target Local File Systems.1848machine # [ 88.722647] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1849machine # [ 88.732576] systemd[1]: Starting Create SUID/SGID Wrappers...1850machine # [ 88.733475] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1851machine # [ 88.734679] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671852machine # [ 88.744605] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1853machine # [ 88.746310] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1854machine # [ 88.747523] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1855machine # [ 88.748855] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1856machine # [ 88.750099] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1857machine # [ 88.817325] systemd[1]: linger-users.service: Deactivated successfully.1858machine # [ 88.819092] systemd[1]: Finished linger-users.service.1859machine # [ 89.089758] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1860machine # [ 89.093378] systemd[1]: Finished Create SUID/SGID Wrappers.1861machine # [ 89.353489] nixos[3668]: finished switching to system configuration /nix/store/crhi4wh7wck7livz0mgs5hpw7k4qrwrw-nixos-system-machine-test1862machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 3.39 seconds)1863machine # [ 89.445030] systemd[1]: Started test-service.service.1864machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11865machine # [ 90.054639] nixos[3814]: switching to system configuration /nix/store/m0hrsrk6hxbacxh7cbagz7cjqydy57pd-nixos-system-machine-test1866machine # [ 90.057154] systemd[1]: Stopped target Remote File Systems.1867machine # [ 90.059411] systemd[1]: Stopped target Local File Systems.1868machine # [ 90.566271] systemd[1]: Reload requested from client PID 3814 ('.switch-to-conf') (unit backdoor.service)...1869machine # [ 90.568328] systemd[1]: Reloading...1870machine # [ 91.235670] systemd[1]: Reloading finished in 665 ms.1871machine # [ 91.436418] systemd[589]: Reexecution requested from client PID 3884 ('switch-to-confi')...1872machine # [ 91.440932] systemd[589]: Reexecuting.1873machine # [ 91.497799] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1874machine # [ 91.698960] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1875machine # [ 91.705878] systemd[589]: Reload requested from client PID 3884 ('switch-to-confi')...1876machine # [ 91.708507] systemd[589]: Reloading...1877machine # [ 91.896894] systemd[589]: Reloading finished in 187 ms.1878machine # [ 91.912161] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1879machine # [ 91.922016] systemd[589]: Stopped Run user-specific NixOS activation.1880machine # [ 91.924128] systemd[589]: Stopping Run user-specific NixOS activation...1881machine # [ 91.927233] systemd[589]: Starting Run user-specific NixOS activation...1882machine # [ 91.972729] systemd[589]: Finished Run user-specific NixOS activation.1883machine # [ 91.977077] systemd[1]: Stopped target Reactivate sysinit units.1884machine # [ 91.979902] systemd[1]: Stopping Reactivate sysinit units...1885machine # [ 91.981821] systemd[1]: Reached target Reactivate sysinit units.1886machine # [ 91.984294] systemd[1]: Reached target Local File Systems.1887machine # [ 91.985098] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1888machine # [ 91.991327] systemd[1]: Starting Create SUID/SGID Wrappers...1889machine # [ 91.995454] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1890machine # [ 92.004257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671891machine # [ 92.006532] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1892machine # [ 92.010829] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1893machine # [ 92.013761] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1894machine # [ 92.015154] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1895machine # [ 92.017455] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1896machine # [ 92.018847] systemd[1]: Reached target Remote File Systems.1897machine # [ 92.318163] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1898machine # [ 92.322805] systemd[1]: Finished Create SUID/SGID Wrappers.1899machine # [ 92.325331] systemd[1]: Starting linger-users.service...1900machine # [ 92.381814] systemd[1]: linger-users.service: Deactivated successfully.1901machine # [ 92.384340] systemd[1]: Finished linger-users.service.1902machine # [ 92.642399] nixos[3814]: finished switching to system configuration /nix/store/m0hrsrk6hxbacxh7cbagz7cjqydy57pd-nixos-system-machine-test1903machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 3.20 seconds)1904machine: must succeed: systemctl is-active test-service.service1905machine: (finished: must succeed: systemctl is-active test-service.service, in 0.06 seconds)1906machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&11907machine # [ 93.357739] nixos[3958]: switching to system configuration /nix/store/r924q9jz5k64x3b2zy019vqvqc7gs5kr-nixos-system-machine-test1908machine # [ 93.360995] systemd[1]: Stopped target Remote File Systems.1909machine # [ 93.363419] systemd[1]: Stopped target Local File Systems.1910machine # [ 93.367988] systemd[1]: Stopped target test-target.target.1911machine # [ 93.372119] systemd[1]: Stopping test-service.service...1912machine # [ 93.375488] systemd[1]: test-service.service: Deactivated successfully.1913machine # [ 93.377249] systemd[1]: Stopped test-service.service.1914machine # [ 93.925844] systemd[1]: Reload requested from client PID 3958 ('.switch-to-conf') (unit backdoor.service)...1915machine # [ 93.927893] systemd[1]: Reloading...1916machine # [ 94.641640] systemd[1]: Reloading finished in 709 ms.1917machine # [ 94.782032] systemd[589]: Reexecution requested from client PID 4028 ('switch-to-confi')...1918machine # [ 94.785577] systemd[589]: Reexecuting.1919machine # [ 94.845536] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1920machine # [ 95.033869] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1921machine # [ 95.040396] systemd[589]: Reload requested from client PID 4028 ('switch-to-confi')...1922machine # [ 95.042771] systemd[589]: Reloading...1923machine # [ 95.270907] systemd[589]: Reloading finished in 227 ms.1924machine # [ 95.284602] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1925machine # [ 95.304200] systemd[589]: Stopped Run user-specific NixOS activation.1926machine # [ 95.305577] systemd[589]: Stopping Run user-specific NixOS activation...1927machine # [ 95.311454] systemd[589]: Starting Run user-specific NixOS activation...1928machine # [ 95.364596] systemd[589]: Finished Run user-specific NixOS activation.1929machine # [ 95.368729] systemd[1]: Stopped target Reactivate sysinit units.1930machine # [ 95.371182] systemd[1]: Stopping Reactivate sysinit units...1931machine # [ 95.372857] systemd[1]: Reached target Reactivate sysinit units.1932machine # [ 95.376448] systemd[1]: Reached target Remote File Systems.1933machine # [ 95.378504] systemd[1]: Reached target Local File Systems.1934machine # [ 95.382253] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1935machine # [ 95.387281] systemd[1]: Starting Create SUID/SGID Wrappers...1936machine # [ 95.390657] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1937machine # [ 95.396270] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671938machine # [ 95.398505] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1939machine # [ 95.403897] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1940machine # [ 95.408079] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1941machine # [ 95.409369] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1942machine # [ 95.411040] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1943machine # [ 95.412634] systemd[1]: Reached target test-target.target.1944machine # [ 95.735873] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1945machine # [ 95.739944] systemd[1]: Finished Create SUID/SGID Wrappers.1946machine # [ 95.743122] systemd[1]: Starting linger-users.service...1947machine # [ 95.804739] systemd[1]: linger-users.service: Deactivated successfully.1948machine # [ 95.807505] systemd[1]: Finished linger-users.service.1949machine # [ 96.067239] nixos[3958]: finished switching to system configuration /nix/store/r924q9jz5k64x3b2zy019vqvqc7gs5kr-nixos-system-machine-test1950machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 3.37 seconds)1951machine: must fail: systemctl is-active test-service.servce1952machine: (finished: must fail: systemctl is-active test-service.servce, in 0.06 seconds)1953machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11954machine # [ 96.729152] nixos[4102]: switching to system configuration /nix/store/af5v26rv1j0ng04d2di9ikyhf70pi7ba-nixos-system-machine-test1955machine # [ 96.732708] systemd[1]: Stopped target Local File Systems.1956machine # [ 96.734187] systemd[1]: Stopped target Remote File Systems.1957machine # [ 96.735016] systemd[1]: Stopped target test-target.target.1958machine # [ 97.197177] systemd[1]: Reload requested from client PID 4102 ('.switch-to-conf') (unit backdoor.service)...1959machine # [ 97.199101] systemd[1]: Reloading...1960machine # [ 97.804800] systemd[1]: Reloading finished in 603 ms.1961machine # [ 97.950653] systemd[589]: Reexecution requested from client PID 4171 ('switch-to-confi')...1962machine # [ 97.954714] systemd[589]: Reexecuting.1963machine # [ 98.002517] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1964machine # [ 98.195880] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1965machine # [ 98.202929] systemd[589]: Reload requested from client PID 4171 ('switch-to-confi')...1966machine # [ 98.206880] systemd[589]: Reloading...1967machine # [ 98.402616] systemd[589]: Reloading finished in 196 ms.1968machine # [ 98.420209] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1969machine # [ 98.431772] systemd[589]: Stopped Run user-specific NixOS activation.1970machine # [ 98.432948] systemd[589]: Stopping Run user-specific NixOS activation...1971machine # [ 98.436082] systemd[589]: Starting Run user-specific NixOS activation...1972machine # [ 98.481641] systemd[589]: Finished Run user-specific NixOS activation.1973machine # [ 98.486114] systemd[1]: Stopped target Reactivate sysinit units.1974machine # [ 98.488268] systemd[1]: Stopping Reactivate sysinit units...1975machine # [ 98.493281] systemd[1]: Reached target Reactivate sysinit units.1976machine # [ 98.494545] systemd[1]: Reached target Local File Systems.1977machine # [ 98.497901] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1978machine # [ 98.499460] systemd[1]: Starting Create SUID/SGID Wrappers...1979machine # [ 98.508321] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1980machine # [ 98.509674] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671981machine # [ 98.511790] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1982machine # [ 98.516228] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1983machine # [ 98.519533] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1984machine # [ 98.524786] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1985machine # [ 98.526338] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1986machine # [ 98.528442] systemd[1]: Reached target Remote File Systems.1987machine # [ 98.840433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1988machine # [ 98.844258] systemd[1]: Finished Create SUID/SGID Wrappers.1989machine # [ 98.845133] systemd[1]: Started test-timer.timer.1990machine # [ 98.849133] systemd[1]: Starting linger-users.service...1991machine # [ 98.911263] systemd[1]: linger-users.service: Deactivated successfully.1992machine # [ 98.914507] systemd[1]: Finished linger-users.service.1993machine # [ 99.174176] nixos[4102]: finished switching to system configuration /nix/store/af5v26rv1j0ng04d2di9ikyhf70pi7ba-nixos-system-machine-test1994machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 3.05 seconds)1995(finished: subtest: targets, in 16.57 seconds)1996subtest: paths1997machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&11998machine # [ 99.846283] nixos[4242]: switching to system configuration /nix/store/q6b0b9bgq2k8zlb6zzq36c5j75wcg1wi-nixos-system-machine-test1999machine # [ 99.850147] systemd[1]: test-timer.timer: Deactivated successfully.2000machine # [ 99.851909] systemd[1]: Stopped test-timer.timer.2001machine # [ 99.853299] systemd[1]: Stopped target Local File Systems.2002machine # [ 99.854248] systemd[1]: Stopped target Remote File Systems.2003machine # [ 100.381664] systemd[1]: Reload requested from client PID 4242 ('.switch-to-conf') (unit backdoor.service)...2004machine # [ 100.383638] systemd[1]: Reloading...2005machine # [ 101.046601] systemd[1]: Reloading finished in 660 ms.2006machine # [ 101.200462] systemd[589]: Reexecution requested from client PID 4311 ('switch-to-confi')...2007machine # [ 101.203783] systemd[589]: Reexecuting.2008machine # [ 101.266897] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2009machine # [ 101.471963] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2010machine # [ 101.478784] systemd[589]: Reload requested from client PID 4311 ('switch-to-confi')...2011machine # [ 101.481335] systemd[589]: Reloading...2012machine # [ 101.674670] systemd[589]: Reloading finished in 192 ms.2013machine # [ 101.688173] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2014machine # [ 101.698245] systemd[589]: Stopped Run user-specific NixOS activation.2015machine # [ 101.699281] systemd[589]: Stopping Run user-specific NixOS activation...2016machine # [ 101.702342] systemd[589]: Starting Run user-specific NixOS activation...2017machine # [ 101.750793] systemd[589]: Finished Run user-specific NixOS activation.2018machine # [ 101.755069] systemd[1]: Stopped target Reactivate sysinit units.2019machine # [ 101.757761] systemd[1]: Stopping Reactivate sysinit units...2020machine # [ 101.758639] systemd[1]: Reached target Reactivate sysinit units.2021machine # [ 101.761947] systemd[1]: Reached target Local File Systems.2022machine # [ 101.764791] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2023machine # [ 101.769814] systemd[1]: Starting Create SUID/SGID Wrappers...2024machine # [ 101.770732] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2025machine # [ 101.771948] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672026machine # [ 101.776400] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2027machine # [ 101.789264] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2028machine # [ 101.791364] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2029machine # [ 101.793239] systemd[1]: Reached target Remote File Systems.2030machine # [ 101.794052] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2031machine # [ 101.795294] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2032machine # [ 102.116749] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2033machine # [ 102.120843] systemd[1]: Finished Create SUID/SGID Wrappers.2034machine # [ 102.121745] systemd[1]: Started test-watch.path.2035machine # [ 102.125533] systemd[1]: Starting linger-users.service...2036machine # [ 102.185257] systemd[1]: linger-users.service: Deactivated successfully.2037machine # [ 102.187354] systemd[1]: Finished linger-users.service.2038machine # [ 102.448581] nixos[4242]: finished switching to system configuration /nix/store/q6b0b9bgq2k8zlb6zzq36c5j75wcg1wi-nixos-system-machine-test2039machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 3.27 seconds)2040machine: must fail: test -f /testpath-modified2041machine: (finished: must fail: test -f /testpath-modified, in 0.06 seconds)2042machine: must succeed: touch /testpath2043machine: (finished: must succeed: touch /testpath, in 0.07 seconds)2044machine: waiting for success: test -f /testpath-modified2045machine # [ 102.600845] systemd[1]: Starting test-watch.service...2046machine # [ 102.675738] systemd[1]: Finished test-watch.service.2047machine: (finished: waiting for success: test -f /testpath-modified, in 1.13 seconds)2048machine: must succeed: rm /testpath /testpath-modified2049machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.05 seconds)2050machine # [ 103.828563] systemd[1]: test-watch.service: Deactivated successfully.2051machine # [ 103.830342] systemd[1]: Stopped test-watch.service.2052machine # Stopping 'test-watch.service', but its triggering units are still active:2053machine # test-watch.path2054machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12055machine # [ 104.443280] nixos[4407]: switching to system configuration /nix/store/kxxr261r79sf5f2y9h244gl42ygxlzws-nixos-system-machine-test2056machine # [ 104.446978] systemd[1]: Stopped target Local File Systems.2057machine # [ 104.448366] systemd[1]: Stopped target Remote File Systems.2058machine # [ 104.971470] systemd[1]: Reload requested from client PID 4407 ('.switch-to-conf') (unit backdoor.service)...2059machine # [ 104.975364] systemd[1]: Reloading...2060machine # [ 105.738133] systemd[1]: Reloading finished in 759 ms.2061machine # [ 105.884457] systemd[589]: Reexecution requested from client PID 4477 ('switch-to-confi')...2062machine # [ 105.887609] systemd[589]: Reexecuting.2063machine # [ 105.949801] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2064machine # [ 106.177131] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2065machine # [ 106.184133] systemd[589]: Reload requested from client PID 4477 ('switch-to-confi')...2066machine # [ 106.185488] systemd[589]: Reloading...2067machine # [ 106.370603] systemd[589]: Reloading finished in 182 ms.2068machine # [ 106.386243] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2069machine # [ 106.396156] systemd[589]: Stopped Run user-specific NixOS activation.2070machine # [ 106.397286] systemd[589]: Stopping Run user-specific NixOS activation...2071machine # [ 106.401217] systemd[589]: Starting Run user-specific NixOS activation...2072machine # [ 106.467963] systemd[589]: Finished Run user-specific NixOS activation.2073machine # [ 106.475527] systemd[1]: Stopped target Reactivate sysinit units.2074machine # [ 106.479085] systemd[1]: Stopping Reactivate sysinit units...2075machine # [ 106.481767] systemd[1]: Reached target Reactivate sysinit units.2076machine # [ 106.484191] systemd[1]: Reached target Local File Systems.2077machine # [ 106.487669] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2078machine # [ 106.492605] systemd[1]: Starting Create SUID/SGID Wrappers...2079machine # [ 106.493507] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2080machine # [ 106.494730] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672081machine # [ 106.499331] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2082machine # [ 106.503711] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2083machine # [ 106.513447] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2084machine # [ 106.514674] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2085machine # [ 106.515906] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2086machine # [ 106.520501] systemd[1]: Reached target Remote File Systems.2087machine # [ 106.863493] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2088machine # [ 106.868547] systemd[1]: Finished Create SUID/SGID Wrappers.2089machine # [ 106.870976] systemd[1]: Starting linger-users.service...2090machine # [ 106.934767] systemd[1]: linger-users.service: Deactivated successfully.2091machine # [ 106.937410] systemd[1]: Finished linger-users.service.2092machine # [ 107.193269] nixos[4407]: finished switching to system configuration /nix/store/kxxr261r79sf5f2y9h244gl42ygxlzws-nixos-system-machine-test2093machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 3.36 seconds)2094machine: must succeed: touch /testpath2095machine: (finished: must succeed: touch /testpath, in 0.04 seconds)2096machine: must fail: test -f /testpath-modified2097machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2098machine: must succeed: touch /testpath22099machine: (finished: must succeed: touch /testpath2, in 0.03 seconds)2100machine: waiting for success: test -f /testpath-modified2101machine # [ 107.302144] systemd[1]: Starting test-watch.service...2102machine # [ 107.350753] systemd[1]: Finished test-watch.service.2103machine: (finished: waiting for success: test -f /testpath-modified, in 1.10 seconds)2104(finished: subtest: paths, in 9.21 seconds)2105subtest: slices2106machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2107machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.04 seconds)2108machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12109machine # [ 109.099204] nixos[4572]: switching to system configuration /nix/store/s2h4i5gzxrkh4hhgaxgdhn3xffsyc6my-nixos-system-machine-test2110machine # [ 109.103499] systemd[1]: Stopped target Local File Systems.2111machine # [ 109.106300] systemd[1]: Stopped target Remote File Systems.2112machine # [ 109.107277] systemd[1]: test-watch.service: Deactivated successfully.2113machine # [ 109.108715] systemd[1]: Stopped test-watch.service.2114machine # [ 109.109724] systemd[1]: test-watch.path: Deactivated successfully.2115machine # [ 109.110656] systemd[1]: Stopped test-watch.path.2116machine # [ 109.660288] systemd[1]: Reload requested from client PID 4572 ('.switch-to-conf') (unit backdoor.service)...2117machine # [ 109.662431] systemd[1]: Reloading...2118machine # [ 110.326801] systemd[1]: Reloading finished in 661 ms.2119machine # [ 110.471335] systemd[589]: Reexecution requested from client PID 4641 ('switch-to-confi')...2120machine # [ 110.473466] systemd[589]: Reexecuting.2121machine # [ 110.542623] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2122machine # [ 110.786685] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2123machine # [ 110.793592] systemd[589]: Reload requested from client PID 4641 ('switch-to-confi')...2124machine # [ 110.796243] systemd[589]: Reloading...2125machine # [ 110.994386] systemd[589]: Reloading finished in 196 ms.2126machine # [ 111.008199] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2127machine # [ 111.018774] systemd[589]: Stopped Run user-specific NixOS activation.2128machine # [ 111.021529] systemd[589]: Stopping Run user-specific NixOS activation...2129machine # [ 111.024946] systemd[589]: Starting Run user-specific NixOS activation...2130machine # [ 111.076941] systemd[589]: Finished Run user-specific NixOS activation.2131machine # [ 111.081058] systemd[1]: Stopped target Reactivate sysinit units.2132machine # [ 111.084314] systemd[1]: Stopping Reactivate sysinit units...2133machine # [ 111.085182] systemd[1]: Reached target Reactivate sysinit units.2134machine # [ 111.086056] systemd[1]: Reached target Local File Systems.2135machine # [ 111.092095] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2136machine # [ 111.096760] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2137machine # [ 111.098101] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2138machine # [ 111.103965] systemd[1]: Starting Create SUID/SGID Wrappers...2139machine # [ 111.105057] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2140machine # [ 111.106303] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672141machine # [ 111.110036] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2142machine # [ 111.119930] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2143machine # [ 111.121427] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2144machine # [ 111.122620] systemd[1]: Reached target Remote File Systems.2145machine # [ 111.450218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2146machine # [ 111.452566] systemd[1]: Finished Create SUID/SGID Wrappers.2147machine # [ 111.457639] systemd[1]: Starting linger-users.service...2148machine # [ 111.523099] systemd[1]: linger-users.service: Deactivated successfully.2149machine # [ 111.525586] systemd[1]: Finished linger-users.service.2150machine # [ 111.786977] nixos[4572]: finished switching to system configuration /nix/store/s2h4i5gzxrkh4hhgaxgdhn3xffsyc6my-nixos-system-machine-test2151machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 3.36 seconds)2152machine: must fail: systemctl start testservice.service2153machine # [ 111.896323] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02154machine # [ 111.896438] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.49 #1-NixOS PREEMPTLAZY2155machine # [ 111.896446] Hardware name: linux,dummy-virt (DT)2156machine # [ 111.896449] Call trace:2157machine # [ 111.896451] show_stack+0x30/0x98 (C)2158machine # [ 111.896507] dump_stack_lvl+0x60/0x802159machine # [ 111.896512] dump_stack+0x18/0x242160machine # [ 111.896514] dump_header+0x48/0x1982161machine # [ 111.896523] out_of_memory+0x54c/0x5a02162machine # [ 111.896544] mem_cgroup_out_of_memory+0xcc/0xe02163machine # [ 111.896565] try_charge_memcg+0x440/0x6882164machine # [ 111.896569] obj_cgroup_charge_account+0x130/0x2182165machine # [ 111.896573] __memcg_slab_post_alloc_hook+0xf4/0x2d02166machine # [ 111.896580] __kmalloc_cache_noprof+0x4ac/0x5b02167machine # [ 111.896592] mem_cgroup_css_alloc+0x10c/0x8402168machine # [ 111.896623] cgroup_apply_control_enable+0x14c/0x3602169machine # [ 111.896640] cgroup_mkdir+0x150/0x6882170machine # [ 111.896644] kernfs_iop_mkdir+0x94/0x1002171machine # [ 111.896668] vfs_mkdir+0x1a0/0x2902172machine # [ 111.896679] do_mkdirat+0x148/0x1902173machine # [ 111.896682] __arm64_sys_mkdirat+0x54/0xa82174machine # [ 111.896685] invoke_syscall.constprop.0+0x48/0xf02175machine # [ 111.896690] el0_svc_common.constprop.0+0x40/0xf02176machine # [ 111.896692] do_el0_svc+0x24/0x402177machine # [ 111.896694] el0_svc+0x40/0x1b02178machine # [ 111.896698] el0t_64_sync_handler+0xa0/0xe82179machine # [ 111.896701] el0t_64_sync+0x198/0x1a02180machine # [ 111.896922] memory: usage 0kB, limit 0kB, failcnt 182181machine # [ 111.896946] swap: usage 0kB, limit 9007199254740988kB, failcnt 02182machine # [ 111.896964] Memory cgroup stats for /testslice.slice:2183machine # [ 111.897012] anon 02184machine # [ 111.897039] file 02185machine # [ 111.897054] kernel 02186machine # [ 111.897070] kernel_stack 02187machine # [ 111.897085] pagetables 02188machine # [ 111.897100] sec_pagetables 02189machine # [ 111.897115] percpu 02190machine # [ 111.897128] sock 02191machine # [ 111.897142] vmalloc 02192machine # [ 111.897155] shmem 02193machine # [ 111.897171] zswap 02194machine # [ 111.897185] zswapped 02195machine # [ 111.897200] file_mapped 02196machine # [ 111.897215] file_dirty 02197machine # [ 111.897229] file_writeback 02198machine # [ 111.897473] swapcached 02199machine # [ 111.897695] anon_thp 02200machine # [ 111.897902] file_thp 02201machine # [ 111.914484] systemd[1]: Created slice Slice /testslice.2202machine # [ 111.934682] shmem_thp 02203machine # [ 111.934938] inactive_anon 02204machine # [ 111.935090] active_anon 02205machine # [ 111.935295] inactive_file 02206machine # [ 111.935432] active_file 02207machine # [ 111.935564] unevictable 02208machine # [ 111.935693] slab_reclaimable 02209machine # [ 111.935820] slab_unreclaimable 02210machine # [ 111.935947] slab 02211machine # [ 111.936074] hugetlb 02212machine # [ 111.936201] workingset_refault_anon 02213machine # [ 111.936327] workingset_refault_file 02214machine # [ 111.936459] workingset_activate_anon 02215machine # [ 111.936593] workingset_activate_file 02216machine # [ 111.936713] workingset_restore_anon 02217machine # [ 111.936838] workingset_restore_file 02218machine # [ 111.936958] workingset_nodereclaim 02219machine # [ 111.937076] pgdemote_kswapd 02220machine # [ 111.937193] pgdemote_direct 02221machine # [ 111.937312] pgdemote_khugepaged 02222machine # [ 111.937429] pgdemote_proactive 02223machine # [ 111.937545] pgpromote_success 02224machine # [ 111.937660] pgscan 02225machine # [ 111.937778] pgsteal 02226machine # [ 111.937894] pswpin 02227machine # [ 111.953693] pswpout 02228machine # [ 111.953906] pgscan_kswapd 02229machine # [ 111.956428] pgscan_direct 02230machine # [ 111.956631] pgscan_khugepaged 02231machine # [ 111.956802] pgscan_proactive 02232machine # [ 111.956916] pgsteal_kswapd 02233machine # [ 111.957024] pgsteal_direct 02234machine # [ 111.957127] pgsteal_khugepaged 02235machine # [ 111.957232] pgsteal_proactive 02236machine # [ 111.957337] pgfault 02237machine # [ 111.957441] pgmajfault 02238machine # [ 111.957543] pgrefill 02239machine # [ 111.957649] pgactivate 02240machine # [ 111.957752] pgdeactivate 02241machine # [ 111.957854] pglazyfree 02242machine # [ 111.957960] pglazyfreed 02243machine # [ 111.966207] swpin_zero 02244machine # [ 111.966419] swpout_zero 02245machine # [ 111.966535] zswpin 02246machine # [ 111.966641] zswpout 02247machine # [ 111.966747] zswpwb 02248machine # [ 111.966851] thp_fault_alloc 02249machine # [ 111.966956] thp_collapse_alloc 02250machine # [ 111.967063] thp_swpout 02251machine # [ 111.967171] thp_swpout_fallback 02252machine # [ 111.967277] numa_pages_migrated 02253machine # [ 111.967387] numa_pte_updates 02254machine # [ 111.967493] numa_hint_faults 02255machine # [ 111.967600] Tasks state (memory values in pages):2256machine # [ 111.967712] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2257machine # [ 111.967837] Out of memory and no killable processes...2258machine # [ 111.960658] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2259machine # [ 111.963081] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2260machine # [ 111.967740] systemd[1]: testservice.service: Failed with result 'resources'.2261machine # [ 111.969840] systemd[1]: Failed to start testservice.service.2262machine # Job for testservice.service failed because of unavailable resources or another system error.2263machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2264machine: (finished: must fail: systemctl start testservice.service, in 0.18 seconds)2265machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12266machine # [ 112.616371] nixos[4716]: switching to system configuration /nix/store/qpyxwx7gfcdrxi5wma16r0vxh8ydw177-nixos-system-machine-test2267machine # [ 112.619098] systemd[1]: Stopped target Local File Systems.2268machine # [ 112.621378] systemd[1]: Stopped target Remote File Systems.2269machine # [ 113.153834] systemd[1]: Reload requested from client PID 4716 ('.switch-to-conf') (unit backdoor.service)...2270machine # [ 113.155835] systemd[1]: Reloading...2271machine # [ 113.838149] systemd[1]: Reloading finished in 679 ms.2272machine # [ 113.989299] systemd[589]: Reexecution requested from client PID 4785 ('switch-to-confi')...2273machine # [ 113.994374] systemd[589]: Reexecuting.2274machine # [ 114.057979] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2275machine # [ 114.310040] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2276machine # [ 114.316588] systemd[589]: Reload requested from client PID 4785 ('switch-to-confi')...2277machine # [ 114.319170] systemd[589]: Reloading...2278machine # [ 114.514540] systemd[589]: Reloading finished in 194 ms.2279machine # [ 114.528179] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2280machine # [ 114.537882] systemd[589]: Stopped Run user-specific NixOS activation.2281machine # [ 114.540483] systemd[589]: Stopping Run user-specific NixOS activation...2282machine # [ 114.543612] systemd[589]: Starting Run user-specific NixOS activation...2283machine # [ 114.590044] systemd[589]: Finished Run user-specific NixOS activation.2284machine # [ 114.594330] systemd[1]: Stopped target Reactivate sysinit units.2285machine # [ 114.596899] systemd[1]: Stopping Reactivate sysinit units...2286machine # [ 114.597802] systemd[1]: Reached target Reactivate sysinit units.2287machine # [ 114.598631] systemd[1]: Reached target Remote File Systems.2288machine # [ 114.605433] systemd[1]: Starting linger-users.service...2289machine # [ 114.617492] systemd[1]: Reached target Local File Systems.2290machine # [ 114.618485] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2291machine # [ 114.623173] systemd[1]: Starting Create SUID/SGID Wrappers...2292machine # [ 114.626401] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2293machine # [ 114.627637] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672294machine # [ 114.638627] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2295machine # [ 114.641375] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2296machine # [ 114.643280] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2297machine # [ 114.645116] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2298machine # [ 114.646806] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2299machine # [ 114.707135] systemd[1]: linger-users.service: Deactivated successfully.2300machine # [ 114.710644] systemd[1]: Finished linger-users.service.2301machine # [ 114.992971] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2302machine # [ 114.996349] systemd[1]: Finished Create SUID/SGID Wrappers.2303machine # [ 115.253330] nixos[4716]: finished switching to system configuration /nix/store/qpyxwx7gfcdrxi5wma16r0vxh8ydw177-nixos-system-machine-test2304machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 3.28 seconds)2305machine: must succeed: systemctl start testservice.service2306machine # [ 115.343851] systemd[1]: Starting testservice.service...2307machine # [ 115.399200] systemd[1]: Finished testservice.service.2308machine: (finished: must succeed: systemctl start testservice.service, in 0.14 seconds)2309machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2310machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2311(finished: subtest: slices, in 7.03 seconds)2312subtest: dbus reloads2313machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&12314machine # [ 116.017686] nixos[4865]: switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test2315machine # [ 116.022451] systemd[1]: testservice.service: Deactivated successfully.2316machine # [ 116.023543] systemd[1]: Stopped testservice.service.2317machine # [ 116.025516] systemd[1]: Stopped target Local File Systems.2318machine # [ 116.029494] systemd[1]: Removed slice Slice /testslice.2319machine # [ 116.030715] systemd[1]: Stopped target Remote File Systems.2320machine # [ 116.521393] systemd[1]: Reload requested from client PID 4865 ('.switch-to-conf') (unit backdoor.service)...2321machine # [ 116.523541] systemd[1]: Reloading...2322machine # [ 117.200501] systemd[1]: Reloading finished in 674 ms.2323machine # [ 117.352447] systemd[589]: Reexecution requested from client PID 4933 ('switch-to-confi')...2324machine # [ 117.356120] systemd[589]: Reexecuting.2325machine # [ 117.422132] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2326machine # [ 117.624186] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2327machine # [ 117.630536] systemd[589]: Reload requested from client PID 4933 ('switch-to-confi')...2328machine # [ 117.632841] systemd[589]: Reloading...2329machine # [ 117.826494] systemd[589]: Reloading finished in 192 ms.2330machine # [ 117.844169] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2331machine # [ 117.853777] systemd[589]: Stopped Run user-specific NixOS activation.2332machine # [ 117.856208] systemd[589]: Stopping Run user-specific NixOS activation...2333machine # [ 117.859073] systemd[589]: Starting Run user-specific NixOS activation...2334machine # [ 117.905872] systemd[589]: Finished Run user-specific NixOS activation.2335machine # [ 117.910056] systemd[1]: Stopped target Reactivate sysinit units.2336machine # [ 117.911873] systemd[1]: Stopping Reactivate sysinit units...2337machine # [ 117.913391] systemd[1]: Reached target Reactivate sysinit units.2338machine # [ 117.917448] systemd[1]: Reached target Local File Systems.2339machine # [ 117.918966] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2340machine # [ 117.922943] systemd[1]: Starting Create SUID/SGID Wrappers...2341machine # [ 117.923765] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2342machine # [ 117.926473] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672343machine # [ 117.936645] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2344machine # [ 117.938252] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2345machine # [ 117.939510] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2346machine # [ 117.947870] systemd[1]: Reached target Remote File Systems.2347machine # [ 117.948823] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2348machine # [ 117.950110] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2349machine # [ 118.271037] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2350machine # [ 118.275902] systemd[1]: Finished Create SUID/SGID Wrappers.2351machine # [ 118.278763] systemd[1]: Starting linger-users.service...2352machine # [ 118.340118] systemd[1]: linger-users.service: Deactivated successfully.2353machine # [ 118.342649] systemd[1]: Finished linger-users.service.2354machine # [ 118.599609] nixos[4865]: finished switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test2355machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.19 seconds)2356machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12357machine # [ 119.380765] nixos[5005]: switching to system configuration /nix/store/vqr6vqx98h5km42k58cgg3fh5dpgd83r-nixos-system-machine-test2358machine # [ 119.385772] systemd[1]: Stopped target Local File Systems.2359machine # [ 119.387371] systemd[1]: Stopped target Remote File Systems.2360machine # [ 119.940740] systemd[1]: Reload requested from client PID 5005 ('.switch-to-conf') (unit backdoor.service)...2361machine # [ 119.942923] systemd[1]: Reloading...2362machine # [ 120.617882] systemd[1]: Reloading finished in 671 ms.2363machine # [ 120.760424] systemd[589]: Reexecution requested from client PID 5074 ('switch-to-confi')...2364machine # [ 120.763412] systemd[589]: Reexecuting.2365machine # [ 120.826013] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2366machine # [ 121.023891] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2367machine # [ 121.030668] systemd[589]: Reload requested from client PID 5074 ('switch-to-confi')...2368machine # [ 121.033302] systemd[589]: Reloading...2369machine # [ 121.229831] systemd[589]: Reloading finished in 195 ms.2370machine # [ 121.244160] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2371machine # [ 121.253543] systemd[589]: Stopped Run user-specific NixOS activation.2372machine # [ 121.255921] systemd[589]: Stopping Run user-specific NixOS activation...2373machine # [ 121.258827] systemd[589]: Starting Run user-specific NixOS activation...2374machine # [ 121.305781] systemd[589]: Finished Run user-specific NixOS activation.2375machine # [ 121.309920] systemd[1]: Stopped target Reactivate sysinit units.2376machine # [ 121.313985] systemd[1]: Stopping Reactivate sysinit units...2377machine # [ 121.315295] systemd[1]: Reached target Reactivate sysinit units.2378machine # [ 121.316864] systemd[1]: Reloading D-Bus System Message Bus...2379machine # [ 121.318245] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.2380machine # [ 121.331607] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...2381machine # [ 121.333832] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'2382machine # [ 121.334985] dbus-broker-launch[489]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2383machine # [ 121.361998] systemd[1]: Reloaded D-Bus System Message Bus.2384machine # [ 121.369878] systemd[1]: Reached target Local File Systems.2385machine # [ 121.372610] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2386machine # [ 121.377937] systemd[1]: Starting Create SUID/SGID Wrappers...2387machine # [ 121.378861] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2388machine # [ 121.383135] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672389machine # [ 121.392854] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2390machine # [ 121.394497] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2391machine # [ 121.395745] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2392machine # [ 121.399473] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2393machine # [ 121.401226] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2394machine # [ 121.402676] systemd[1]: Reached target Remote File Systems.2395machine # [ 121.729967] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2396machine # [ 121.734741] systemd[1]: Finished Create SUID/SGID Wrappers.2397machine # [ 121.737624] systemd[1]: Starting linger-users.service...2398machine # [ 121.797749] systemd[1]: linger-users.service: Deactivated successfully.2399machine # [ 121.800541] systemd[1]: Finished linger-users.service.2400machine # [ 122.058847] nixos[5005]: finished switching to system configuration /nix/store/vqr6vqx98h5km42k58cgg3fh5dpgd83r-nixos-system-machine-test2401machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 3.46 seconds)2402(finished: subtest: dbus reloads, in 6.65 seconds)2403subtest: generators2404machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12405machine # [ 122.690017] nixos[5145]: switching to system configuration /nix/store/d72dv72k3j9nn3wqnr13sdn35j1902km-nixos-system-machine-test2406machine # [ 122.693255] systemd[1]: Stopped target Local File Systems.2407machine # [ 122.695252] systemd[1]: Stopped target Remote File Systems.2408machine # [ 123.218610] systemd[1]: Reload requested from client PID 5145 ('.switch-to-conf') (unit backdoor.service)...2409machine # [ 123.220866] systemd[1]: Reloading...2410machine # [ 123.830975] systemd[1]: Reloading finished in 608 ms.2411machine # [ 123.978104] systemd[589]: Reexecution requested from client PID 5216 ('switch-to-confi')...2412machine # [ 123.979464] systemd[589]: Reexecuting.2413machine # [ 124.041880] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2414machine # [ 124.237523] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2415machine # [ 124.244404] systemd[589]: Reload requested from client PID 5216 ('switch-to-confi')...2416machine # [ 124.246908] systemd[589]: Reloading...2417machine # [ 124.437494] systemd[589]: Reloading finished in 189 ms.2418machine # [ 124.452156] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2419machine # [ 124.461873] systemd[589]: Stopped Run user-specific NixOS activation.2420machine # [ 124.462910] systemd[589]: Stopping Run user-specific NixOS activation...2421machine # [ 124.465853] systemd[589]: Starting Run user-specific NixOS activation...2422machine # [ 124.513145] systemd[589]: Finished Run user-specific NixOS activation.2423machine # [ 124.517339] systemd[1]: Stopped target Reactivate sysinit units.2424machine # [ 124.521070] systemd[1]: Stopping Reactivate sysinit units...2425machine # [ 124.522047] systemd[1]: Reached target Reactivate sysinit units.2426machine # [ 124.523202] systemd[1]: Reloading D-Bus System Message Bus...2427machine # [ 124.524752] dbus-broker-launch[489]: Caught SIGHUP, trigger reload.2428machine # [ 124.538916] dbus-broker-launch[489]: Looking up NSS user entry for 'systemd-timesync'...2429machine # [ 124.541050] dbus-broker-launch[489]: NSS returned no entry for 'systemd-timesync'2430machine # [ 124.542181] dbus-broker-launch[489]: Invalid user-name in /nix/store/q9kni4k6lchfc3fafb6a1yk6yvf86s3s-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2431machine # [ 124.568542] systemd[1]: Reloaded D-Bus System Message Bus.2432machine # [ 124.572477] systemd[1]: Reached target Local File Systems.2433machine # [ 124.583134] systemd[1]: Reached target Remote File Systems.2434machine # [ 124.587245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2435machine # [ 124.589736] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2436machine # [ 124.596366] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2437machine # [ 124.605263] systemd[1]: Starting Create SUID/SGID Wrappers...2438machine # [ 124.606487] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2439machine # [ 124.616248] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672440machine # [ 124.619449] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2441machine # [ 124.624547] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2442machine # [ 124.626114] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2443machine # [ 124.959816] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2444machine # [ 124.964827] systemd[1]: Finished Create SUID/SGID Wrappers.2445machine # [ 124.967373] systemd[1]: Starting linger-users.service...2446machine # [ 125.029881] systemd[1]: linger-users.service: Deactivated successfully.2447machine # [ 125.032828] systemd[1]: Finished linger-users.service.2448machine # [ 125.290721] nixos[5145]: finished switching to system configuration /nix/store/d72dv72k3j9nn3wqnr13sdn35j1902km-nixos-system-machine-test2449machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 3.23 seconds)2450machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2451machine # [ 125.376579] systemd[1]: Started simple-generated.service.2452machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.10 seconds)2453machine: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&12454machine # [ 125.952594] nixos[5294]: switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test2455machine # [ 125.956654] systemd[1]: Stopped target Local File Systems.2456machine # [ 125.958405] systemd[1]: Stopped target Remote File Systems.2457machine # [ 126.392772] systemd[1]: Reload requested from client PID 5294 ('.switch-to-conf') (unit backdoor.service)...2458machine # [ 126.394668] systemd[1]: Reloading...2459machine # [ 127.037206] systemd[1]: Reloading finished in 640 ms.2460machine # [ 127.186349] systemd[589]: Reexecution requested from client PID 5364 ('switch-to-confi')...2461machine # [ 127.190612] systemd[589]: Reexecuting.2462machine # [ 127.242332] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2463machine # [ 127.432561] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2464machine # [ 127.439746] systemd[589]: Reload requested from client PID 5364 ('switch-to-confi')...2465machine # [ 127.442615] systemd[589]: Reloading...2466machine # [ 127.636142] systemd[589]: Reloading finished in 192 ms.2467machine # [ 127.652173] systemd[589]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2468machine # [ 127.663714] systemd[589]: Stopped Run user-specific NixOS activation.2469machine # [ 127.664883] systemd[589]: Stopping Run user-specific NixOS activation...2470machine # [ 127.667796] systemd[589]: Starting Run user-specific NixOS activation...2471machine # [ 127.713469] systemd[589]: Finished Run user-specific NixOS activation.2472machine # [ 127.717673] systemd[1]: Stopped target Reactivate sysinit units.2473machine # [ 127.720651] systemd[1]: Stopping Reactivate sysinit units...2474machine # [ 127.721495] systemd[1]: Reached target Reactivate sysinit units.2475machine # [ 127.723303] systemd[1]: Reached target Local File Systems.2476machine # [ 127.727369] systemd[1]: Reached target Remote File Systems.2477machine # [ 127.731290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2478machine # [ 127.737970] systemd[1]: Starting Create SUID/SGID Wrappers...2479machine # [ 127.740965] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2480machine # [ 127.744636] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672481machine # [ 127.748261] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2482machine # [ 127.749848] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2483machine # [ 127.751101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2484machine # [ 127.752631] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2485machine # [ 127.754185] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2486machine # [ 128.082958] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2487machine # [ 128.087100] systemd[1]: Finished Create SUID/SGID Wrappers.2488machine # [ 128.089834] systemd[1]: Starting linger-users.service...2489machine # [ 128.151174] systemd[1]: linger-users.service: Deactivated successfully.2490machine # [ 128.153740] systemd[1]: Finished linger-users.service.2491machine # [ 128.413650] nixos[5294]: finished switching to system configuration /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test2492machine: (finished: must succeed: /nix/store/vhj0fhhxl0hvhij52s7sl7pf5i9far69-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.02 seconds)2493machine: must succeed: systemctl is-active simple-generated.service2494machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.06 seconds)2495(finished: subtest: generators, in 6.41 seconds)2496(finished: run the VM test script, in 129.24 seconds)2497test script finished in 129.30s2498cleanup2499kill QemuMachine (pid 45)2500machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)2501(finished: cleanup, in 0.20 seconds)2502additionally exposed symbols:2503 machine, other,2504 vlan1,2505 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