vm-test-run-switch-test-services
checks.aarch64-linux.switchTest
· build #33
· 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.aRzk3bGSWo', 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: 583bfa1a-fad7-47de-9140-654c1f7ef1dd17machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]27machine # [ 0.000000] Linux version 6.18.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Mon Sep 7 15:23:00 UTC 202628machine # [ 0.000000] KASLR enabled29machine # [ 0.000000] random: crng init done30machine # [ 0.000000] Machine model: linux,dummy-virt31machine # [ 0.000000] efi: UEFI not found.32machine # [ 0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT33machine # [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000007fffffff]34machine # [ 0.000000] NODE_DATA(0) allocated [mem 0x7fded700-0x7fdf0e7f]35machine # [ 0.000000] Zone ranges:36machine # [ 0.000000] DMA [mem 0x0000000040000000-0x000000007fffffff]37machine # [ 0.000000] DMA32 empty38machine # [ 0.000000] Normal empty39machine # [ 0.000000] Device empty40machine # [ 0.000000] Movable zone start for each node41machine # [ 0.000000] Early memory node ranges42machine # [ 0.000000] node 0: [mem 0x0000000040000000-0x000000007fffffff]43machine # [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000007fffffff]44machine # [ 0.000000] cma: Reserved 32 MiB at 0x000000007cc0000045machine # [ 0.000000] psci: probing for conduit method from DT.46machine # [ 0.000000] psci: PSCIv1.3 detected in firmware.47machine # [ 0.000000] psci: Using standard PSCI v0.2 function IDs48machine # [ 0.000000] psci: Trusted OS migration not required49machine # [ 0.000000] psci: SMC Calling Convention v1.150machine # [ 0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)51machine # [ 0.000000] percpu: Embedded 76 pages/cpu s186648 r8192 d116456 u31129652machine # [ 0.000000] Detected PIPT I-cache on CPU053machine # [ 0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)54machine # [ 0.000000] CPU features: detected: GICv3 CPU interface55machine # [ 0.000000] CPU features: detected: Spectre-v456machine # [ 0.000000] CPU features: detected: Spectre-BHB57machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_3858machine # [ 0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_2359machine # [ 0.000000] alternatives: applying boot alternatives60machine # [ 0.000000] Kernel command line: console=ttyAMA0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/init regInfo=/nix/store/6qx1r7b12w7154w32l22wllgqwx08db9-closure-info/registration console=ttyAMA0,115200n8 console=tty061machine # [ 0.000000] Unknown kernel command line parameters "regInfo=/nix/store/6qx1r7b12w7154w32l22wllgqwx08db9-closure-info/registration", will be passed to user space.62machine # [ 0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes63machine # [ 0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)64machine # [ 0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)65machine # [ 0.000000] software IO TLB: SWIOTLB bounce buffer size adjusted to 1MB66machine # [ 0.000000] software IO TLB: area num 1.67machine # [ 0.000000] software IO TLB: mapped [mem 0x000000007ca80000-0x000000007cb80000] (1MB)68machine # [ 0.000000] Fallback order for Node 0: 069machine # [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 26214470machine # [ 0.000000] Policy zone: DMA71machine # [ 0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off72machine # [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=173machine # [ 0.000000] allocated 2097152 bytes of page_ext74machine # [ 0.000000] ftrace: allocating 74885 entries in 294 pages75machine # [ 0.000000] ftrace: allocated 294 pages with 4 groups76machine # [ 0.000000] rcu: Hierarchical RCU implementation.77machine # [ 0.000000] rcu: RCU event tracing is enabled.78machine # [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.79machine # [ 0.000000] Trampoline variant of Tasks RCU enabled.80machine # [ 0.000000] Rude variant of Tasks RCU enabled.81machine # [ 0.000000] Tracing variant of Tasks RCU enabled.82machine # [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.83machine # [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=184machine # [ 0.000000] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.85machine # [ 0.000000] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.86machine # [ 0.000000] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.87machine # [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 088machine # [ 0.000000] GICv3: 256 SPIs implemented89machine # [ 0.000000] GICv3: 0 Extended SPIs implemented90machine # [ 0.000000] Root IRQ handler: gic_handle_irq91machine # [ 0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI92machine # [ 0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=093machine # [ 0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a000094machine # [ 0.000000] ITS [mem 0x08080000-0x0809ffff]95machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Devices @44ce0000 (indirect, esz 8, psz 64K, shr 1)96machine # [ 0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @44cf0000 (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.000033] arm-pv: using stolen time PV105machine # [ 0.000418] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)106machine # [ 0.000590] Console: colour dummy device 80x25107machine # [ 0.000604] printk: legacy console [tty0] enabled108machine # [ 0.000843] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)109machine # [ 0.000850] pid_max: default: 32768 minimum: 301110machine # [ 0.000937] LSM: initializing lsm=capability,landlock,yama,bpf,ima111machine # [ 0.001061] landlock: Up and running.112machine # [ 0.001063] Yama: becoming mindful.113machine # [ 0.001529] LSM support for eBPF active114machine # [ 0.001654] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)115machine # [ 0.001675] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)116machine # [ 0.002824] cacheinfo: Unable to detect cache hierarchy for CPU 0117machine # [ 0.003536] rcu: Hierarchical SRCU implementation.118machine # [ 0.003540] rcu: Max phase no-delay instances is 1000.119machine # [ 0.004787] fsl-mc MSI: its@8080000 domain created120machine # [ 0.004882] EFI services will not be available.121machine # [ 0.004948] smp: Bringing up secondary CPUs ...122machine # [ 0.004956] smp: Brought up 1 node, 1 CPU123machine # [ 0.004960] SMP: Total of 1 processors activated.124machine # [ 0.004962] CPU: All CPU(s) started at EL1125machine # [ 0.004984] CPU features: detected: Branch Target Identification126machine # [ 0.004990] CPU features: detected: ARMv8.4 Translation Table Level127machine # [ 0.004993] CPU features: detected: Instruction cache invalidation not required for I/D coherence128machine # [ 0.004997] CPU features: detected: Data cache clean to the PoU not required for I/D coherence129machine # [ 0.005000] CPU features: detected: Common not Private translations130machine # [ 0.005003] CPU features: detected: CRC32 instructions131machine # [ 0.005006] CPU features: detected: Data cache clean to Point of Deep Persistence132machine # [ 0.005010] CPU features: detected: Data cache clean to Point of Persistence133machine # [ 0.005013] CPU features: detected: Data independent timing control (DIT)134machine # [ 0.005016] CPU features: detected: E0PD135machine # [ 0.005019] CPU features: detected: Enhanced Counter Virtualization136machine # [ 0.005022] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)137machine # [ 0.005025] CPU features: detected: Enhanced Virtualization Traps138machine # [ 0.005028] CPU features: detected: Fine Grained Traps139machine # [ 0.005031] CPU features: detected: Generic authentication (architected QARMA5 algorithm)140machine # [ 0.005036] CPU features: detected: RCpc load-acquire (LDAPR)141machine # [ 0.005038] CPU features: detected: LSE atomic instructions142machine # [ 0.005041] CPU features: detected: Privileged Access Never143machine # [ 0.005044] CPU features: detected: PMUv3144machine # [ 0.005047] CPU features: detected: RAS Extension Support145machine # [ 0.005049] CPU features: detected: RASv1p1 Extension Support146machine # [ 0.005052] CPU features: detected: Random Number Generator147machine # [ 0.005054] CPU features: detected: Speculation barrier (SB)148machine # [ 0.005057] CPU features: detected: Stage-2 Force Write-Back149machine # [ 0.005059] CPU features: detected: TLB range maintenance instructions150machine # [ 0.005064] CPU features: detected: Speculative Store Bypassing Safe (SSBS)151machine # [ 0.005100] alternatives: applying system-wide alternatives152machine # [ 0.008245] CPU features: detected: BBM Level 2 without TLB conflict abort153machine # [ 0.008500] Memory: 894224K/1048576K available (24448K kernel code, 7090K rwdata, 26348K rodata, 4736K init, 1107K bss, 113068K reserved, 32768K cma-reserved)154machine # [ 0.008885] devtmpfs: initialized155machine # [ 0.010666] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)156machine # [ 0.010689] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).157machine # [ 0.010885] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL158machine # [ 0.010890] 0 pages in range for non-PLT usage159machine # [ 0.010890] 508288 pages in range for PLT usage160machine # [ 0.011003] pinctrl core: initialized pinctrl subsystem161machine # [ 0.011761] DMI not present or invalid.162machine # [ 0.014979] NET: Registered PF_NETLINK/PF_ROUTE protocol family163machine # [ 0.018029] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations164machine # [ 0.018183] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations165machine # [ 0.018378] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations166machine # [ 0.018408] audit: initializing netlink subsys (disabled)167machine # [ 0.019032] thermal_sys: Registered thermal governor 'fair_share'168machine # [ 0.019034] thermal_sys: Registered thermal governor 'bang_bang'169machine # [ 0.019038] thermal_sys: Registered thermal governor 'step_wise'170machine # [ 0.019041] thermal_sys: Registered thermal governor 'user_space'171machine # [ 0.019046] thermal_sys: Registered thermal governor 'power_allocator'172machine # [ 0.019078] audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1173machine # [ 0.019086] cpuidle: using governor ladder174machine # [ 0.019092] cpuidle: using governor menu175machine # [ 0.019314] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.176machine # [ 0.019475] ASID allocator initialised with 65536 entries177machine # [ 0.020660] Serial: AMBA PL011 UART driver178machine # [ 0.026202] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1179machine # [ 0.026370] printk: console [ttyAMA0] enabled180machine # [ 0.150266] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages181machine # [ 0.150288] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page182machine # [ 0.150293] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages183machine # [ 0.150297] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page184machine # [ 0.150302] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages185machine # [ 0.150306] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page186machine # [ 0.150310] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages187machine # [ 0.150314] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page188machine # [ 0.158153] fbcon: Taking over console189machine # [ 0.158175] ACPI: Interpreter disabled.190machine # [ 0.166709] iommu: Default domain type: Translated191machine # [ 0.166720] iommu: DMA domain TLB invalidation policy: strict mode192machine # [ 0.167399] SCSI subsystem initialized193machine # [ 0.169477] usbcore: registered new interface driver usbfs194machine # [ 0.169509] usbcore: registered new interface driver hub195machine # [ 0.169534] usbcore: registered new device driver usb196machine # [ 0.169835] pps_core: LinuxPPS API ver. 1 registered197machine # [ 0.169841] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>198machine # [ 0.169850] PTP clock support registered199machine # [ 0.169900] EDAC MC: Ver: 3.0.0200machine # [ 0.174618] scmi_core: SCMI protocol bus registered201machine # [ 0.175673] FPGA manager framework202machine # [ 0.176710] vgaarb: loaded203machine # [ 0.177384] clocksource: Switched to clocksource arch_sys_counter204machine # [ 0.181665] VFS: Disk quotas dquot_6.6.0205machine # [ 0.181711] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)206machine # [ 0.185564] netfs: FS-Cache loaded207machine # [ 0.185693] pnp: PnP ACPI: disabled208machine # [ 0.189817] NET: Registered PF_INET protocol family209machine # [ 0.189979] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)210machine # [ 0.220387] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)211machine # [ 0.220445] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)212machine # [ 0.220471] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)213machine # [ 0.220521] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)214machine # [ 0.220597] TCP: Hash tables configured (established 8192 bind 8192)215machine # [ 0.220683] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)216machine # [ 0.220714] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)217machine # [ 0.220739] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)218machine # [ 0.220848] NET: Registered PF_UNIX/PF_LOCAL protocol family219machine # [ 0.220869] NET: Registered PF_XDP protocol family220machine # [ 0.220891] PCI: CLS 0 bytes, default 64221machine # [ 0.221134] Trying to unpack rootfs image as initramfs...222machine # [ 0.239240] kvm [1]: HYP mode not available223machine # [ 0.346010] Initialise system trusted keyrings224machine # [ 0.346809] workingset: timestamp_bits=42 max_order=18 bucket_order=0225machine # [ 0.348114] squashfs: version 4.0 (2009/01/31) Phillip Lougher226machine # [ 0.348934] 9p: Installing v9fs 9p2000 file system support227machine # [ 0.377721] Key type asymmetric registered228machine # [ 0.377758] Asymmetric key parser 'x509' registered229machine # [ 0.377846] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)230machine # [ 0.380068] io scheduler mq-deadline registered231machine # [ 0.380081] io scheduler kyber registered232machine # [ 0.389579] pl061_gpio 9030000.pl061: PL061 GPIO chip registered233machine # [ 0.391146] ledtrig-cpu: registered to indicate activity on CPUs234machine # [ 0.391541] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:235machine # [ 0.391558] pci-host-generic 4010000000.pcie: IO 0x003eff0000..0x003effffff -> 0x0000000000236machine # [ 0.391571] pci-host-generic 4010000000.pcie: MEM 0x0010000000..0x003efeffff -> 0x0010000000237machine # [ 0.391580] pci-host-generic 4010000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000238machine # [ 0.391602] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits239machine # [ 0.391628] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]240machine # [ 0.391707] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00241machine # [ 0.391717] pci_bus 0000:00: root bus resource [bus 00-ff]242machine # [ 0.391723] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]243machine # [ 0.391729] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]244machine # [ 0.391734] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]245machine # [ 0.391800] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint246machine # [ 0.392265] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint247machine # [ 0.392459] pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f]248machine # [ 0.392477] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]249machine # [ 0.392511] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]250machine # [ 0.392528] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]251machine # [ 0.393028] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint252machine # [ 0.393219] pci 0000:00:02.0: BAR 0 [io 0x0000-0x001f]253machine # [ 0.393236] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]254machine # [ 0.393268] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]255machine # [ 0.413739] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint256machine # [ 0.413933] pci 0000:00:03.0: BAR 0 [io 0x0000-0x003f]257machine # [ 0.413949] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]258machine # [ 0.413982] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]259machine # [ 0.414455] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint260machine # [ 0.414643] pci 0000:00:04.0: BAR 0 [io 0x0000-0x001f]261machine # [ 0.414659] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]262machine # [ 0.414689] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]263machine # [ 0.415158] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint264machine # [ 0.415348] pci 0000:00:05.0: BAR 0 [io 0x0000-0x001f]265machine # [ 0.415366] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]266machine # [ 0.415398] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]267machine # [ 0.415863] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint268machine # [ 0.416059] pci 0000:00:06.0: BAR 0 [io 0x0000-0x007f]269machine # [ 0.416075] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]270machine # [ 0.416105] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]271machine # [ 0.416576] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint272machine # [ 0.416783] pci 0000:00:07.0: BAR 0 [io 0x0000-0x001f]273machine # [ 0.416800] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]274machine # [ 0.416830] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]275machine # [ 0.416850] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]276machine # [ 0.417330] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint277machine # [ 0.441642] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]278machine # [ 0.441700] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]279machine # [ 0.442239] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint280machine # [ 0.442436] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]281machine # [ 0.442466] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]282machine # [ 0.442861] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint283machine # [ 0.443049] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]284machine # [ 0.443300] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint285machine # [ 0.443630] pci 0000:00:0b.0: BAR 0 [io 0x0000-0x003f]286machine # [ 0.443648] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]287machine # [ 0.443679] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]288machine # [ 0.444156] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint289machine # [ 0.444342] pci 0000:00:0c.0: BAR 0 [io 0x0000-0x001f]290machine # [ 0.444358] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]291machine # [ 0.444394] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]292machine # [ 0.445033] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned293machine # [ 0.445045] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned294machine # [ 0.445051] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned295machine # [ 0.445098] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned296machine # [ 0.445147] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned297machine # [ 0.445195] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned298machine # [ 0.445243] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned299machine # [ 0.445291] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned300machine # [ 0.445339] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned301machine # [ 0.445409] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned302machine # [ 0.445456] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned303machine # [ 0.445511] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned304machine # [ 0.445579] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned305machine # [ 0.445661] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned306machine # [ 0.445686] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned307machine # [ 0.445707] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned308machine # [ 0.445731] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned309machine # [ 0.445754] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned310machine # [ 0.445780] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned311machine # [ 0.445804] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned312machine # [ 0.445827] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned313machine # [ 0.445850] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned314machine # [ 0.445872] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned315machine # [ 0.445895] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned316machine # [ 0.445918] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned317machine # [ 0.445943] pci 0000:00:06.0: BAR 0 [io 0x1000-0x107f]: assigned318machine # [ 0.445966] pci 0000:00:03.0: BAR 0 [io 0x1080-0x10bf]: assigned319machine # [ 0.445988] pci 0000:00:0b.0: BAR 0 [io 0x10c0-0x10ff]: assigned320machine # [ 0.446010] pci 0000:00:01.0: BAR 0 [io 0x1100-0x111f]: assigned321machine # [ 0.446033] pci 0000:00:02.0: BAR 0 [io 0x1120-0x113f]: assigned322machine # [ 0.446059] pci 0000:00:04.0: BAR 0 [io 0x1140-0x115f]: assigned323machine # [ 0.446085] pci 0000:00:05.0: BAR 0 [io 0x1160-0x117f]: assigned324machine # [ 0.446108] pci 0000:00:07.0: BAR 0 [io 0x1180-0x119f]: assigned325machine # [ 0.446131] pci 0000:00:0c.0: BAR 0 [io 0x11a0-0x11bf]: assigned326machine # [ 0.446159] pci_bus 0000:00: resource 4 [io 0x0000-0xffff]327machine # [ 0.446169] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]328machine # [ 0.446174] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]329machine # [ 0.447390] pci 0000:00:0a.0: enabling device (0000 -> 0002)330machine # [ 0.520108] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)331machine # [ 0.526589] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)332machine # [ 0.528649] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)333machine # [ 0.538892] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)334machine # [ 0.541147] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)335machine # [ 0.551916] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)336machine # [ 0.558052] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)337machine # [ 0.560265] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)338machine # [ 0.570391] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)339machine # [ 0.572461] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)340machine # [ 0.584377] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)341machine # [ 0.594853] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled342machine # [ 0.602753] msm_serial: driver initialized343machine # [ 0.602909] SuperH (H)SCI(F) driver initialized344machine # [ 0.602963] STM32 USART driver initialized345machine # [ 0.634836] loop: module loaded346machine # [ 0.635062] virtio_blk virtio5: 1/0/0 default/read/poll queues347machine # [ 0.635865] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)348machine # [ 0.646173] megasas: 07.734.00.00-rc1349machine # [ 0.646956] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]350machine # [ 0.657491] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000351machine # [ 0.657530] Intel/Sharp Extended Query Table at 0x0031352machine # [ 0.659383] Using buffer write method353machine # [ 0.659493] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]354machine # [ 0.661486] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000355machine # [ 0.661513] Intel/Sharp Extended Query Table at 0x0031356machine # [ 0.663309] Using buffer write method357machine # [ 0.663347] Concatenating MTD devices:358machine # [ 0.663352] (0): "0.flash"359machine # [ 0.663356] (1): "0.flash"360machine # [ 0.663359] into device "0.flash"361machine # [ 0.973998] Freeing initrd memory: 26208K362machine # [ 0.980574] tun: Universal TUN/TAP device driver, 1.6363machine # [ 0.984780] thunder_xcv, ver 1.0364machine # [ 0.984828] thunder_bgx, ver 1.0365machine # [ 0.984851] nicpf, ver 1.0366machine # [ 0.986596] e1000: Intel(R) PRO/1000 Network Driver367machine # [ 0.986607] e1000: Copyright (c) 1999-2006 Intel Corporation.368machine # [ 0.986646] e1000e: Intel(R) PRO/1000 Network Driver369machine # [ 0.986656] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.370machine # [ 0.986681] igb: Intel(R) Gigabit Ethernet Network Driver371machine # [ 0.986686] igb: Copyright (c) 2007-2014 Intel Corporation.372machine # [ 0.986709] igbvf: Intel(R) Gigabit Virtual Function Network Driver373machine # [ 0.986715] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.374machine # [ 0.986861] sky2: driver version 1.30375machine # [ 0.988536] usbcore: registered new interface driver usb-storage376machine # [ 0.988594] usbcore: registered new interface driver usbserial_generic377machine # [ 0.988610] usbserial: USB Serial support registered for generic378machine # [ 0.989327] hv_vmbus: registering driver hyperv_keyboard379machine # [ 0.990187] ehci-pci 0000:00:0a.0: EHCI Host Controller380machine # [ 0.990223] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1381machine # [ 0.990495] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000382machine # [ 1.002097] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00383machine # [ 1.002475] hub 1-0:1.0: USB hub found384machine # [ 1.002492] hub 1-0:1.0: 6 ports detected385machine # [ 1.005698] rtc-pl031 9010000.pl031: registered as rtc0386machine # [ 1.005735] rtc-pl031 9010000.pl031: setting system clock to 2026-09-12T02:45:38 UTC (1789181138)387machine # [ 1.006055] i2c_dev: i2c /dev entries driver388machine # [ 1.011434] sdhci: Secure Digital Host Controller Interface driver389machine # [ 1.011453] sdhci: Copyright(c) Pierre Ossman390machine # [ 1.011722] Synopsys Designware Multimedia Card Interface Driver391machine # [ 1.012100] sdhci-pltfm: SDHCI platform and OF driver helper392machine # [ 1.016832] hid: raw HID events driver (C) Jiri Kosina393machine # [ 1.017091] usbcore: registered new interface driver usbhid394machine # [ 1.017100] usbhid: USB HID core driver395machine # [ 1.020135] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available396machine # [ 1.023127] drop_monitor: Initializing network drop monitor service397machine # [ 1.023289] NET: Registered PF_INET6 protocol family398machine # [ 1.025322] Segment Routing with IPv6399machine # [ 1.025343] In-situ OAM (IOAM) with IPv6400machine # [ 1.025376] NET: Registered PF_PACKET protocol family401machine # [ 1.027297] 9pnet: Installing 9P2000 support402machine # [ 1.030415] Key type dns_resolver registered403machine # [ 1.037175] registered taskstats version 1404machine # [ 1.037346] Loading compiled-in X.509 certificates405machine # [ 1.046225] Demotion targets for Node 0: null406machine # [ 1.046367] Key type .fscrypt registered407machine # [ 1.046374] Key type fscrypt-provisioning registered408machine # [ 1.046478] ima: No TPM chip found, activating TPM-bypass!409machine # [ 1.046502] ima: Allocated hash algorithm: sha1410machine # [ 1.046534] ima: No architecture policies found411machine # [ 1.051167] input: gpio-keys as /devices/platform/gpio-keys/input/input0412machine # [ 1.071846] clk: Disabling unused clocks413machine # [ 1.071882] PM: genpd: Disabling unused power domains414machine # [ 1.076505] Freeing unused kernel memory: 4736K415machine # [ 1.076766] Run /init as init process416machine # [ 1.091850] systemd[1]: Successfully made /usr/ read-only.417machine # [ 1.249490] usb 1-1: new high-speed USB device number 2 using ehci-pci418machine # [ 1.406210] 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.426991] systemd[1]: systemd 262-b504aa0 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.439632] systemd[1]: Detected virtualization qemu.421machine # [ 1.441859] systemd[1]: Detected architecture arm64.422machine # [ 1.443788] systemd[1]: Running in initrd.423machine # [ 1.446904] systemd[1]: Initializing machine ID from random generator.424machine # [ 1.450072] systemd[1]: Hostname set to <machine>.425machine # [ 1.501757] 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.620941] systemd[1]: bpf-restrict-fs: LSM BPF program attached427machine # [ 1.625707] usb 1-2: new high-speed USB device number 3 using ehci-pci428machine # [ 1.682968] systemd[1]: Queued start job for default target Initrd Default Target.429machine # [ 1.691845] systemd[1]: Created slice Slice /system/modprobe.430machine # [ 1.692928] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.431machine # [ 1.694052] systemd[1]: Expecting device /dev/disk/by-label/nixos...432machine # [ 1.694875] systemd[1]: Reached target Path Units.433machine # [ 1.695516] systemd[1]: Reached target Slice Units.434machine # [ 1.696175] systemd[1]: Reached target Swaps.435machine # [ 1.696779] systemd[1]: Reached target Timer Units.436machine # [ 1.697687] systemd[1]: Listening on D-Bus System Message Bus Socket.437machine # [ 1.698707] systemd[1]: Listening on Journal Socket (/dev/log).438machine # [ 1.699668] systemd[1]: Listening on Journal Sockets.439machine # [ 1.700475] systemd[1]: Listening on udev Kernel Socket.440machine # [ 1.701243] systemd[1]: Reached target Socket Units.441machine # [ 1.703598] systemd[1]: Starting Create List of Static Device Nodes...442machine # [ 1.713598] systemd[1]: Starting Load Kernel Module 9pnet_virtio...443machine # [ 1.714593] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs444machine # [ 1.717530] systemd[1]: Mounting Kernel Configuration File System...445machine # [ 1.728679] systemd[1]: Starting Journal Service...446machine # [ 1.753112] systemd[1]: Starting Load Kernel Modules...447machine # [ 1.754486] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os448machine # [ 1.781788] systemd[1]: Starting Coldplug All udev Devices...449machine # [ 1.785560] systemd[1]: Finished Create List of Static Device Nodes.450machine # [ 1.787046] 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/input2451machine # [ 1.787186] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0452machine # [ 1.792080] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.453machine # [ 1.792670] systemd-journald[73]: Collecting audit messages is disabled.454machine # [ 1.798214] systemd[1]: Finished Load Kernel Module 9pnet_virtio.455machine # [ 1.799467] systemd[1]: Mounted Kernel Configuration File System.456machine # [ 1.817950] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...457machine # [ 1.847865] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.458machine # [ 1.873462] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev459machine # [ 1.878475] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0460machine # [ 1.878726] [drm] features: -virgl +edid -resource_blob -host_visible461machine # [ 1.878736] [drm] features: -context_init462machine # [ 1.879519] [drm] number of scanouts: 1463machine # [ 1.879539] [drm] number of cap sets: 0464machine # [ 1.894220] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.465machine # [ 1.897105] systemd[1]: Starting Create Static Device Nodes in /dev...466machine # [ 1.901919] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic467machine # [ 1.901947] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0468machine # [ 1.937805] Console: switching to colour frame buffer device 160x50469machine # [ 1.944688] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device470machine # [ 1.966218] systemd[1]: Finished Create Static Device Nodes in /dev.471machine # [ 1.966478] systemd[1]: Reached target Preparation for Local File Systems.472machine # [ 1.966513] systemd[1]: Reached target Local File Systems.473machine # [ 1.973816] systemd[1]: Starting Rule-based Manager for Device Events and Files...474machine # [ 1.975028] systemd-modules-load[74]: Inserted module 'dm_mod'475machine # [ 1.997755] systemd[1]: Started Journal Service.476machine # [ 1.984298] systemd-modules-load[74]: Module 'virtio_balloon' is built in477machine # [ 1.985496] systemd-modules-load[74]: Module 'virtio_console' is built in478machine # [ 1.986650] systemd-modules-load[74]: Inserted module 'virtio_gpu'479machine # [ 1.987662] systemd-modules-load[74]: Module 'virtio_rng' is built in480machine # [ 2.000473] systemd[1]: Finished Load Kernel Modules.481machine # [ 2.001415] systemd[1]: Starting Apply Kernel Variables...482machine # [ 2.009437] systemd[1]: Starting Create System Files and Directories...483machine # [ 2.077114] systemd[1]: Finished Create System Files and Directories.484machine # [ 2.082973] systemd-udevd[79]: Using default interface naming scheme 'v261'.485machine # [ 2.088382] systemd[1]: Finished Apply Kernel Variables.486machine # [ 2.121331] systemd[1]: Started Rule-based Manager for Device Events and Files.487machine # [ 2.193802] systemd[1]: Starting Virtual Console Setup...488machine # [ 2.282125] systemd-vconsole-setup[103]: Configuration of first virtual console was skipped, ignoring remaining ones.489machine # [ 2.288257] systemd[1]: Finished Virtual Console Setup.490machine # [ 2.936534] systemd[1]: Finished Coldplug All udev Devices.491machine # [ 2.938273] systemd[1]: Reached target System Initialization.492machine # [ 2.939167] systemd[1]: Reached target Basic System.493machine # [ 3.116491] (udev-worker)[98]: Network interface NamePolicy= disabled on kernel command line.494machine # [ 3.120788] (udev-worker)[99]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.495machine # [ 3.126819] (udev-worker)[99]: Network interface NamePolicy= disabled on kernel command line.496machine # [ 3.182406] systemd[1]: Found device /dev/disk/by-label/nixos.497machine # [ 3.185996] systemd[1]: Reached target Initrd Root Device.498machine # [ 3.192503] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...499machine # [ 3.245070] systemd-fsck[112]: nixos: clean, 12/65536 files, 13019/262144 blocks500machine # [ 3.251948] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.501machine # [ 3.267542] systemd[1]: Mounting /sysroot...502machine # [ 3.324977] EXT4-fs (vda): mounted filesystem 583bfa1a-fad7-47de-9140-654c1f7ef1dd r/w with ordered data mode. Quota mode: none.503machine # [ 3.313242] systemd[1]: Mounted /sysroot.504machine # [ 3.316555] systemd[1]: Reached target Initrd Root File System.505machine # [ 3.319428] systemd[1]: Starting Mountpoints Configured in the Real Root...506machine # [ 3.355272] systemd-sysroot-fstab-check[120]: /sysroot should be mounted in the initrd, will request daemon-reload.507machine # [ 3.363302] systemd[1]: Reload requested from client PID 120 ('systemd-sysroot') (unit initrd-parse-etc.service)...508machine # [ 3.367164] systemd[1]: Reloading...509machine # [ 3.572704] systemd[1]: Reloading finished in 209 ms.510machine # [ 3.603253] systemd-sysroot-fstab-check[120]: Requesting initrd-fs.target/start/replace...511machine # [ 3.607328] systemd-sysroot-fstab-check[120]: Requesting swap.target/start/replace...512machine # [ 3.622123] systemd[1]: Starting Load Kernel Module 9pnet_virtio...513machine # [ 3.624226] systemd[1]: initrd-parse-etc.service: Deactivated successfully.514machine # [ 3.631603] systemd[1]: Finished Mountpoints Configured in the Real Root.515machine # [ 3.632849] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.516machine # [ 3.655144] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.517machine # [ 3.657356] systemd[1]: Finished Load Kernel Module 9pnet_virtio.518machine # [ 3.770621] systemd[1]: Mounting /sysroot/nix/.ro-store...519machine # [ 3.781614] systemd[1]: Mounting /sysroot/nix/.rw-store...520machine # [ 3.797916] systemd[1]: Mounting /sysroot/run...521machine # [ 3.833347] systemd[1]: Mounting /sysroot/tmp/shared...522machine # [ 3.846773] systemd[1]: Mounting /sysroot/tmp/xchg...523machine # [ 3.876108] systemd[1]: Mounted /sysroot/nix/.ro-store.524machine # [ 3.893468] systemd[1]: Mounted /sysroot/nix/.rw-store.525machine # [ 3.909971] systemd[1]: Mounted /sysroot/run.526machine # [ 3.929669] systemd[1]: Starting rw-sysroot-nix-store.service...527machine # [ 3.934309] systemd[1]: Mounted /sysroot/tmp/shared.528machine # [ 3.947402] systemd[1]: Mounted /sysroot/tmp/xchg.529machine # [ 3.968145] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.530machine # [ 3.971480] systemd[1]: Finished rw-sysroot-nix-store.service.531machine # [ 3.974198] systemd[1]: Mounting /sysroot/nix/store...532machine # [ 4.021153] systemd[1]: Mounted /sysroot/nix/store.533machine # [ 4.022317] systemd[1]: Reached target Initrd File Systems.534machine # [ 4.025036] systemd[1]: Starting Find NixOS closure...535machine # [ 4.048889] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...536machine # [ 4.082836] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.537machine # [ 4.094359] systemd[1]: Finished Find NixOS closure.538machine # [ 4.096161] systemd[1]: Reached target Initrd Default Target.539machine # [ 4.100314] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...540machine # [ 4.130726] systemd[1]: Stopped target Initrd Default Target.541machine # [ 4.134239] systemd[1]: Stopped target Basic System.542machine # [ 4.135829] systemd[1]: Stopped target Initrd Root Device.543machine # [ 4.141449] systemd[1]: Stopped target Path Units.544machine # [ 4.142219] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.545machine # [ 4.143292] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.546machine # [ 4.148265] systemd[1]: Stopped target Slice Units.547machine # [ 4.151460] systemd[1]: Stopped target Socket Units.548machine # [ 4.153412] systemd[1]: Stopped target System Initialization.549machine # [ 4.156764] systemd[1]: Stopped target Swaps.550machine # [ 4.158260] systemd[1]: Stopped target Timer Units.551machine # [ 4.161188] systemd[1]: dbus.socket: Deactivated successfully.552machine # [ 4.164511] systemd[1]: Closed D-Bus System Message Bus Socket.553machine # [ 4.173251] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.554machine # [ 4.174368] systemd[1]: Stopped Find NixOS closure.555machine # [ 4.175096] systemd[1]: Starting Load Kernel Module 9pnet_virtio...556machine # [ 4.184129] systemd[1]: Starting rw-sysroot-nix-store.service...557machine # [ 4.185817] systemd[1]: systemd-sysctl.service: Deactivated successfully.558machine # [ 4.192344] systemd[1]: Stopped Apply Kernel Variables.559machine # [ 4.194347] systemd[1]: systemd-modules-load.service: Deactivated successfully.560machine # [ 4.199051] systemd[1]: Stopped Load Kernel Modules.561machine # [ 4.205399] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.562machine # [ 4.208391] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.563machine # [ 4.210904] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.564machine # [ 4.220310] systemd[1]: Stopped Create System Files and Directories.565machine # [ 4.223198] systemd[1]: Stopped target Local File Systems.566machine # [ 4.225988] systemd[1]: Stopped target Preparation for Local File Systems.567machine # [ 4.227033] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.568machine # [ 4.234966] systemd[1]: Stopped Coldplug All udev Devices.569machine # [ 4.235905] systemd[1]: Stopping Rule-based Manager for Device Events and Files...570machine # [ 4.240927] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.571machine # [ 4.242457] systemd[1]: Stopped Virtual Console Setup.572machine # [ 4.244263] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.573machine # [ 4.247524] systemd[1]: Finished Load Kernel Module 9pnet_virtio.574machine # [ 4.249021] systemd[1]: initrd-cleanup.service: Deactivated successfully.575machine # [ 4.252284] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.576machine # [ 4.253281] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.577machine # [ 4.256458] systemd[1]: Finished rw-sysroot-nix-store.service.578machine # [ 4.257369] systemd[1]: systemd-udevd.service: Deactivated successfully.579machine # [ 4.259713] systemd[1]: Stopped Rule-based Manager for Device Events and Files.580machine # [ 4.261088] systemd[1]: systemd-udevd.service: Consumed 1.022s CPU time over 2.289s wall clock time, 15.7M memory peak.581machine # [ 4.262519] systemd[1]: Starting Cleanup udev Database...582machine # [ 4.263286] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.583machine # [ 4.264461] systemd[1]: Stopped Create Static Device Nodes in /dev.584machine # [ 4.265344] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.585machine # [ 4.266558] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.586machine # [ 4.267573] systemd[1]: kmod-static-nodes.service: Deactivated successfully.587machine # [ 4.272282] systemd[1]: Stopped Create List of Static Device Nodes.588machine # [ 4.296732] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.589machine # [ 4.300551] systemd[1]: Finished Cleanup udev Database.590machine # [ 4.301381] systemd[1]: Reached target Switch Root.591machine # [ 4.303152] systemd[1]: Starting NixOS Activation...592machine # [ 4.493422] initrd-nixos-activation-start[172]: booting system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test593machine # [ 4.575075] initrd-nixos-activation-start[172]: running activation script...594machine # [ 5.024700] initrd-nixos-activation-start[195]: setting up /etc...595machine # [ 5.307356] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.596machine # [ 5.310449] systemd[1]: Finished NixOS Activation.597machine # [ 5.316279] systemd[1]: Starting Switch Root...598machine # [ 5.336944] systemd[1]: Switching root.599machine # [ 5.537834] systemd-journald[73]: Received SIGTERM from PID 1 (systemd).600machine # [ 6.156454] systemd[1]: systemd 262-b504aa0 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)601machine # [ 6.168882] systemd[1]: Detected virtualization qemu.602machine # [ 6.172280] systemd[1]: Detected architecture arm64.603machine # [ 6.176832] systemd[1]: Detected first boot.604machine # [ 6.184630] systemd[1]: Initializing machine ID from random generator.605machine # [ 6.335097] systemd[1]: bpf-restrict-fs: LSM BPF program attached606machine # [ 6.510624] systemd[1]: Applying preset policy.607machine # [ 6.990993] systemd[1]: Populated /etc with preset unit settings.608machine # [ 7.523297] systemd[1]: initrd-switch-root.service: Deactivated successfully.609machine # [ 7.524767] systemd[1]: Stopped initrd-switch-root.service.610machine # [ 7.528750] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.611machine # [ 7.533709] systemd[1]: Created slice Slice /system/getty.612machine # [ 7.535578] systemd[1]: Created slice User and Session Slice.613machine # [ 7.536623] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.614machine # [ 7.539615] systemd[1]: Started Forward Password Requests to Wall Directory Watch.615machine # [ 7.541706] systemd[1]: Expecting device /dev/hvc0...616machine # [ 7.543053] systemd[1]: Expecting device /dev/ttyAMA0...617machine # [ 7.544696] systemd[1]: Reached target Local Encrypted Volumes.618machine # [ 7.547214] systemd[1]: Stopped target initrd-fs.target.619machine # [ 7.549205] systemd[1]: Stopped target initrd-root-fs.target.620machine # [ 7.551258] systemd[1]: Stopped target initrd-switch-root.target.621machine # [ 7.553379] systemd[1]: Reached target Virtual Machines and Containers.622machine # [ 7.555642] systemd[1]: Reached target Path Units.623machine # [ 7.557661] systemd[1]: Reached target Remote File Systems.624machine # [ 7.559675] systemd[1]: Reached target Slice Units.625machine # [ 7.561732] systemd[1]: Reached target Swaps.626machine # [ 7.567418] systemd[1]: Listening on Query the User Interactively for a Password.627machine # [ 7.572928] systemd[1]: Listening on Process Core Dump Socket.628machine # [ 7.577472] systemd[1]: Listening on Credential Encryption/Decryption.629machine # [ 7.581869] systemd[1]: Listening on Factory Reset Management.630machine # [ 7.583271] systemd[1]: Listening on Hostname Service Socket.631machine # [ 7.590311] systemd[1]: Starting Journal Log Access Socket...632machine # [ 7.592265] systemd[1]: Listening on Journal Audit Socket.633machine # [ 7.596801] systemd[1]: Listening on Console Output Muting Service Socket.634machine # [ 7.598739] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.635machine # [ 7.600832] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os636machine # [ 7.603910] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki637machine # [ 7.616939] systemd[1]: Listening on Disk Repartitioning Service Socket.638machine # [ 7.618746] systemd[1]: Listening on udev Varlink Socket.639machine # [ 7.625117] systemd[1]: Mounting Huge Pages File System...640machine # [ 7.630494] systemd[1]: Mounting POSIX Message Queue File System...641machine # [ 7.642788] systemd[1]: Mounting Kernel Debug File System...642machine # [ 7.651629] systemd[1]: Mounting Kernel Trace File System...643machine # [ 7.668947] systemd[1]: Starting Create List of Static Device Nodes...644machine # [ 7.684578] systemd[1]: Starting Load Kernel Module 9pnet_virtio...645machine # [ 7.686282] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs646machine # [ 7.705617] systemd[1]: Mounting Kernel Configuration File System...647machine # [ 7.707609] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm648machine # [ 7.710445] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore649machine # [ 7.733996] systemd[1]: Starting Load Kernel Module fuse...650machine # [ 7.736912] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67651machine # [ 7.797244] systemd[1]: Starting Journal Service...652machine # [ 7.841755] systemd[1]: Starting Load Kernel Modules...653machine # [ 7.870736] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...654machine # [ 7.931760] systemd-journald[268]: Collecting audit messages is enabled.655machine # [ 7.949831] systemd[1]: Starting Remount Root and Kernel File Systems...656machine # [ 7.935151] systemd[1]: Queued start job for default target Multi-User System.657machine # [ 7.938168] systemd[1]: systemd-journald.service: Deactivated successfully.658machine # [ 7.957905] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os659machine # [ 7.982854] fuse: init (API version 7.45)660machine # [ 7.987193] systemd[1]: Starting Coldplug All udev Devices...661machine # [ 7.979081] systemd-modules-load[269]: Module 'atkbd' is built in662machine # [ 7.982709] systemd-modules-load[269]: Module 'loop' is built in663machine # [ 8.005159] systemd[1]: Started Journal Service.664machine # [ 8.009027] systemd[1]: Listening on Journal Log Access Socket.665machine # [ 8.014178] systemd[1]: Mounted Huge Pages File System.666machine # [ 8.017783] systemd[1]: Mounted POSIX Message Queue File System.667machine # [ 8.024955] systemd[1]: Mounted Kernel Debug File System.668machine # [ 8.028306] systemd[1]: Mounted Kernel Trace File System.669machine # [ 8.035535] systemd[1]: Finished Create List of Static Device Nodes.670machine # [ 8.041078] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.671machine # [ 8.060136] systemd[1]: Finished Load Kernel Module 9pnet_virtio.672machine # [ 8.061300] systemd[1]: Mounted Kernel Configuration File System.673machine # [ 8.062281] systemd[1]: modprobe@fuse.service: Deactivated successfully.674machine # [ 8.063285] systemd[1]: Finished Load Kernel Module fuse.675machine # [ 8.072157] systemd[1]: Finished Load Kernel Modules.676machine # [ 8.095042] systemd[1]: Mounting FUSE Control File System...677machine # [ 8.121794] EXT4-fs (vda): re-mounted 583bfa1a-fad7-47de-9140-654c1f7ef1dd.678machine # [ 8.126318] systemd[1]: Starting Firewall...679machine # [ 8.144202] systemd[1]: Starting Apply Kernel Variables...680machine # [ 8.146379] systemd-oomd[271]: No swap; memory pressure usage will be degraded681machine # [ 8.171141] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...682machine # [ 8.192878] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.683machine # [ 8.210921] systemd[1]: Finished Remount Root and Kernel File Systems.684machine # [ 8.237742] systemd[1]: Listening on Disk Image Download Service Socket.685machine # [ 8.249635] systemd[1]: Starting Flush Journal to Persistent Storage...686machine # [ 8.253749] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore687machine # [ 8.283118] systemd[1]: Starting Load/Save OS Random Seed...688machine # [ 8.292621] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os689machine # [ 8.338122] systemd[1]: Mounted FUSE Control File System.690machine # [ 8.350525] systemd[1]: Finished Apply Kernel Variables.691machine # [ 8.388449] systemd-journald[268]: Received client request to flush runtime journal.692machine # [ 8.448733] systemd[1]: Finished Load/Save OS Random Seed.693machine # [ 8.456124] systemd[1]: Reached target First Boot Complete.694machine # [ 8.458062] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.695machine # [ 8.461803] systemd[1]: Starting Create Static Device Nodes in /dev...696machine # [ 8.465898] systemd[1]: Finished Flush Journal to Persistent Storage.697machine # [ 8.552745] systemd[1]: Finished Create Static Device Nodes in /dev.698machine # [ 8.557209] systemd[1]: Reached target Preparation for Local File Systems.699machine # [ 8.567544] systemd[1]: Mounting /run/wrappers...700machine # [ 8.570499] systemd[1]: Starting Rule-based Manager for Device Events and Files...701machine # [ 8.652270] systemd[1]: Mounted /run/wrappers.702machine # [ 8.655819] systemd[1]: Reached target Local File Systems.703machine # [ 8.661579] systemd[1]: Listening on Boot Loader Control Service Socket.704machine # [ 8.666979] systemd[1]: Starting Create SUID/SGID Wrappers...705machine # [ 8.671880] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.706machine # [ 8.684454] systemd[1]: Starting Save Transient machine-id to Disk...707machine # [ 8.703549] systemd[1]: Starting Create System Files and Directories...708machine # [ 8.850124] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.709machine # [ 8.856561] systemd[1]: Finished Save Transient machine-id to Disk.710machine # [ 8.918119] systemd[1]: Finished Create System Files and Directories.711machine # [ 8.928729] systemd[1]: Starting Rebuild Journal Catalog...712machine # [ 8.944542] systemd[1]: Starting Record System Boot/Shutdown in UTMP...713machine # [ 9.062235] systemd[1]: Finished Record System Boot/Shutdown in UTMP.714machine # [ 9.139136] systemd[1]: Finished Rebuild Journal Catalog.715machine # [ 9.144591] systemd-udevd[313]: Using default interface naming scheme 'v261'.716machine # [ 9.154320] systemd[1]: Starting Update is Completed...717machine # [ 9.240369] systemd[1]: Finished Update is Completed.718machine # [ 9.376334] systemd[1]: Started Rule-based Manager for Device Events and Files.719machine # [ 9.662660] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.720machine # [ 9.669870] systemd[1]: Finished Create SUID/SGID Wrappers.721machine # [ 10.093736] systemd[1]: Finished Coldplug All udev Devices.722machine # [ 10.096151] systemd[1]: Reached target System Initialization.723machine # [ 10.101709] systemd[1]: Started Discard unused filesystem blocks once a week.724machine # [ 10.109093] systemd[1]: Started Daily Cleanup of Temporary Directories.725machine # [ 10.110197] systemd[1]: Reached target Timer Units.726machine # [ 10.114226] systemd[1]: Listening on D-Bus System Message Bus Socket.727machine # [ 10.124186] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.728machine # [ 10.126311] systemd[1]: Reached target Socket Units.729machine # [ 10.127604] systemd[1]: Reached target Basic System.730machine # [ 10.131633] systemd[1]: Starting Import lastlog data into lastlog2 database...731machine # [ 10.143464] systemd[1]: Starting Name Service Cache Daemon (nsncd)...732machine # [ 10.153017] systemd[1]: Starting Post-Boot Actions...733machine # [ 10.180990] systemd[1]: Started Reset console on configuration changes.734machine # [ 10.213757] systemd[1]: Starting resolvconf update...735machine # [ 10.262971] nsncd[452]: Sep 12 02:45:47.773 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"736machine # [ 10.296325] systemd[1]: Starting D-Bus System Message Bus...737machine # [ 10.310414] systemd[1]: Started Name Service Cache Daemon (nsncd).738machine # [ 10.338811] systemd[1]: Finished Post-Boot Actions.739machine # [ 10.349865] systemd[1]: Reached target Host and Network Name Lookups.740machine # [ 10.360273] systemd[1]: Reached target User and Group Name Lookups.741machine # [ 10.372889] systemd[1]: Starting User Login Management...742machine # [ 10.382436] systemd[1]: Finished Import lastlog data into lastlog2 database.743machine # [ 10.580779] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'...744machine # [ 10.589506] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync'745machine # [ 10.596610] dbus-broker-launch[477]: Invalid user-name in /nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"746machine # [ 10.606773] systemd[1]: Finished Firewall.747machine # [ 10.618025] systemd-logind[482]: New seat seat0.748machine # [ 10.623568] systemd[1]: Started User Login Management.749machine # [ 10.629816] systemd[1]: Starting linger-users.service...750machine # [ 10.649754] systemd[1]: Started D-Bus System Message Bus.751machine # [ 10.700747] dbus-broker-launch[477]: Ready752machine # [ 10.721812] systemd[1]: Stopped target Host and Network Name Lookups.753machine # [ 10.726790] systemd[1]: Stopping Host and Network Name Lookups...754machine # [ 10.734801] systemd[1]: Stopped target User and Group Name Lookups.755machine # [ 10.739042] systemd[1]: Stopping User and Group Name Lookups...756machine # [ 10.746791] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...757machine # [ 10.751916] systemd[1]: nscd.service: Deactivated successfully.758machine # [ 10.757852] systemd[1]: Stopped Name Service Cache Daemon (nsncd).759machine # [ 10.769493] systemd[1]: Starting Name Service Cache Daemon (nsncd)...760machine # [ 10.839954] systemd[1]: Created slice Slice /user/1001.761machine # [ 10.858224] systemd[1]: Starting User Runtime Directory /run/user/1001...762machine # [ 10.902184] systemd[1]: linger-users.service: Deactivated successfully.763machine # [ 10.919614] systemd[1]: Finished linger-users.service.764machine # [ 10.972867] systemd[1]: Finished User Runtime Directory /run/user/1001.765machine # [ 11.000611] systemd[1]: Starting User Manager for UID 1001...766machine # [ 11.027059] systemd[1]: Started Name Service Cache Daemon (nsncd).767machine # [ 11.048100] systemd[1]: Reached target Host and Network Name Lookups.768machine # [ 11.074926] nsncd[546]: Sep 12 02:45:48.540 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"769machine # [ 11.139755] (systemd)[560]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)770machine # [ 11.175886] systemd[1]: Reached target User and Group Name Lookups.771machine # [ 11.210178] systemd-logind[482]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.772machine # [ 11.233595] systemd[1]: Finished resolvconf update.773machine # [ 11.252405] systemd[1]: Reached target Preparation for Network.774machine # [ 11.270407] systemd[1]: Starting DHCP Client...775machine # [ 11.289336] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy776machine # [ 11.382270] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3777machine # [ 11.569056] systemd[1]: Starting Extra networking commands....778machine # [ 11.595472] systemd[560]: Queued start job for default target Main User Target.779machine # [ 11.635023] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy780machine # [ 11.666540] systemd[560]: Created slice User Application Slice.781machine # [ 11.682726] systemd[560]: Started Daily Cleanup of User's Temporary Directories.782machine # [ 11.702137] systemd[560]: Reached target Paths.783machine # [ 11.713371] systemd[560]: Reached target Timers.784machine # [ 11.736656] systemd[560]: Starting D-Bus User Message Bus Socket...785machine # [ 11.745130] systemd[560]: Starting Create User Files and Directories...786machine # [ 11.760347] systemd[560]: Finished Create User Files and Directories.787machine # [ 11.781361] systemd[560]: Listening on D-Bus User Message Bus Socket.788machine # [ 11.791014] systemd[560]: Reached target Sockets.789machine # [ 11.804730] systemd[560]: Reached target Basic System.790machine # [ 11.813895] systemd[1]: Started User Manager for UID 1001.791machine # [ 11.823895] systemd[560]: Starting Run user-specific NixOS activation...792machine # [ 11.849339] systemd[560]: Finished Run user-specific NixOS activation.793machine # [ 11.866972] systemd[560]: Reached target Main User Target.794machine # [ 11.888699] systemd[560]: Startup finished in 476ms.795machine # [ 11.921841] systemd[1]: Starting Virtual Console Setup...796machine # [ 11.949467] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs797machine # [ 12.020376] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.798machine # [ 12.042290] systemd[1]: Stopped Virtual Console Setup.799machine # [ 12.058663] systemd[1]: Starting Virtual Console Setup...800machine # [ 12.238379] dhcpcd[594]: dhcpcd-10.3.2 starting801machine # [ 12.254260] dhcpcd[645]: dev: loaded udev802machine # [ 12.277970] systemd[1]: Finished Extra networking commands..803machine # [ 12.280390] systemd[1]: Reached target Network.804machine # [ 12.282549] systemd[1]: Starting Permit User Sessions...805machine # [ 12.317874] 8021q: 802.1Q VLAN Support v1.8806machine # [ 12.396259] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.807machine # [ 12.427439] cfg80211: Loading compiled-in X.509 certificates for regulatory database808machine # [ 12.465164] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'809machine # [ 12.467717] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'810machine # [ 12.472308] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2811machine # [ 12.472667] cfg80211: failed to load regulatory.db812machine # [ 12.471678] dhcpcd[645]: no valid interfaces found813machine # [ 12.492799] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.814machine # [ 12.509849] dhcpcd[645]: no valid interfaces found815machine # [ 12.526211] systemd[1]: Started backdoor.service.816machine # [ 12.537829] (udev-worker)[654]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.817machine # [ 12.579006] (udev-worker)[654]: Network interface NamePolicy= disabled on kernel command line.818machine # [ 12.611337] (udev-worker)[657]: Network interface NamePolicy= disabled on kernel command line.819machine # [ 12.639392] systemd[1]: Finished Permit User Sessions.820machine # [ 12.664706] systemd[1]: Started Getty on tty1.821machine # [ 12.682508] systemd[1]: Reached target Login Prompts.822machine # [ 12.738805] systemd[1]: Condition check resulted in Virtio network device being skipped.823machine # [ 12.764157] systemd[1]: Starting Address configuration of eth1...824machine # [ 12.846845] systemd-logind[482]: Watching system buttons on /dev/input/event0 (gpio-keys)825machine # connecting to host...826machine # [ 12.964989] mousedev: PS/2 mouse device common for all mice827machine: Guest shell says: b'Spawning backdoor root shell...\n'828machine: connected to guest root shell829machine: (connecting took 13.32 seconds)830machine: (finished: waiting for the VM to finish booting, in 13.98 seconds)831machine # [ 13.026516] 8021q: adding VLAN 0 to HW filter on device eth0832machine # [ 13.013132] dhcpcd[645]: eth0: waiting for carrier833machine # [ 13.019726] dhcpcd[645]: eth0: carrier acquired834machine # [ 13.125029] 8021q: adding VLAN 0 to HW filter on device eth1835machine # [ 13.117655] dhcpcd[645]: DUID 00:01:00:01:32:37:79:5e:52:54:00:12:34:56836machine # [ 13.119563] dhcpcd[645]: eth0: IAID 00:12:34:56837machine # [ 13.148118] dhcpcd[645]: eth0: adding address fe80::5054:ff:fe12:3456838machine # [ 13.165826] systemd-logind[482]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)839machine # [ 13.221475] network-addresses-eth1-start[675]: adding address 192.168.1.1/24... done840machine # [ 13.304477] network-addresses-eth1-start[675]: adding address 2001:db8:1::1/64... done841machine # [ 13.412751] systemd[1]: Finished Address configuration of eth1.842machine # [ 13.765163] systemd-vconsole-setup[605]: Configuration of first virtual console was skipped, ignoring remaining ones.843machine # [ 13.794608] systemd[1]: Finished Virtual Console Setup.844machine # [ 14.233084] dhcpcd[645]: eth0: soliciting a DHCP lease845machine # [ 14.233932] dhcpcd[645]: eth0: offered 10.0.2.15 from 10.0.2.2846machine # [ 14.259684] dhcpcd[645]: eth0: probing address 10.0.2.15/24847machine # [ 14.378490] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.848machine # [ 14.396435] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.849machine # [ 14.397595] systemd[1]: Stopped Virtual Console Setup.850machine # [ 14.398372] systemd[1]: Stopping Virtual Console Setup...851machine # [ 14.399118] systemd[1]: Starting Virtual Console Setup...852machine # [ 14.440977] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.853machine # [ 14.442125] systemd[1]: Stopped Virtual Console Setup.854machine # [ 14.448331] systemd[1]: Starting Virtual Console Setup...855machine # [ 14.455668] systemd-logind[482]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)856machine # [ 14.828509] systemd-vconsole-setup[713]: Configuration of first virtual console was skipped, ignoring remaining ones.857machine # [ 14.835445] systemd[1]: Finished Virtual Console Setup.858machine # [ 15.742297] dhcpcd[645]: eth0: soliciting an IPv6 router859machine # [ 15.742713] dhcpcd[645]: eth0: Router Advertisement from fe80::2860machine # [ 15.742877] dhcpcd[645]: eth0: adding address fec0::5054:ff:fe12:3456/64861machine # [ 15.751390] dhcpcd[645]: eth0: adding route to fec0::/64862machine # [ 15.756458] dhcpcd[645]: eth0: adding default route via fe80::2863machine # [ 19.026879] dhcpcd[645]: eth0: leased 10.0.2.15 for 86400 seconds864machine # [ 19.030937] dhcpcd[645]: eth0: adding route to 10.0.2.0/24865machine # [ 19.035050] dhcpcd[645]: eth0: adding default route via 10.0.2.2866machine # [ 19.290152] systemd[1]: Started DHCP Client.867machine # [ 19.292718] systemd[1]: Reached target Multi-User System.868machine # [ 19.293969] systemd[1]: Startup finished in 1.064s (kernel) + 4.585s (initrd) + 13.642s (userspace) = 19.291s.869machine: (finished: waiting for unit multi-user.target, in 21.34 seconds)870machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test871machine # Checking switch inhibitors... done872machine # [ 20.957779] nixos[830]: switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test873machine # activating the configuration...874machine # [ 20.962088] systemd[1]: Stopped target Local File Systems.875machine # [ 20.963236] systemd[1]: Stopped target Remote File Systems.876machine # setting up /etc...877machine # [ 21.422634] systemd[1]: Reload requested from client PID 830 ('.switch-to-conf') (unit backdoor.service)...878machine # [ 21.424696] systemd[1]: Reloading...879machine # [ 22.018161] systemd[1]: Reloading finished in 591 ms.880machine # reloading user units for usertest...881machine # [ 22.071995] systemd[560]: Created slice User Core Session Slice.882machine # [ 22.075973] systemd[560]: Starting D-Bus User Message Bus...883machine # [ 22.146511] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/services/org.freedesktop.import1.service'884machine # [ 22.150086] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/services/org.freedesktop.machine1.service'885machine # [ 22.153503] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/services/org.freedesktop.portable1.service'886machine # [ 22.156924] dbus-broker-launch[900]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'887machine # [ 22.160713] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored888machine # [ 22.163543] dbus-broker-launch[900]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored889machine # [ 22.166004] systemd[560]: Started D-Bus User Message Bus.890machine # [ 22.173238] dbus-broker-launch[900]: Ready891machine # [ 22.275493] systemd[560]: Reexecution requested from client PID 899 ('switch-to-confi')...892machine # [ 22.279266] systemd[560]: Reexecuting.893machine # [ 22.342109] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy894machine # [ 22.553604] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy895machine # [ 22.563155] systemd[560]: Reload requested from client PID 899 ('switch-to-confi')...896machine # [ 22.568581] systemd[560]: Reloading...897machine # [ 22.775802] systemd[560]: Reloading finished in 209 ms.898machine # [ 22.788182] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy899machine # restarting the following user units: nixos-activation.service900machine # [ 22.799523] systemd[560]: Stopped Run user-specific NixOS activation.901machine # [ 22.802085] systemd[560]: Stopping Run user-specific NixOS activation...902machine # [ 22.805225] systemd[560]: Starting Run user-specific NixOS activation...903machine # [ 22.856390] systemd[560]: Finished Run user-specific NixOS activation.904machine # restarting sysinit-reactivation.target905machine # [ 22.874009] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....906machine # [ 22.995308] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..907machine # [ 22.998079] systemd[1]: Reached target Reactivate sysinit units.908machine # [ 23.003971] systemd[1]: Reached target Remote File Systems.909machine # [ 23.009769] systemd[1]: Starting linger-users.service...910machine # [ 23.014365] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os911machine # [ 23.015787] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki912machine # [ 23.028521] systemd[1]: Reached target Local File Systems.913machine # [ 23.029495] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore914machine # [ 23.041696] systemd[1]: Starting Create SUID/SGID Wrappers...915machine # [ 23.042804] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.916machine # [ 23.048331] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67917machine # [ 23.051769] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore918machine # [ 23.054570] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os919machine # [ 23.056719] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os920machine # [ 23.119964] systemd[1]: linger-users.service: Deactivated successfully.921machine # [ 23.122928] systemd[1]: Finished linger-users.service.922machine # [ 23.411418] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.923machine # [ 23.414850] systemd[1]: Finished Create SUID/SGID Wrappers.924machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service925machine # [ 23.676703] nixos[830]: finished switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test926machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test, in 3.32 seconds)927machine: must succeed: rm /etc/NIXOS928machine: (finished: must succeed: rm /etc/NIXOS, in 0.05 seconds)929machine: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/wc9s91xqi66mnv0w6vf5686lpihqwdwa-nixos-system-other-test/bin/switch-to-configuration test930machine # this should succeed (config: /nix/store/wc9s91xqi66mnv0w6vf5686lpihqwdwa-nixos-system-other-test, action: test)931machine # Checking switch inhibitors... done932machine # [ 24.367545] nixos[978]: switching to system configuration /nix/store/wc9s91xqi66mnv0w6vf5686lpihqwdwa-nixos-system-other-test933machine # [ 24.370357] systemd[1]: Stopped target Remote File Systems.934machine # activating the configuration...935machine # [ 24.373099] systemd[1]: Stopped target Local File Systems.936machine # removing user ‘usertest’937machine # setting up /etc...938machine # [ 24.848991] systemd[1]: Reload requested from client PID 978 ('.switch-to-conf') (unit backdoor.service)...939machine # [ 24.850919] systemd[1]: Reloading...940machine # [ 25.488139] systemd[1]: Reloading finished in 635 ms.941machine # reloading user units for usertest...942machine # [ 25.653554] systemd[560]: Reexecution requested from client PID 1049 ('switch-to-confi')...943machine # [ 25.658422] systemd[560]: Reexecuting.944machine # [ 25.709925] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy945machine # [ 25.941160] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 25.948694] systemd[560]: Reload requested from client PID 1049 ('switch-to-confi')...947machine # [ 25.951428] systemd[560]: Reloading...948machine # [ 26.149253] systemd[560]: Reloading finished in 196 ms.949machine # [ 26.164180] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy950machine # restarting the following user units: nixos-activation.service951machine # [ 26.175075] systemd[560]: Stopped Run user-specific NixOS activation.952machine # [ 26.176188] systemd[560]: Stopping Run user-specific NixOS activation...953machine # [ 26.179450] systemd[560]: Starting Run user-specific NixOS activation...954machine # [ 26.229152] systemd[560]: Finished Run user-specific NixOS activation.955machine # restarting sysinit-reactivation.target956machine # [ 26.233884] systemd[1]: Stopped target Reactivate sysinit units.957machine # reloading the following units: dbus-broker.service[ 26.236487] systemd[1]: Stopping Reactivate sysinit units...958machine # [ 26.237390] systemd[1]: Reached target Reactivate sysinit units.959machine # 960machine # [ 26.240746] systemd[1]: Reloading D-Bus System Message Bus...961machine # [ 26.242253] dbus-broker-launch[477]: Caught SIGHUP, trigger reload.962machine # [ 26.262613] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'...963machine # [ 26.264955] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync'964machine # [ 26.266032] dbus-broker-launch[477]: Invalid user-name in /nix/store/mx1y8zqfrap40lzhvxqayqjls63h1pnv-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"965machine # [ 26.310911] systemd[1]: Reloaded D-Bus System Message Bus.966machine # restarting the following units: network-addresses-eth1.service967machine # [ 26.319093] systemd[1]: Stopping Address configuration of eth1...968machine # [ 26.372256] network-addresses-eth1-pre-stop[1062]: deleting address 192.168.1.1/24... done969machine # [ 26.384718] network-addresses-eth1-pre-stop[1062]: deleting address 2001:db8:1::1/64... done970machine # [ 26.395531] systemd[1]: network-addresses-eth1.service: Deactivated successfully.971machine # [ 26.397920] systemd[1]: Stopped Address configuration of eth1.972machine # [ 26.403957] systemd[1]: Starting Address configuration of eth1...973machine # [ 26.489601] network-addresses-eth1-start[1067]: adding address 192.168.1.2/24... done974machine # [ 26.502991] network-addresses-eth1-start[1067]: adding address 2001:db8:1::2/64... done975machine # [ 26.521371] systemd[1]: Finished Address configuration of eth1.976machine # [ 26.525016] systemd[1]: Reached target Local File Systems.977machine # [ 26.527787] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os978machine # [ 26.530365] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki979machine # [ 26.535079] systemd[1]: Reached target Remote File Systems.980machine # [ 26.541257] systemd[1]: Starting linger-users.service...981machine # [ 26.552618] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore982machine # [ 26.557771] systemd[1]: Starting Create SUID/SGID Wrappers...983machine # [ 26.561329] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.984machine # [ 26.562712] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67985machine # [ 26.570688] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore986machine # [ 26.573009] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os987machine # [ 26.574460] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os988machine # [ 26.622616] linger-users-start[1081]: id: ‘usertest’: no such user989machine # [ 26.625833] linger-users-start[1078]: Removing linger for missing user usertest990machine # [ 26.638368] systemd[1]: linger-users.service: Deactivated successfully.991machine # [ 26.641797] systemd[1]: Finished linger-users.service.992machine # [ 27.012884] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.993machine # [ 27.016635] systemd[1]: Finished Create SUID/SGID Wrappers.994machine # [ 27.275243] nixos[978]: finished switching to system configuration /nix/store/wc9s91xqi66mnv0w6vf5686lpihqwdwa-nixos-system-other-test995machine: (finished: must succeed: /nix/store/lcxydqrhkhwhva2mijlnq8rxfi36hpwd-stderr-runner /nix/store/wc9s91xqi66mnv0w6vf5686lpihqwdwa-nixos-system-other-test/bin/switch-to-configuration test, in 3.55 seconds)996subtest: services997machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&1998machine # [ 27.906757] nixos[1145]: switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test999machine # [ 27.910287] systemd[1]: Stopped target Local File Systems.1000machine # [ 27.911730] systemd[1]: Stopped target Remote File Systems.1001machine # [ 28.377577] systemd[1]: Reload requested from client PID 1145 ('.switch-to-conf') (unit backdoor.service)...1002machine # [ 28.379715] systemd[1]: Reloading...1003machine # [ 28.997282] systemd[1]: Reloading finished in 614 ms.1004machine # [ 29.144455] systemd[560]: Reexecution requested from client PID 1213 ('switch-to-confi')...1005machine # [ 29.145857] systemd[560]: Reexecuting.1006machine # [ 29.222603] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1007machine # [ 29.424368] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1008machine # [ 29.431171] systemd[560]: Reload requested from client PID 1213 ('switch-to-confi')...1009machine # [ 29.433900] systemd[560]: Reloading...1010machine # [ 29.619318] systemd[560]: Reloading finished in 185 ms.1011machine # [ 29.632165] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1012machine # [ 29.642090] systemd[560]: Stopped Run user-specific NixOS activation.1013machine # [ 29.644591] systemd[560]: Stopping Run user-specific NixOS activation...1014machine # [ 29.647526] systemd[560]: Starting Run user-specific NixOS activation...1015machine # [ 29.699497] systemd[560]: Finished Run user-specific NixOS activation.1016machine # [ 29.704563] systemd[1]: Stopped target Reactivate sysinit units.1017machine # [ 29.709661] systemd[1]: Stopping Reactivate sysinit units...1018machine # [ 29.710761] systemd[1]: Reached target Reactivate sysinit units.1019machine # [ 29.711782] systemd[1]: Reloading D-Bus System Message Bus...1020machine # [ 29.713560] dbus-broker-launch[477]: Caught SIGHUP, trigger reload.1021machine # [ 29.725971] dbus-broker-launch[477]: Looking up NSS user entry for 'systemd-timesync'...1022machine # [ 29.728576] dbus-broker-launch[477]: NSS returned no entry for 'systemd-timesync'1023machine # [ 29.729804] dbus-broker-launch[477]: Invalid user-name in /nix/store/8pik3vng42n4iy67gvrlnf7gkid3xs0z-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1024machine # [ 29.766315] systemd[1]: Reloaded D-Bus System Message Bus.1025machine # [ 29.773643] systemd[1]: Stopping Address configuration of eth1...1026machine # [ 29.826460] network-addresses-eth1-pre-stop[1227]: deleting address 192.168.1.2/24... done1027machine # [ 29.838129] network-addresses-eth1-pre-stop[1227]: deleting address 2001:db8:1::2/64... done1028machine # [ 29.847435] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1029machine # [ 29.849874] systemd[1]: Stopped Address configuration of eth1.1030machine # [ 29.854815] systemd[1]: Starting Address configuration of eth1...1031machine # [ 29.928682] network-addresses-eth1-start[1232]: adding address 192.168.1.1/24... done1032machine # [ 29.941385] network-addresses-eth1-start[1232]: adding address 2001:db8:1::1/64... done1033machine # [ 29.958656] systemd[1]: Finished Address configuration of eth1.1034machine # [ 29.962506] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1035machine # [ 29.966324] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1036machine # [ 29.970208] systemd[1]: Reached target Remote File Systems.1037machine # [ 29.975977] systemd[1]: Starting linger-users.service...1038machine # [ 29.976929] systemd[1]: Reached target Local File Systems.1039machine # [ 29.979889] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1040machine # [ 29.991803] systemd[1]: Starting Create SUID/SGID Wrappers...1041machine # [ 29.993877] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1042machine # [ 30.002542] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671043machine # [ 30.007417] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1044machine # [ 30.010574] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1045machine # [ 30.013205] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1046machine # [ 30.060545] systemd[1]: linger-users.service: Deactivated successfully.1047machine # [ 30.063343] systemd[1]: Finished linger-users.service.1048machine # [ 30.358689] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1049machine # [ 30.362191] systemd[1]: Finished Create SUID/SGID Wrappers.1050machine # [ 30.619019] nixos[1145]: finished switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test1051machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1052machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&11053machine # [ 31.179540] nixos[1301]: switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test1054machine # [ 31.182686] systemd[1]: Stopped target Local File Systems.1055machine # [ 31.185361] systemd[1]: Stopped target Remote File Systems.1056machine # [ 31.651734] systemd[1]: Reload requested from client PID 1301 ('.switch-to-conf') (unit backdoor.service)...1057machine # [ 31.654093] systemd[1]: Reloading...1058machine # [ 32.256424] systemd[1]: Reloading finished in 600 ms.1059machine # [ 32.407724] systemd[560]: Reexecution requested from client PID 1370 ('switch-to-confi')...1060machine # [ 32.411701] systemd[560]: Reexecuting.1061machine # [ 32.469904] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1062machine # [ 32.694605] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1063machine # [ 32.701899] systemd[560]: Reload requested from client PID 1370 ('switch-to-confi')...1064machine # [ 32.704492] systemd[560]: Reloading...1065machine # [ 32.894391] systemd[560]: Reloading finished in 188 ms.1066machine # [ 32.908160] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1067machine # [ 32.916973] systemd[560]: Stopped Run user-specific NixOS activation.1068machine # [ 32.919327] systemd[560]: Stopping Run user-specific NixOS activation...1069machine # [ 32.922094] systemd[560]: Starting Run user-specific NixOS activation...1070machine # [ 32.968474] systemd[560]: Finished Run user-specific NixOS activation.1071machine # [ 32.972653] systemd[1]: Stopped target Reactivate sysinit units.1072machine # [ 32.975561] systemd[1]: Stopping Reactivate sysinit units...1073machine # [ 32.977031] systemd[1]: Reached target Reactivate sysinit units.1074machine # [ 32.978233] systemd[1]: Reached target Local File Systems.1075machine # [ 32.982113] systemd[1]: Reached target Remote File Systems.1076machine # [ 32.984653] systemd[1]: Starting linger-users.service...1077machine # [ 32.987436] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1078machine # [ 32.994902] systemd[1]: Starting Create SUID/SGID Wrappers...1079machine # [ 32.995819] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1080machine # [ 33.005295] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671081machine # [ 33.007538] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1082machine # [ 33.015476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1083machine # [ 33.018301] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1084machine # [ 33.019536] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1085machine # [ 33.022568] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1086machine # [ 33.085674] systemd[1]: linger-users.service: Deactivated successfully.1087machine # [ 33.089170] systemd[1]: Finished linger-users.service.1088machine # [ 33.350311] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1089machine # [ 33.353780] systemd[1]: Finished Create SUID/SGID Wrappers.1090machine # [ 33.610836] nixos[1301]: finished switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test1091machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.99 seconds)1092machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11093machine # [ 34.231027] nixos[1442]: switching to system configuration /nix/store/j7bddzhbml4r1aph0kl2v1y8yd6b5yhz-nixos-system-machine-test1094machine # [ 34.233590] systemd[1]: Stopped target Local File Systems.1095machine # [ 34.235792] systemd[1]: Stopped target Remote File Systems.1096machine # [ 34.726291] systemd[1]: Reload requested from client PID 1442 ('.switch-to-conf') (unit backdoor.service)...1097machine # [ 34.728331] systemd[1]: Reloading...1098machine # [ 35.337926] systemd[1]: Reloading finished in 607 ms.1099machine # [ 35.480392] systemd[560]: Reexecution requested from client PID 1510 ('switch-to-confi')...1100machine # [ 35.481793] systemd[560]: Reexecuting.1101machine # [ 35.550649] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1102machine # [ 35.743325] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1103machine # [ 35.750712] systemd[560]: Reload requested from client PID 1510 ('switch-to-confi')...1104machine # [ 35.754651] systemd[560]: Reloading...1105machine # [ 35.975646] systemd[560]: Reloading finished in 222 ms.1106machine # [ 35.988175] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1107machine # [ 35.998150] systemd[560]: Stopped Run user-specific NixOS activation.1108machine # [ 36.000721] systemd[560]: Stopping Run user-specific NixOS activation...1109machine # [ 36.003794] systemd[560]: Starting Run user-specific NixOS activation...1110machine # [ 36.051219] systemd[560]: Finished Run user-specific NixOS activation.1111machine # [ 36.055745] systemd[1]: Stopped target Reactivate sysinit units.1112machine # [ 36.058154] systemd[1]: Stopping Reactivate sysinit units...1113machine # [ 36.059455] systemd[1]: Reached target Reactivate sysinit units.1114machine # [ 36.063765] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1115machine # [ 36.066426] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1116machine # [ 36.068410] systemd[1]: Reached target Local File Systems.1117machine # [ 36.070856] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1118machine # [ 36.078245] systemd[1]: Starting Create SUID/SGID Wrappers...1119machine # [ 36.079186] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1120machine # [ 36.085669] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671121machine # [ 36.092649] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1122machine # [ 36.095177] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1123machine # [ 36.096557] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 36.097714] systemd[1]: Reached target Remote File Systems.1125machine # [ 36.424121] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1126machine # [ 36.428659] systemd[1]: Finished Create SUID/SGID Wrappers.1127machine # [ 36.431473] systemd[1]: Starting linger-users.service...1128machine # [ 36.435696] systemd[1]: Starting test.service...1129machine # [ 36.481430] systemd[1]: Finished test.service.1130machine # [ 36.509006] systemd[1]: linger-users.service: Deactivated successfully.1131machine # [ 36.511852] systemd[1]: Finished linger-users.service.1132machine # [ 36.771432] nixos[1442]: finished switching to system configuration /nix/store/j7bddzhbml4r1aph0kl2v1y8yd6b5yhz-nixos-system-machine-test1133machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1134machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&11135machine # [ 37.351299] nixos[1585]: switching to system configuration /nix/store/j7bddzhbml4r1aph0kl2v1y8yd6b5yhz-nixos-system-machine-test1136machine # [ 37.354262] systemd[1]: Stopped target Local File Systems.1137machine # [ 37.356624] systemd[1]: Stopped target Remote File Systems.1138machine # [ 37.911412] systemd[1]: Reload requested from client PID 1585 ('.switch-to-conf') (unit backdoor.service)...1139machine # [ 37.913987] systemd[1]: Reloading...1140machine # [ 38.649541] systemd[1]: Reloading finished in 733 ms.1141machine # [ 38.811287] systemd[560]: Reexecution requested from client PID 1654 ('switch-to-confi')...1142machine # [ 38.813524] systemd[560]: Reexecuting.1143machine # [ 38.885663] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1144machine # [ 39.147903] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1145machine # [ 39.159825] systemd[560]: Reload requested from client PID 1654 ('switch-to-confi')...1146machine # [ 39.164302] systemd[560]: Reloading...1147machine # [ 39.423895] systemd[560]: Reloading finished in 260 ms.1148machine # [ 39.436187] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1149machine # [ 39.448111] systemd[560]: Stopped Run user-specific NixOS activation.1150machine # [ 39.449193] systemd[560]: Stopping Run user-specific NixOS activation...1151machine # [ 39.452557] systemd[560]: Starting Run user-specific NixOS activation...1152machine # [ 39.502757] systemd[560]: Finished Run user-specific NixOS activation.1153machine # [ 39.507555] systemd[1]: Stopped target Reactivate sysinit units.1154machine # [ 39.510288] systemd[1]: Stopping Reactivate sysinit units...1155machine # [ 39.511236] systemd[1]: Reached target Reactivate sysinit units.1156machine # [ 39.514762] systemd[1]: Reached target Local File Systems.1157machine # [ 39.519322] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1158machine # [ 39.523487] systemd[1]: Starting Create SUID/SGID Wrappers...1159machine # [ 39.525896] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1160machine # [ 39.529736] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671161machine # [ 39.531984] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1162machine # [ 39.539507] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1163machine # [ 39.545730] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1164machine # [ 39.549387] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1165machine # [ 39.550686] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1166machine # [ 39.551974] systemd[1]: Reached target Remote File Systems.1167machine # [ 39.907339] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1168machine # [ 39.913155] systemd[1]: Finished Create SUID/SGID Wrappers.1169machine # [ 39.915438] systemd[1]: Starting linger-users.service...1170machine # [ 39.981998] systemd[1]: linger-users.service: Deactivated successfully.1171machine # [ 39.984190] systemd[1]: Finished linger-users.service.1172machine # [ 40.242909] nixos[1585]: finished switching to system configuration /nix/store/j7bddzhbml4r1aph0kl2v1y8yd6b5yhz-nixos-system-machine-test1173machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration test 2>&1, in 3.48 seconds)1174machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&11175machine # [ 41.011758] nixos[1726]: switching to system configuration /nix/store/9xxd8hzjzx91fg19dqfcjmcih6xcwjqz-nixos-system-machine-test1176machine # [ 41.015148] systemd[1]: Stopped target Local File Systems.1177machine # [ 41.017493] systemd[1]: Stopped target Remote File Systems.1178machine # [ 41.579269] systemd[1]: Reload requested from client PID 1726 ('.switch-to-conf') (unit backdoor.service)...1179machine # [ 41.581726] systemd[1]: Reloading...1180machine # [ 42.285213] systemd[1]: Reloading finished in 701 ms.1181machine # [ 42.438024] systemd[560]: Reexecution requested from client PID 1795 ('switch-to-confi')...1182machine # [ 42.439447] systemd[560]: Reexecuting.1183machine # [ 42.515421] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1184machine # [ 42.757829] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1185machine # [ 42.766104] systemd[560]: Reload requested from client PID 1795 ('switch-to-confi')...1186machine # [ 42.768603] systemd[560]: Reloading...1187machine # [ 42.984695] systemd[560]: Reloading finished in 214 ms.1188machine # [ 43.000197] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1189machine # [ 43.009967] systemd[560]: Stopped Run user-specific NixOS activation.1190machine # [ 43.012813] systemd[560]: Stopping Run user-specific NixOS activation...1191machine # [ 43.015831] systemd[560]: Starting Run user-specific NixOS activation...1192machine # [ 43.070034] systemd[560]: Finished Run user-specific NixOS activation.1193machine # [ 43.074823] systemd[1]: Stopped target Reactivate sysinit units.1194machine # [ 43.077073] systemd[1]: Stopping Reactivate sysinit units...1195machine # [ 43.078712] systemd[1]: Reached target Reactivate sysinit units.1196machine # [ 43.082443] systemd[1]: Reached target Remote File Systems.1197machine # [ 43.085377] systemd[1]: Reached target Local File Systems.1198machine # [ 43.089040] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1199machine # [ 43.094728] systemd[1]: Starting Create SUID/SGID Wrappers...1200machine # [ 43.095740] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1201machine # [ 43.100999] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671202machine # [ 43.108998] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1203machine # [ 43.117916] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1204machine # [ 43.119353] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1205machine # [ 43.121686] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1206machine # [ 43.123079] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1207machine # [ 43.463383] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1208machine # [ 43.468527] systemd[1]: Finished Create SUID/SGID Wrappers.1209machine # [ 43.471474] systemd[1]: Starting linger-users.service...1210machine # [ 43.543999] systemd[1]: linger-users.service: Deactivated successfully.1211machine # [ 43.545877] systemd[1]: Finished linger-users.service.1212machine # [ 43.805322] nixos[1726]: finished switching to system configuration /nix/store/9xxd8hzjzx91fg19dqfcjmcih6xcwjqz-nixos-system-machine-test1213machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceDifferentDescription/bin/switch-to-configuration test 2>&1, in 3.56 seconds)1214machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11215machine # [ 44.480689] nixos[1867]: switching to system configuration /nix/store/68g3ac6i44dp6spfylk9v6fm02jjd1wi-nixos-system-machine-test1216machine # [ 44.484615] systemd[1]: Stopped target Local File Systems.1217machine # [ 44.486406] systemd[1]: Stopped target Remote File Systems.1218machine # [ 44.487281] systemd[1]: test.service: Deactivated successfully.1219machine # [ 44.488806] systemd[1]: Stopped Test unit.1220machine # [ 45.012124] systemd[1]: Reload requested from client PID 1867 ('.switch-to-conf') (unit backdoor.service)...1221machine # [ 45.014136] systemd[1]: Reloading...1222machine # [ 45.669762] systemd[1]: Reloading finished in 653 ms.1223machine # [ 45.830969] systemd[560]: Reexecution requested from client PID 1936 ('switch-to-confi')...1224machine # [ 45.834232] systemd[560]: Reexecuting.1225machine # [ 45.901705] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1226machine # [ 46.094524] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1227machine # [ 46.101752] systemd[560]: Reload requested from client PID 1936 ('switch-to-confi')...1228machine # [ 46.104247] systemd[560]: Reloading...1229machine # [ 46.295750] systemd[560]: Reloading finished in 190 ms.1230machine # [ 46.308192] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1231machine # [ 46.317895] systemd[560]: Stopped Run user-specific NixOS activation.1232machine # [ 46.320416] systemd[560]: Stopping Run user-specific NixOS activation...1233machine # [ 46.323420] systemd[560]: Starting Run user-specific NixOS activation...1234machine # [ 46.369609] systemd[560]: Finished Run user-specific NixOS activation.1235machine # [ 46.373905] systemd[1]: Stopped target Reactivate sysinit units.1236machine # [ 46.376975] systemd[1]: Stopping Reactivate sysinit units...1237machine # [ 46.378389] systemd[1]: Reached target Reactivate sysinit units.1238machine # [ 46.379916] systemd[1]: Reached target Remote File Systems.1239machine # [ 46.388266] systemd[1]: Starting linger-users.service...1240machine # [ 46.392864] systemd[1]: Starting test.service...1241machine # [ 46.393656] systemd[1]: Reached target Local File Systems.1242machine # [ 46.408749] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1243machine # [ 46.413822] systemd[1]: Starting Create SUID/SGID Wrappers...1244machine # [ 46.414756] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1245machine # [ 46.415997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671246machine # [ 46.428550] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1247machine # [ 46.430248] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1248machine # [ 46.431705] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1249machine # [ 46.436158] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1250machine # [ 46.438921] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1251machine # [ 46.468480] systemd[1]: Finished test.service.1252machine # [ 46.505512] systemd[1]: linger-users.service: Deactivated successfully.1253machine # [ 46.508781] systemd[1]: Finished linger-users.service.1254machine # [ 46.785349] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1255machine # [ 46.788107] systemd[1]: Finished Create SUID/SGID Wrappers.1256machine # [ 47.045010] nixos[1867]: finished switching to system configuration /nix/store/68g3ac6i44dp6spfylk9v6fm02jjd1wi-nixos-system-machine-test1257machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.24 seconds)1258machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&11259machine # [ 47.669505] nixos[2010]: switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test1260machine # [ 47.672055] systemd[1]: Stopped target Local File Systems.1261machine # [ 47.674227] systemd[1]: Stopped target Remote File Systems.1262machine # [ 48.172768] systemd[1]: Reload requested from client PID 2010 ('.switch-to-conf') (unit backdoor.service)...1263machine # [ 48.174785] systemd[1]: Reloading...1264machine # [ 48.807813] systemd[1]: Reloading finished in 630 ms.1265machine # [ 48.952776] systemd[560]: Reexecution requested from client PID 2079 ('switch-to-confi')...1266machine # [ 48.956888] systemd[560]: Reexecuting.1267machine # [ 49.018391] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1268machine # [ 49.197637] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1269machine # [ 49.204666] systemd[560]: Reload requested from client PID 2079 ('switch-to-confi')...1270machine # [ 49.206779] systemd[560]: Reloading...1271machine # [ 49.394231] systemd[560]: Reloading finished in 186 ms.1272machine # [ 49.412199] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1273machine # [ 49.422622] systemd[560]: Stopped Run user-specific NixOS activation.1274machine # [ 49.425182] systemd[560]: Stopping Run user-specific NixOS activation...1275machine # [ 49.428405] systemd[560]: Starting Run user-specific NixOS activation...1276machine # [ 49.479416] systemd[560]: Finished Run user-specific NixOS activation.1277machine # [ 49.484953] systemd[1]: Stopped target Reactivate sysinit units.1278machine # [ 49.488633] systemd[1]: Stopping Reactivate sysinit units...1279machine # [ 49.489512] systemd[1]: Reached target Reactivate sysinit units.1280machine # [ 49.490337] systemd[1]: test.service: Deactivated successfully.1281machine # [ 49.491233] systemd[1]: Stopped test.service.1282machine # [ 49.491891] systemd[1]: Stopping test.service...1283machine # [ 49.495115] systemd[1]: Starting test.service...1284machine # [ 49.534703] systemd[1]: Finished test.service.1285machine # [ 49.539575] systemd[1]: Reached target Local File Systems.1286machine # [ 49.543548] systemd[1]: Reached target Remote File Systems.1287machine # [ 49.549138] systemd[1]: Starting linger-users.service...1288machine # [ 49.554606] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1289machine # [ 49.562804] systemd[1]: Starting Create SUID/SGID Wrappers...1290machine # [ 49.563816] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1291machine # [ 49.576633] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671292machine # [ 49.578893] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1293machine # [ 49.582865] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1294machine # [ 49.586207] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1295machine # [ 49.588513] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1296machine # [ 49.591419] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1297machine # [ 49.658679] systemd[1]: linger-users.service: Deactivated successfully.1298machine # [ 49.662113] systemd[1]: Finished linger-users.service.1299machine # [ 49.945688] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1300machine # [ 49.949055] systemd[1]: Finished Create SUID/SGID Wrappers.1301machine # [ 50.206141] nixos[2010]: finished switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test1302machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.16 seconds)1303machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&11304machine # [ 50.857814] nixos[2152]: switching to system configuration /nix/store/19a4q3aiwb16kmq58cznmv6dwvb1axyj-nixos-system-machine-test1305machine # [ 50.861218] systemd[1]: Stopped target Local File Systems.1306machine # [ 50.862627] systemd[1]: Stopped target Remote File Systems.1307machine # [ 51.374490] systemd[1]: Reload requested from client PID 2152 ('.switch-to-conf') (unit backdoor.service)...1308machine # [ 51.376867] systemd[1]: Reloading...1309machine # [ 52.052779] systemd[1]: Reloading finished in 673 ms.1310machine # [ 52.204438] systemd[560]: Reexecution requested from client PID 2221 ('switch-to-confi')...1311machine # [ 52.205901] systemd[560]: Reexecuting.1312machine # [ 52.270427] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1313machine # [ 52.472778] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1314machine # [ 52.479945] systemd[560]: Reload requested from client PID 2221 ('switch-to-confi')...1315machine # [ 52.482642] systemd[560]: Reloading...1316machine # [ 52.678088] systemd[560]: Reloading finished in 194 ms.1317machine # [ 52.692200] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1318machine # [ 52.701931] systemd[560]: Stopped Run user-specific NixOS activation.1319machine # [ 52.704626] systemd[560]: Stopping Run user-specific NixOS activation...1320machine # [ 52.707675] systemd[560]: Starting Run user-specific NixOS activation...1321machine # [ 52.754609] systemd[560]: Finished Run user-specific NixOS activation.1322machine # [ 52.759016] systemd[1]: Stopped target Reactivate sysinit units.1323machine # [ 52.763484] systemd[1]: Stopping Reactivate sysinit units...1324machine # [ 52.765196] systemd[1]: Reached target Reactivate sysinit units.1325machine # [ 52.767481] systemd[1]: Reloading test.service...1326machine # [ 52.804188] systemd[1]: Reloaded test.service.1327machine # [ 52.806633] systemd[1]: Reached target Remote File Systems.1328machine # [ 52.815037] systemd[1]: Reached target Local File Systems.1329machine # [ 52.818490] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1330machine # [ 52.822959] systemd[1]: Starting Create SUID/SGID Wrappers...1331machine # [ 52.823898] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1332machine # [ 52.832742] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671333machine # [ 52.835022] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1334machine # [ 52.840806] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1335machine # [ 52.842178] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1336machine # [ 52.843411] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1337machine # [ 52.844787] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1338machine # [ 53.166229] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1339machine # [ 53.171085] systemd[1]: Finished Create SUID/SGID Wrappers.1340machine # [ 53.173858] systemd[1]: Starting linger-users.service...1341machine # [ 53.238961] systemd[1]: linger-users.service: Deactivated successfully.1342machine # [ 53.241996] systemd[1]: Finished linger-users.service.1343machine # [ 53.499952] nixos[2152]: finished switching to system configuration /nix/store/19a4q3aiwb16kmq58cznmv6dwvb1axyj-nixos-system-machine-test1344machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReload/bin/switch-to-configuration test 2>&1, in 3.29 seconds)1345machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&11346machine # [ 54.147394] nixos[2294]: switching to system configuration /nix/store/nx6l9vsfd584jdskbz0fsnwf1p8m5iii-nixos-system-machine-test1347machine # [ 54.150003] systemd[1]: Stopped target Local File Systems.1348machine # [ 54.152149] systemd[1]: Stopped target Remote File Systems.1349machine # [ 54.668929] systemd[1]: Reload requested from client PID 2294 ('.switch-to-conf') (unit backdoor.service)...1350machine # [ 54.670994] systemd[1]: Reloading...1351machine # [ 55.350444] systemd[1]: Reloading finished in 677 ms.1352machine # [ 55.510010] systemd[560]: Reexecution requested from client PID 2363 ('switch-to-confi')...1353machine # [ 55.513969] systemd[560]: Reexecuting.1354machine # [ 55.566400] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1355machine # [ 55.766206] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1356machine # [ 55.774027] systemd[560]: Reload requested from client PID 2363 ('switch-to-confi')...1357machine # [ 55.776700] systemd[560]: Reloading...1358machine # [ 55.970784] systemd[560]: Reloading finished in 192 ms.1359machine # [ 55.985646] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1360machine # [ 55.995275] systemd[560]: Stopped Run user-specific NixOS activation.1361machine # [ 55.997666] systemd[560]: Stopping Run user-specific NixOS activation...1362machine # [ 56.000529] systemd[560]: Starting Run user-specific NixOS activation...1363machine # [ 56.051557] systemd[560]: Finished Run user-specific NixOS activation.1364machine # [ 56.056462] systemd[1]: Stopped target Reactivate sysinit units.1365machine # [ 56.059380] systemd[1]: Stopping Reactivate sysinit units...1366machine # [ 56.061345] systemd[1]: Reached target Reactivate sysinit units.1367machine # [ 56.063373] systemd[1]: Reached target Remote File Systems.1368machine # [ 56.066799] systemd[1]: Reached target Local File Systems.1369machine # [ 56.070680] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1370machine # [ 56.075003] systemd[1]: Starting Create SUID/SGID Wrappers...1371machine # [ 56.075914] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1372machine # [ 56.080675] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671373machine # [ 56.088308] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1374machine # [ 56.090070] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1375machine # [ 56.091377] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1376machine # [ 56.098066] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1377machine # [ 56.099418] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1378machine # [ 56.440218] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1379machine # [ 56.444308] systemd[1]: Finished Create SUID/SGID Wrappers.1380machine # [ 56.448347] systemd[1]: Starting linger-users.service...1381machine # [ 56.511263] systemd[1]: linger-users.service: Deactivated successfully.1382machine # [ 56.513946] systemd[1]: Finished linger-users.service.1383machine # [ 56.773929] nixos[2294]: finished switching to system configuration /nix/store/nx6l9vsfd584jdskbz0fsnwf1p8m5iii-nixos-system-machine-test1384machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 3.27 seconds)1385machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&11386machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleService/bin/switch-to-configuration dry-activate 2>&1, in 0.68 seconds)1387machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&11388machine # [ 57.982018] nixos[2444]: switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test1389machine # [ 57.986013] systemd[1]: Stopped target Local File Systems.1390machine # [ 57.987820] systemd[1]: Stopped target Remote File Systems.1391machine # [ 57.989309] systemd[1]: test.service: Deactivated successfully.1392machine # [ 57.990591] systemd[1]: Stopped test.service.1393machine # [ 58.455676] systemd[1]: Reload requested from client PID 2444 ('.switch-to-conf') (unit backdoor.service)...1394machine # [ 58.457980] systemd[1]: Reloading...1395machine # [ 59.072943] systemd[1]: Reloading finished in 612 ms.1396machine # [ 59.224057] systemd[560]: Reexecution requested from client PID 2513 ('switch-to-confi')...1397machine # [ 59.226951] systemd[560]: Reexecuting.1398machine # [ 59.294361] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1399machine # [ 59.499131] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1400machine # [ 59.505873] systemd[560]: Reload requested from client PID 2513 ('switch-to-confi')...1401machine # [ 59.508405] systemd[560]: Reloading...1402machine # [ 59.708689] systemd[560]: Reloading finished in 199 ms.1403machine # [ 59.724174] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1404machine # [ 59.733575] systemd[560]: Stopped Run user-specific NixOS activation.1405machine # [ 59.735946] systemd[560]: Stopping Run user-specific NixOS activation...1406machine # [ 59.738967] systemd[560]: Starting Run user-specific NixOS activation...1407machine # [ 59.786912] systemd[560]: Finished Run user-specific NixOS activation.1408machine # [ 59.791078] systemd[1]: Stopped target Reactivate sysinit units.1409machine # [ 59.794356] systemd[1]: Stopping Reactivate sysinit units...1410machine # [ 59.795252] systemd[1]: Reached target Reactivate sysinit units.1411machine # [ 59.797970] systemd[1]: Reached target Remote File Systems.1412machine # [ 59.798816] systemd[1]: Reached target Local File Systems.1413machine # [ 59.805547] systemd[1]: Starting linger-users.service...1414machine # [ 59.813377] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1415machine # [ 59.817321] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1416machine # [ 59.823105] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1417machine # [ 59.827392] systemd[1]: Starting Create SUID/SGID Wrappers...1418machine # [ 59.828989] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1419machine # [ 59.830638] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671420machine # [ 59.834815] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1421machine # [ 59.840190] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1422machine # [ 59.841738] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1423machine # [ 59.905305] systemd[1]: linger-users.service: Deactivated successfully.1424machine # [ 59.908749] systemd[1]: Finished linger-users.service.1425machine # [ 60.225234] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1426machine # [ 60.228798] systemd[1]: Finished Create SUID/SGID Wrappers.1427machine # [ 60.487423] nixos[2444]: finished switching to system configuration /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test1428machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.03 seconds)1429machine: must succeed: /nix/store/5j9pwaqdmlg5jgdqki7vdldix56qqmhm-separate-activation-script/bin/switch-to-configuration test 2>&11430machine # [ 61.101780] nixos[2584]: switching to system configuration /nix/store/iwsy3idcpgnc7kgprgjnqcn96hk7vki3-nixos-system-machine-test1431machine # [ 61.105439] systemd[1]: Stopped target Local File Systems.1432machine # [ 61.106566] systemd[1]: Stopped target Remote File Systems.1433machine # [ 61.629956] systemd[1]: Reload requested from client PID 2584 ('.switch-to-conf') (unit backdoor.service)...1434machine # [ 61.631962] systemd[1]: Reloading...1435machine # [ 62.239130] systemd[1]: Reloading finished in 604 ms.1436machine # [ 62.398916] systemd[560]: Stopped Run user-specific NixOS activation.1437machine # [ 62.403301] systemd[560]: Reexecution requested from client PID 2653 ('switch-to-confi')...1438machine # [ 62.405671] systemd[560]: Reexecuting.1439machine # [ 62.475307] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1440machine # [ 62.683261] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 62.690206] systemd[560]: Reload requested from client PID 2653 ('switch-to-confi')...1442machine # [ 62.692749] systemd[560]: Reloading...1443machine # [ 62.885685] systemd[560]: Reloading finished in 191 ms.1444machine # [ 62.900204] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1445machine # [ 62.912759] systemd[1]: Stopped target Reactivate sysinit units.1446machine # [ 62.915892] systemd[1]: Stopping Reactivate sysinit units...1447machine # [ 62.917922] systemd[1]: Reached target Reactivate sysinit units.1448machine # [ 62.918907] systemd[1]: Reached target Remote File Systems.1449machine # [ 62.922633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1450machine # [ 62.925343] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1451machine # [ 62.930384] systemd[1]: Reached target Local File Systems.1452machine # [ 62.931347] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1453machine # [ 62.937731] systemd[1]: Starting Create SUID/SGID Wrappers...1454machine # [ 62.938756] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1455machine # [ 62.942430] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671456machine # [ 62.953024] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1457machine # [ 62.954707] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 62.959658] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 63.320134] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1460machine # [ 63.322440] systemd[1]: Finished Create SUID/SGID Wrappers.1461machine # [ 63.328342] systemd[1]: Starting linger-users.service...1462machine # [ 63.332874] systemd[1]: Starting test.service...1463machine # [ 63.380450] systemd[1]: Finished test.service.1464machine # [ 63.410910] systemd[1]: linger-users.service: Deactivated successfully.1465machine # [ 63.413618] systemd[1]: Finished linger-users.service.1466machine # [ 63.672608] nixos[2584]: finished switching to system configuration /nix/store/iwsy3idcpgnc7kgprgjnqcn96hk7vki3-nixos-system-machine-test1467machine: (finished: must succeed: /nix/store/5j9pwaqdmlg5jgdqki7vdldix56qqmhm-separate-activation-script/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1468machine: must succeed: ! test -e /run/current-system/activate1469machine: (finished: must succeed: ! test -e /run/current-system/activate, in 0.04 seconds)1470machine: must succeed: ! test -e /run/current-system/dry-activate1471machine: (finished: must succeed: ! test -e /run/current-system/dry-activate, in 0.03 seconds)1472machine: must succeed: ! test -e /run/current-system/bin/switch-to-configuration1473machine: (finished: must succeed: ! test -e /run/current-system/bin/switch-to-configuration, in 0.03 seconds)1474machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&11475machine # [ 64.381369] nixos[2732]: switching to system configuration /nix/store/ycjnnxs5755s9w6rd2xc87sz3aac0ahg-nixos-system-machine-test1476machine # [ 64.384906] systemd[1]: Stopped target Remote File Systems.1477machine # [ 64.387151] systemd[1]: Stopped target Local File Systems.1478machine # [ 64.389753] systemd[1]: test.service: Deactivated successfully.1479machine # [ 64.390798] systemd[1]: Stopped test.service.1480machine # [ 64.899253] systemd[1]: Reload requested from client PID 2732 ('.switch-to-conf') (unit backdoor.service)...1481machine # [ 64.901486] systemd[1]: Reloading...1482machine # [ 65.533379] systemd[1]: Reloading finished in 629 ms.1483machine # [ 65.678954] systemd[560]: Reexecution requested from client PID 2801 ('switch-to-confi')...1484machine # [ 65.681398] systemd[560]: Reexecuting.1485machine # [ 65.742454] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1486machine # [ 65.931734] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1487machine # [ 65.938855] systemd[560]: Reload requested from client PID 2801 ('switch-to-confi')...1488machine # [ 65.941711] systemd[560]: Reloading...1489machine # [ 66.137691] systemd[560]: Reloading finished in 194 ms.1490machine # [ 66.152218] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1491machine # [ 66.163930] systemd[560]: Starting Run user-specific NixOS activation...1492machine # [ 66.214523] systemd[560]: Finished Run user-specific NixOS activation.1493machine # [ 66.218851] systemd[560]: Stopped Run user-specific NixOS activation.1494machine # [ 66.219905] systemd[560]: Stopping Run user-specific NixOS activation...1495machine # [ 66.223922] systemd[560]: Starting Run user-specific NixOS activation...1496machine # [ 66.227175] systemd[1]: Stopped target Reactivate sysinit units.1497machine # [ 66.229577] systemd[1]: Stopping Reactivate sysinit units...1498machine # [ 66.230404] systemd[1]: Reached target Reactivate sysinit units.1499machine # [ 66.247499] systemd[1]: Reached target Remote File Systems.1500machine # [ 66.251797] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1501machine # [ 66.258428] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1502machine # [ 66.259883] systemd[1]: Started test.service.1503machine # [ 66.276201] systemd[1]: Starting linger-users.service...1504machine # [ 66.278633] systemd[1]: Reached target Local File Systems.1505machine # [ 66.296976] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1506machine # [ 66.312660] systemd[1]: Starting Create SUID/SGID Wrappers...1507machine # [ 66.321199] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1508machine # [ 66.324410] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671509machine # [ 66.332977] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1510machine # [ 66.334760] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1511machine # [ 66.336684] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1512machine # [ 66.339087] systemd[560]: Finished Run user-specific NixOS activation.1513machine # [ 66.358336] systemd[1]: test.service: Deactivated successfully.1514machine # [ 66.416788] systemd[1]: linger-users.service: Deactivated successfully.1515machine # [ 66.421038] systemd[1]: Finished linger-users.service.1516machine # [ 66.707088] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1517machine # [ 66.710559] systemd[1]: Finished Create SUID/SGID Wrappers.1518machine # [ 66.967198] nixos[2732]: finished switching to system configuration /nix/store/ycjnnxs5755s9w6rd2xc87sz3aac0ahg-nixos-system-machine-test1519machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithMultilineValue/bin/switch-to-configuration test 2>&1, in 3.19 seconds)1520machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&11521machine # [ 67.628553] nixos[2877]: switching to system configuration /nix/store/3r20fzmn151zapywszlv9l3ddy3qb0g8-nixos-system-machine-test1522machine # [ 67.631906] systemd[1]: Stopped target Local File Systems.1523machine # [ 67.633536] systemd[1]: Stopped target Remote File Systems.1524machine # [ 68.161372] systemd[1]: Reload requested from client PID 2877 ('.switch-to-conf') (unit backdoor.service)...1525machine # [ 68.163400] systemd[1]: Reloading...1526machine # [ 68.849689] systemd[1]: Reloading finished in 682 ms.1527machine # [ 69.002930] systemd[560]: Reexecution requested from client PID 2946 ('switch-to-confi')...1528machine # [ 69.006853] systemd[560]: Reexecuting.1529machine # [ 69.070434] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1530machine # [ 69.271087] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1531machine # [ 69.278077] systemd[560]: Reload requested from client PID 2946 ('switch-to-confi')...1532machine # [ 69.280811] systemd[560]: Reloading...1533machine # [ 69.478431] systemd[560]: Reloading finished in 196 ms.1534machine # [ 69.493669] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1535machine # [ 69.501929] systemd[560]: Stopped Run user-specific NixOS activation.1536machine # [ 69.504893] systemd[560]: Stopping Run user-specific NixOS activation...1537machine # [ 69.508063] systemd[560]: Starting Run user-specific NixOS activation...1538machine # [ 69.555459] systemd[560]: Finished Run user-specific NixOS activation.1539machine # [ 69.560877] systemd[1]: Stopped target Reactivate sysinit units.1540machine # [ 69.561836] systemd[1]: Stopping Reactivate sysinit units...1541machine # [ 69.562618] systemd[1]: Reached target Reactivate sysinit units.1542machine # [ 69.566990] systemd[1]: Reached target Local File Systems.1543machine # [ 69.570636] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1544machine # [ 69.575812] systemd[1]: Starting Create SUID/SGID Wrappers...1545machine # [ 69.580450] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1546machine # [ 69.581729] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671547machine # [ 69.583907] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1548machine # [ 69.594037] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1549machine # [ 69.595662] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1550machine # [ 69.599441] systemd[1]: Reached target Remote File Systems.1551machine # [ 69.600819] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1552machine # [ 69.602606] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1553machine # [ 69.924083] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1554machine # [ 69.928584] systemd[1]: Finished Create SUID/SGID Wrappers.1555machine # [ 69.932379] systemd[1]: Starting escaped\x2ddash.service...1556machine # [ 69.935304] systemd[1]: Starting linger-users.service...1557machine # [ 69.981991] systemd[1]: Finished escaped\x2ddash.service.1558machine # [ 70.012204] systemd[1]: linger-users.service: Deactivated successfully.1559machine # [ 70.013248] systemd[1]: Finished linger-users.service.1560machine # [ 70.270565] nixos[2877]: finished switching to system configuration /nix/store/3r20fzmn151zapywszlv9l3ddy3qb0g8-nixos-system-machine-test1561machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithBackslash/bin/switch-to-configuration test 2>&1, in 3.30 seconds)1562machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&11563machine # [ 70.931357] nixos[3019]: switching to system configuration /nix/store/hzhw4rr790wndv526231dwixdjvqv89k-nixos-system-machine-test1564machine # [ 70.935585] systemd[1]: Stopped target Local File Systems.1565machine # [ 70.937582] systemd[1]: Stopped target Remote File Systems.1566machine # [ 70.938452] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1567machine # [ 70.939423] systemd[1]: Stopped escaped\x2ddash.service.1568machine # [ 71.451399] systemd[1]: Reload requested from client PID 3019 ('.switch-to-conf') (unit backdoor.service)...1569machine # [ 71.453705] systemd[1]: Reloading...1570machine # [ 72.080332] systemd[1]: Reloading finished in 624 ms.1571machine # [ 72.227388] systemd[560]: Reexecution requested from client PID 3088 ('switch-to-confi')...1572machine # [ 72.231402] systemd[560]: Reexecuting.1573machine # [ 72.278246] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1574machine # [ 72.473364] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1575machine # [ 72.480637] systemd[560]: Reload requested from client PID 3088 ('switch-to-confi')...1576machine # [ 72.483007] systemd[560]: Reloading...1577machine # [ 72.679465] systemd[560]: Reloading finished in 195 ms.1578machine # [ 72.692162] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1579machine # [ 72.702460] systemd[560]: Stopped Run user-specific NixOS activation.1580machine # [ 72.703611] systemd[560]: Stopping Run user-specific NixOS activation...1581machine # [ 72.706600] systemd[560]: Starting Run user-specific NixOS activation...1582machine # [ 72.754626] systemd[560]: Finished Run user-specific NixOS activation.1583machine # [ 72.758816] systemd[1]: Stopped target Reactivate sysinit units.1584machine # [ 72.761486] systemd[1]: Stopping Reactivate sysinit units...1585machine # [ 72.762959] systemd[1]: Reached target Reactivate sysinit units.1586machine # [ 72.768095] systemd[1]: Starting escaped\x2ddash.service...1587machine # [ 72.770909] systemd[1]: Reached target Local File Systems.1588machine # [ 72.774833] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1589machine # [ 72.782234] systemd[1]: Starting Create SUID/SGID Wrappers...1590machine # [ 72.783612] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1591machine # [ 72.789488] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671592machine # [ 72.791953] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1593machine # [ 72.801357] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1594machine # [ 72.811357] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1595machine # [ 72.813379] systemd[1]: Reached target Remote File Systems.1596machine # [ 72.816236] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1597machine # [ 72.818191] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1598machine # [ 72.841792] systemd[1]: Finished escaped\x2ddash.service.1599machine # [ 73.160315] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1600machine # [ 73.164659] systemd[1]: Finished Create SUID/SGID Wrappers.1601machine # [ 73.168386] systemd[1]: Starting linger-users.service...1602machine # [ 73.232870] systemd[1]: linger-users.service: Deactivated successfully.1603machine # [ 73.235673] systemd[1]: Finished linger-users.service.1604machine # [ 73.494045] nixos[3019]: finished switching to system configuration /nix/store/hzhw4rr790wndv526231dwixdjvqv89k-nixos-system-machine-test1605machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithBackslashModified/bin/switch-to-configuration test 2>&1, in 3.23 seconds)1606machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&11607machine # [ 74.162775] nixos[3161]: switching to system configuration /nix/store/581kzc00wd5g1nngc919zxfygj3x9dbi-nixos-system-machine-test1608machine # [ 74.166892] systemd[1]: Stopped target Local File Systems.1609machine # [ 74.168781] systemd[1]: Stopped target Remote File Systems.1610machine # [ 74.169687] systemd[1]: escaped\x2ddash.service: Deactivated successfully.1611machine # [ 74.170641] systemd[1]: Stopped escaped\x2ddash.service.1612machine # [ 74.743717] systemd[1]: Reload requested from client PID 3161 ('.switch-to-conf') (unit backdoor.service)...1613machine # [ 74.746137] systemd[1]: Reloading...1614machine # [ 75.417822] systemd[1]: Reloading finished in 668 ms.1615machine # [ 75.565599] systemd[560]: Reexecution requested from client PID 3230 ('switch-to-confi')...1616machine # [ 75.570098] systemd[560]: Reexecuting.1617machine # [ 75.634567] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1618machine # [ 75.835821] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1619machine # [ 75.843337] systemd[560]: Reload requested from client PID 3230 ('switch-to-confi')...1620machine # [ 75.845999] systemd[560]: Reloading...1621machine # [ 76.044641] systemd[560]: Reloading finished in 197 ms.1622machine # [ 76.061733] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1623machine # [ 76.069794] systemd[560]: Stopped Run user-specific NixOS activation.1624machine # [ 76.072157] systemd[560]: Stopping Run user-specific NixOS activation...1625machine # [ 76.075469] systemd[560]: Starting Run user-specific NixOS activation...1626machine # [ 76.124435] systemd[560]: Finished Run user-specific NixOS activation.1627machine # [ 76.128714] systemd[1]: Stopped target Reactivate sysinit units.1628machine # [ 76.130600] systemd[1]: Stopping Reactivate sysinit units...1629machine # [ 76.132424] systemd[1]: Reached target Reactivate sysinit units.1630machine # [ 76.136188] systemd[1]: Reached target Remote File Systems.1631machine # [ 76.140253] systemd[1]: Starting -.service...1632machine # [ 76.142930] systemd[1]: Starting linger-users.service...1633machine # [ 76.147334] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1634machine # [ 76.157873] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1635machine # [ 76.162595] systemd[1]: Reached target Local File Systems.1636machine # [ 76.165738] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1637machine # [ 76.171349] systemd[1]: Starting Create SUID/SGID Wrappers...1638machine # [ 76.173144] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1639machine # [ 76.175895] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671640machine # [ 76.186937] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1641machine # [ 76.192499] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1642machine # [ 76.195877] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1643machine # [ 76.231046] systemd[1]: Finished -.service.1644machine # [ 76.267503] systemd[1]: linger-users.service: Deactivated successfully.1645machine # [ 76.271008] systemd[1]: Finished linger-users.service.1646machine # [ 76.575071] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1647machine # [ 76.579047] systemd[1]: Finished Create SUID/SGID Wrappers.1648machine # [ 76.838937] nixos[3161]: finished switching to system configuration /nix/store/581kzc00wd5g1nngc919zxfygj3x9dbi-nixos-system-machine-test1649machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitStartingWithDash/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1650machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&11651machine # [ 77.534889] nixos[3303]: switching to system configuration /nix/store/xvw6jkq06xbqkslqk48cdpqnbbs65g80-nixos-system-machine-test1652machine # [ 77.538035] systemd[1]: Stopped target Local File Systems.1653machine # [ 77.539557] systemd[1]: Stopped target Remote File Systems.1654machine # [ 78.078225] systemd[1]: Reload requested from client PID 3303 ('.switch-to-conf') (unit backdoor.service)...1655machine # [ 78.080411] systemd[1]: Reloading...1656machine # [ 78.787884] systemd[1]: Reloading finished in 705 ms.1657machine # [ 78.943336] systemd[560]: Reexecution requested from client PID 3372 ('switch-to-confi')...1658machine # [ 78.945818] systemd[560]: Reexecuting.1659machine # [ 79.014345] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1660machine # [ 79.225395] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1661machine # [ 79.232158] systemd[560]: Reload requested from client PID 3372 ('switch-to-confi')...1662machine # [ 79.234959] systemd[560]: Reloading...1663machine # [ 79.447919] systemd[560]: Reloading finished in 211 ms.1664machine # [ 79.464212] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1665machine # [ 79.475104] systemd[560]: Stopped Run user-specific NixOS activation.1666machine # [ 79.477769] systemd[560]: Stopping Run user-specific NixOS activation...1667machine # [ 79.480908] systemd[560]: Starting Run user-specific NixOS activation...1668machine # [ 79.534340] systemd[560]: Finished Run user-specific NixOS activation.1669machine # [ 79.539059] systemd[1]: Stopped target Reactivate sysinit units.1670machine # [ 79.543624] systemd[1]: Stopping Reactivate sysinit units...1671machine # [ 79.544950] systemd[1]: Reached target Reactivate sysinit units.1672machine # [ 79.547775] systemd[1]: Reloading -.service...1673machine # [ 79.587575] systemd[1]: Reloaded -.service.1674machine # [ 79.593439] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1675machine # [ 79.597871] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1676machine # [ 79.599736] systemd[1]: Reached target Local File Systems.1677machine # [ 79.603126] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1678machine # [ 79.610427] systemd[1]: Starting Create SUID/SGID Wrappers...1679machine # [ 79.611452] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1680machine # [ 79.617257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671681machine # [ 79.624323] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1682machine # [ 79.625984] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1683machine # [ 79.627332] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1684machine # [ 79.629677] systemd[1]: Reached target Remote File Systems.1685machine # [ 80.015378] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1686machine # [ 80.020548] systemd[1]: Finished Create SUID/SGID Wrappers.1687machine # [ 80.023291] systemd[1]: Starting linger-users.service...1688machine # [ 80.090624] systemd[1]: linger-users.service: Deactivated successfully.1689machine # [ 80.093893] systemd[1]: Finished linger-users.service.1690machine # [ 80.351116] nixos[3303]: finished switching to system configuration /nix/store/xvw6jkq06xbqkslqk48cdpqnbbs65g80-nixos-system-machine-test1691machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitStartingWithDashModified/bin/switch-to-configuration test 2>&1, in 3.51 seconds)1692machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&11693machine # [ 81.052490] nixos[3445]: switching to system configuration /nix/store/z525138f6qpi297g4dv1q75n4yglp60d-nixos-system-machine-test1694machine # [ 81.056618] systemd[1]: Stopped target Local File Systems.1695machine # [ 81.059122] systemd[1]: Stopped target Remote File Systems.1696machine # [ 81.060222] systemd[1]: -.service: Deactivated successfully.1697machine # [ 81.061556] systemd[1]: Stopped -.service.1698machine # [ 81.603376] systemd[1]: Reload requested from client PID 3445 ('.switch-to-conf') (unit backdoor.service)...1699machine # [ 81.605618] systemd[1]: Reloading...1700machine # [ 82.307459] systemd[1]: Reloading finished in 699 ms.1701machine # [ 82.462418] systemd[560]: Reexecution requested from client PID 3514 ('switch-to-confi')...1702machine # [ 82.463991] systemd[560]: Reexecuting.1703machine # [ 82.535034] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1704machine # [ 82.750242] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1705machine # [ 82.757511] systemd[560]: Reload requested from client PID 3514 ('switch-to-confi')...1706machine # [ 82.760833] systemd[560]: Reloading...1707machine # [ 82.964898] systemd[560]: Reloading finished in 205 ms.1708machine # [ 82.980243] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1709machine # [ 82.992113] systemd[560]: Stopped Run user-specific NixOS activation.1710machine # [ 82.993241] systemd[560]: Stopping Run user-specific NixOS activation...1711machine # [ 82.997108] systemd[560]: Starting Run user-specific NixOS activation...1712machine # [ 83.049351] systemd[560]: Finished Run user-specific NixOS activation.1713machine # [ 83.053937] systemd[1]: Stopped target Reactivate sysinit units.1714machine # [ 83.055860] systemd[1]: Stopping Reactivate sysinit units...1715machine # [ 83.057596] systemd[1]: Reached target Reactivate sysinit units.1716machine # [ 83.059528] systemd[1]: Reached target Remote File Systems.1717machine # [ 83.066648] systemd[1]: Reached target Local File Systems.1718machine # [ 83.070508] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1719machine # [ 83.075210] systemd[1]: Starting Create SUID/SGID Wrappers...1720machine # [ 83.080346] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1721machine # [ 83.081659] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671722machine # [ 83.083795] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1723machine # [ 83.091673] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1724machine # [ 83.094926] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1725machine # [ 83.096738] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1726machine # [ 83.098990] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1727machine # [ 83.441810] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1728machine # [ 83.446456] systemd[1]: Finished Create SUID/SGID Wrappers.1729machine # [ 83.450567] systemd[1]: Starting linger-users.service...1730machine # [ 83.453102] systemd[1]: Starting required-service.service...1731machine # [ 83.461367] systemd[1]: Starting test-service.service...1732machine # [ 83.513953] systemd[1]: Finished required-service.service.1733machine # [ 83.516528] systemd[1]: Finished test-service.service.1734machine # [ 83.547690] systemd[1]: linger-users.service: Deactivated successfully.1735machine # [ 83.550003] systemd[1]: Finished linger-users.service.1736machine # [ 83.810885] nixos[3445]: finished switching to system configuration /nix/store/z525138f6qpi297g4dv1q75n4yglp60d-nixos-system-machine-test1737machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirement/bin/switch-to-configuration test 2>&1, in 3.46 seconds)1738machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&11739machine # [ 84.509608] nixos[3589]: switching to system configuration /nix/store/0pfy2x2ba7sjicf510qhcqllgshs5fqk-nixos-system-machine-test1740machine # [ 84.514113] systemd[1]: Stopped target Local File Systems.1741machine # [ 84.516493] systemd[1]: Stopped target Remote File Systems.1742machine # [ 84.517389] systemd[1]: required-service.service: Deactivated successfully.1743machine # [ 84.518378] systemd[1]: Stopped required-service.service.1744machine # [ 84.519157] systemd[1]: test-service.service: Deactivated successfully.1745machine # [ 84.521121] systemd[1]: Stopped test-service.service.1746machine # [ 85.058341] systemd[1]: Reload requested from client PID 3589 ('.switch-to-conf') (unit backdoor.service)...1747machine # [ 85.060567] systemd[1]: Reloading...1748machine # [ 85.731424] systemd[1]: Reloading finished in 668 ms.1749machine # [ 85.872420] systemd[560]: Reexecution requested from client PID 3658 ('switch-to-confi')...1750machine # [ 85.875707] systemd[560]: Reexecuting.1751machine # [ 85.938519] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1752machine # [ 86.121181] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1753machine # [ 86.127919] systemd[560]: Reload requested from client PID 3658 ('switch-to-confi')...1754machine # [ 86.132128] systemd[560]: Reloading...1755machine # [ 86.325149] systemd[560]: Reloading finished in 193 ms.1756machine # [ 86.340195] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1757machine # [ 86.350852] systemd[560]: Stopped Run user-specific NixOS activation.1758machine # [ 86.353502] systemd[560]: Stopping Run user-specific NixOS activation...1759machine # [ 86.356701] systemd[560]: Starting Run user-specific NixOS activation...1760machine # [ 86.409389] systemd[560]: Finished Run user-specific NixOS activation.1761machine # [ 86.413886] systemd[1]: Stopped target Reactivate sysinit units.1762machine # [ 86.415883] systemd[1]: Stopping Reactivate sysinit units...1763machine # [ 86.417303] systemd[1]: Reached target Reactivate sysinit units.1764machine # [ 86.427382] systemd[1]: Starting required-service.service...1765machine # [ 86.430995] systemd[1]: Starting test-service.service...1766machine # [ 86.437472] systemd[1]: Reached target Remote File Systems.1767machine # [ 86.442462] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1768machine # [ 86.456444] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1769machine # [ 86.458107] systemd[1]: Reached target Local File Systems.1770machine # [ 86.459231] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1771machine # [ 86.474097] systemd[1]: Starting Create SUID/SGID Wrappers...1772machine # [ 86.475094] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1773machine # [ 86.483333] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671774machine # [ 86.488390] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1775machine # [ 86.490689] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1776machine # [ 86.493934] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1777machine # [ 86.520993] systemd[1]: Finished required-service.service.1778machine # [ 86.529667] systemd[1]: Finished test-service.service.1779machine # [ 86.814737] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1780machine # [ 86.819625] systemd[1]: Finished Create SUID/SGID Wrappers.1781machine # [ 86.822254] systemd[1]: Starting linger-users.service...1782machine # [ 86.884159] systemd[1]: linger-users.service: Deactivated successfully.1783machine # [ 86.886953] systemd[1]: Finished linger-users.service.1784machine # [ 87.145133] nixos[3589]: finished switching to system configuration /nix/store/0pfy2x2ba7sjicf510qhcqllgshs5fqk-nixos-system-machine-test1785machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirementModified/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1786machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&11787machine # [ 87.797720] nixos[3733]: switching to system configuration /nix/store/5cbv8yaj2flm19d5rlmhvf0zy5ns26zj-nixos-system-machine-test1788machine # [ 87.801715] systemd[1]: Stopped target Local File Systems.1789machine # [ 87.803858] systemd[1]: Stopped target Remote File Systems.1790machine # [ 87.805625] systemd[1]: required-service.service: Deactivated successfully.1791machine # [ 87.806613] systemd[1]: Stopped required-service.service.1792machine # [ 87.807382] systemd[1]: test-service.service: Deactivated successfully.1793machine # [ 87.808484] systemd[1]: Stopped test-service.service.1794machine # [ 88.339678] systemd[1]: Reload requested from client PID 3733 ('.switch-to-conf') (unit backdoor.service)...1795machine # [ 88.341961] systemd[1]: Reloading...1796machine # [ 89.042145] systemd[1]: Reloading finished in 697 ms.1797machine # [ 89.194699] systemd[560]: Reexecution requested from client PID 3802 ('switch-to-confi')...1798machine # [ 89.198405] systemd[560]: Reexecuting.1799machine # [ 89.262148] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1800machine # [ 89.463617] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1801machine # [ 89.470602] systemd[560]: Reload requested from client PID 3802 ('switch-to-confi')...1802machine # [ 89.473397] systemd[560]: Reloading...1803machine # [ 89.667407] systemd[560]: Reloading finished in 192 ms.1804machine # [ 89.680180] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1805machine # [ 89.690683] systemd[560]: Stopped Run user-specific NixOS activation.1806machine # [ 89.693616] systemd[560]: Stopping Run user-specific NixOS activation...1807machine # [ 89.696948] systemd[560]: Starting Run user-specific NixOS activation...1808machine # [ 89.749044] systemd[560]: Finished Run user-specific NixOS activation.1809machine # [ 89.753797] systemd[1]: Stopped target Reactivate sysinit units.1810machine # [ 89.756702] systemd[1]: Stopping Reactivate sysinit units...1811machine # [ 89.758114] systemd[1]: Reached target Reactivate sysinit units.1812machine # [ 89.762985] systemd[1]: Reached target Local File Systems.1813machine # [ 89.766767] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1814machine # [ 89.771079] systemd[1]: Starting Create SUID/SGID Wrappers...1815machine # [ 89.772002] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1816machine # [ 89.780111] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671817machine # [ 89.782497] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1818machine # [ 89.785846] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1819machine # [ 89.790385] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1820machine # [ 89.793260] systemd[1]: Reached target Remote File Systems.1821machine # [ 89.797370] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1822machine # [ 89.800502] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1823machine # [ 90.130087] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1824machine # [ 90.135190] systemd[1]: Finished Create SUID/SGID Wrappers.1825machine # [ 90.139390] systemd[1]: Starting linger-users.service...1826machine # [ 90.143641] systemd[1]: Starting required-service.service...1827machine # [ 90.147093] systemd[1]: Starting test-service.service...1828machine # [ 90.201267] systemd[1]: Finished required-service.service.1829machine # [ 90.203950] systemd[1]: Finished test-service.service.1830machine # [ 90.231030] systemd[1]: linger-users.service: Deactivated successfully.1831machine # [ 90.234428] systemd[1]: Finished linger-users.service.1832machine # [ 90.491161] nixos[3733]: finished switching to system configuration /nix/store/5cbv8yaj2flm19d5rlmhvf0zy5ns26zj-nixos-system-machine-test1833machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithRequirementModifiedNostart/bin/switch-to-configuration test 2>&1, in 3.34 seconds)1834machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&11835machine # [ 91.157637] nixos[3877]: switching to system configuration /nix/store/qw578f50pkddf803lnssgzxdvzrd976q-nixos-system-machine-test1836machine # [ 91.162165] systemd[1]: Stopped target Local File Systems.1837machine # [ 91.165266] systemd[1]: Stopped target Remote File Systems.1838machine # [ 91.166356] systemd[1]: required-service.service: Deactivated successfully.1839machine # [ 91.167460] systemd[1]: Stopped required-service.service.1840machine # [ 91.168867] systemd[1]: test-service.service: Deactivated successfully.1841machine # [ 91.169836] systemd[1]: Stopped test-service.service.1842machine # [ 91.691982] systemd[1]: Reload requested from client PID 3877 ('.switch-to-conf') (unit backdoor.service)...1843machine # [ 91.693997] systemd[1]: Reloading...1844machine # [ 92.341594] systemd[1]: Reloading finished in 645 ms.1845machine # [ 92.486797] systemd[560]: Reexecution requested from client PID 3946 ('switch-to-confi')...1846machine # [ 92.491181] systemd[560]: Reexecuting.1847machine # [ 92.554340] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1848machine # [ 92.752747] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1849machine # [ 92.760206] systemd[560]: Reload requested from client PID 3946 ('switch-to-confi')...1850machine # [ 92.762871] systemd[560]: Reloading...1851machine # [ 92.953381] systemd[560]: Reloading finished in 189 ms.1852machine # [ 92.968188] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1853machine # [ 92.977673] systemd[560]: Stopped Run user-specific NixOS activation.1854machine # [ 92.980211] systemd[560]: Stopping Run user-specific NixOS activation...1855machine # [ 92.983248] systemd[560]: Starting Run user-specific NixOS activation...1856machine # [ 93.033306] systemd[560]: Finished Run user-specific NixOS activation.1857machine # [ 93.038126] systemd[1]: Stopped target Reactivate sysinit units.1858machine # [ 93.040607] systemd[1]: Stopping Reactivate sysinit units...1859machine # [ 93.042082] systemd[1]: Reached target Reactivate sysinit units.1860machine # [ 93.047625] systemd[1]: Created slice Slice /system/instantiated.1861machine # [ 93.049628] systemd[1]: Reached target Remote File Systems.1862machine # [ 93.056481] systemd[1]: Starting instantiated@one.service...1863machine # [ 93.060235] systemd[1]: Starting instantiated@two.service...1864machine # [ 93.065323] systemd[1]: Starting linger-users.service...1865machine # [ 93.081055] systemd[1]: Reached target Local File Systems.1866machine # [ 93.084399] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1867machine # [ 93.101038] systemd[1]: Starting Create SUID/SGID Wrappers...1868machine # [ 93.102139] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1869machine # [ 93.103477] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671870machine # [ 93.125635] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1871machine # [ 93.129553] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1872machine # [ 93.133820] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1873machine # [ 93.138293] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1874machine # [ 93.139994] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1875machine # [ 93.183530] systemd[1]: Finished instantiated@one.service.1876machine # [ 93.184989] systemd[1]: Finished instantiated@two.service.1877machine # [ 93.230658] systemd[1]: linger-users.service: Deactivated successfully.1878machine # [ 93.234548] systemd[1]: Finished linger-users.service.1879machine # [ 93.534426] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1880machine # [ 93.537598] systemd[1]: Finished Create SUID/SGID Wrappers.1881machine # [ 93.798559] nixos[3877]: finished switching to system configuration /nix/store/qw578f50pkddf803lnssgzxdvzrd976q-nixos-system-machine-test1882machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithTemplate/bin/switch-to-configuration test 2>&1, in 3.31 seconds)1883machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&11884machine # [ 94.417492] nixos[4021]: switching to system configuration /nix/store/22d0cl8chysykxabhy05rsrnmbx1h3ac-nixos-system-machine-test1885machine # [ 94.420308] systemd[1]: instantiated@one.service: Deactivated successfully.1886machine # [ 94.425193] systemd[1]: Stopped instantiated@one.service.1887machine # [ 94.426460] systemd[1]: Stopped target Remote File Systems.1888machine # [ 94.427803] systemd[1]: Stopped target Local File Systems.1889machine # [ 94.429084] systemd[1]: instantiated@two.service: Deactivated successfully.1890machine # [ 94.430516] systemd[1]: Stopped instantiated@two.service.1891machine # [ 94.965913] systemd[1]: Reload requested from client PID 4021 ('.switch-to-conf') (unit backdoor.service)...1892machine # [ 94.967903] systemd[1]: Reloading...1893machine # [ 95.627405] systemd[1]: Reloading finished in 657 ms.1894machine # [ 95.780745] systemd[560]: Reexecution requested from client PID 4089 ('switch-to-confi')...1895machine # [ 95.783701] systemd[560]: Reexecuting.1896machine # [ 95.842216] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1897machine # [ 96.022542] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1898machine # [ 96.030046] systemd[560]: Reload requested from client PID 4089 ('switch-to-confi')...1899machine # [ 96.032921] systemd[560]: Reloading...1900machine # [ 96.227527] systemd[560]: Reloading finished in 193 ms.1901machine # [ 96.240209] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1902machine # [ 96.250254] systemd[560]: Stopped Run user-specific NixOS activation.1903machine # [ 96.252980] systemd[560]: Stopping Run user-specific NixOS activation...1904machine # [ 96.256107] systemd[560]: Starting Run user-specific NixOS activation...1905machine # [ 96.306116] systemd[560]: Finished Run user-specific NixOS activation.1906machine # [ 96.311394] systemd[1]: Stopped target Reactivate sysinit units.1907machine # [ 96.314234] systemd[1]: Stopping Reactivate sysinit units...1908machine # [ 96.319429] systemd[1]: Reached target Reactivate sysinit units.1909machine # [ 96.321364] systemd[1]: Reached target Remote File Systems.1910machine # [ 96.324403] systemd[1]: Starting instantiated@one.service...1911machine # [ 96.330403] systemd[1]: Starting instantiated@two.service...1912machine # [ 96.336448] systemd[1]: Starting linger-users.service...1913machine # [ 96.354924] systemd[1]: Reached target Local File Systems.1914machine # [ 96.361994] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1915machine # [ 96.363505] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1916machine # [ 96.369663] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1917machine # [ 96.381999] systemd[1]: Starting Create SUID/SGID Wrappers...1918machine # [ 96.382996] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1919machine # [ 96.390108] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671920machine # [ 96.397718] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1921machine # [ 96.399488] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1922machine # [ 96.407089] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1923machine # [ 96.439584] systemd[1]: Finished instantiated@one.service.1924machine # [ 96.443601] systemd[1]: Finished instantiated@two.service.1925machine # [ 96.490114] systemd[1]: linger-users.service: Deactivated successfully.1926machine # [ 96.494741] systemd[1]: Finished linger-users.service.1927machine # [ 96.799414] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1928machine # [ 96.802880] systemd[1]: Finished Create SUID/SGID Wrappers.1929machine # [ 97.061014] nixos[4021]: finished switching to system configuration /nix/store/22d0cl8chysykxabhy05rsrnmbx1h3ac-nixos-system-machine-test1930machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/unitWithTemplateModified/bin/switch-to-configuration test 2>&1, in 3.26 seconds)1931(finished: subtest: services, in 69.78 seconds)1932subtest: failing units1933machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&11934machine # [ 97.675336] nixos[4165]: switching to system configuration /nix/store/68g3ac6i44dp6spfylk9v6fm02jjd1wi-nixos-system-machine-test1935machine # [ 97.678389] systemd[1]: instantiated@one.service: Deactivated successfully.1936machine # [ 97.682347] systemd[1]: Stopped instantiated@one.service.1937machine # [ 97.683345] systemd[1]: instantiated@two.service: Deactivated successfully.1938machine # [ 97.685430] systemd[1]: Stopped instantiated@two.service.1939machine # [ 97.686598] systemd[1]: Stopped target Remote File Systems.1940machine # [ 97.687713] systemd[1]: Stopped target Local File Systems.1941machine # [ 98.161587] systemd[1]: Reload requested from client PID 4165 ('.switch-to-conf') (unit backdoor.service)...1942machine # [ 98.163690] systemd[1]: Reloading...1943machine # [ 98.782211] systemd[1]: Reloading finished in 615 ms.1944machine # [ 98.937441] systemd[560]: Reexecution requested from client PID 4234 ('switch-to-confi')...1945machine # [ 98.942001] systemd[560]: Reexecuting.1946machine # [ 99.002198] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1947machine # [ 99.201436] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1948machine # [ 99.208185] systemd[560]: Reload requested from client PID 4234 ('switch-to-confi')...1949machine # [ 99.210791] systemd[560]: Reloading...1950machine # [ 99.410981] systemd[560]: Reloading finished in 199 ms.1951machine # [ 99.424189] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1952machine # [ 99.434365] systemd[560]: Stopped Run user-specific NixOS activation.1953machine # [ 99.435532] systemd[560]: Stopping Run user-specific NixOS activation...1954machine # [ 99.439952] systemd[560]: Starting Run user-specific NixOS activation...1955machine # [ 99.487532] systemd[560]: Finished Run user-specific NixOS activation.1956machine # [ 99.491770] systemd[1]: Stopped target Reactivate sysinit units.1957machine # [ 99.493545] systemd[1]: Stopping Reactivate sysinit units...1958machine # [ 99.494402] systemd[1]: Reached target Reactivate sysinit units.1959machine # [ 99.498936] systemd[1]: Reached target Remote File Systems.1960machine # [ 99.507770] systemd[1]: Starting linger-users.service...1961machine # [ 99.511252] systemd[1]: Starting test.service...1962machine # [ 99.519447] systemd[1]: Reached target Local File Systems.1963machine # [ 99.525904] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1964machine # [ 99.527548] systemd[1]: Starting Create SUID/SGID Wrappers...1965machine # [ 99.542711] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1966machine # [ 99.544490] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671967machine # [ 99.546731] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1968machine # [ 99.551043] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1969machine # [ 99.553367] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1970machine # [ 99.555957] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1971machine # [ 99.558792] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1972machine # [ 99.592468] systemd[1]: Finished test.service.1973machine # [ 99.630390] systemd[1]: linger-users.service: Deactivated successfully.1974machine # [ 99.631540] systemd[1]: Finished linger-users.service.1975machine # [ 99.933258] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1976machine # [ 99.936253] systemd[1]: Finished Create SUID/SGID Wrappers.1977machine # [ 100.194733] nixos[4165]: finished switching to system configuration /nix/store/68g3ac6i44dp6spfylk9v6fm02jjd1wi-nixos-system-machine-test1978machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceModified/bin/switch-to-configuration test 2>&1, in 3.13 seconds)1979machine: must fail: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&11980machine # [ 100.820447] nixos[4307]: switching to system configuration /nix/store/hn4mvjjh5fnr12ds0aw5bqyvjbd3g577-nixos-system-machine-test1981machine # [ 100.823752] systemd[1]: test.service: Deactivated successfully.1982machine # [ 100.825860] systemd[1]: Stopped test.service.1983machine # [ 100.826607] systemd[1]: Stopped target Local File Systems.1984machine # [ 100.828061] systemd[1]: Stopped target Remote File Systems.1985machine # [ 101.346918] systemd[1]: Reload requested from client PID 4307 ('.switch-to-conf') (unit backdoor.service)...1986machine # [ 101.349348] systemd[1]: Reloading...1987machine # [ 101.961535] systemd[1]: Reloading finished in 609 ms.1988machine # [ 102.119101] systemd[560]: Reexecution requested from client PID 4376 ('switch-to-confi')...1989machine # [ 102.123036] systemd[560]: Reexecuting.1990machine # [ 102.174049] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1991machine # [ 102.375692] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1992machine # [ 102.383458] systemd[560]: Reload requested from client PID 4376 ('switch-to-confi')...1993machine # [ 102.386820] systemd[560]: Reloading...1994machine # [ 102.568630] systemd[560]: Reloading finished in 182 ms.1995machine # [ 102.584325] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1996machine # [ 102.596585] systemd[560]: Stopped Run user-specific NixOS activation.1997machine # [ 102.599329] systemd[560]: Stopping Run user-specific NixOS activation...1998machine # [ 102.602859] systemd[560]: Starting Run user-specific NixOS activation...1999machine # [ 102.655325] systemd[560]: Finished Run user-specific NixOS activation.2000machine # [ 102.660714] systemd[1]: Stopped target Reactivate sysinit units.2001machine # [ 102.662912] systemd[1]: Stopping Reactivate sysinit units...2002machine # [ 102.664884] systemd[1]: Reached target Reactivate sysinit units.2003machine # [ 102.674191] systemd[1]: Starting test.service...2004machine # [ 102.687312] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2005machine # [ 102.691971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2006machine # [ 102.694095] systemd[1]: Reached target Local File Systems.2007machine # [ 102.695009] systemd[1]: Reached target Remote File Systems.2008machine # [ 102.699800] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2009machine # [ 102.709056] systemd[1]: Starting Create SUID/SGID Wrappers...2010machine # [ 102.710159] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2011machine # [ 102.711450] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672012machine # [ 102.714181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2013machine # [ 102.718725] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2014machine # [ 102.721101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2015machine # [ 102.751643] systemd[1]: test.service: Main process exited, code=exited, status=1/FAILURE2016machine # [ 102.756427] systemd[1]: test.service: Failed with result 'exit-code'.2017machine # [ 102.757800] systemd[1]: Failed to start test.service.2018machine # [ 103.108804] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2019machine # [ 103.114306] systemd[1]: Finished Create SUID/SGID Wrappers.2020machine # [ 103.117113] systemd[1]: Starting linger-users.service...2021machine # [ 103.190324] systemd[1]: linger-users.service: Deactivated successfully.2022machine # [ 103.193232] systemd[1]: Finished linger-users.service.2023machine # [ 103.502862] nixos[4307]: switching to system configuration /nix/store/hn4mvjjh5fnr12ds0aw5bqyvjbd3g577-nixos-system-machine-test failed (status 4)2024machine: (finished: must fail: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceFailing/bin/switch-to-configuration test 2>&1, in 3.30 seconds)2025machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12026machine # [ 104.160188] nixos[4450]: switching to system configuration /nix/store/3lbv0h1lp0xhm8kpzhfhsdkx77rml1bz-nixos-system-machine-test2027machine # [ 104.163699] systemd[1]: Stopped target Local File Systems.2028machine # [ 104.165060] systemd[1]: Stopped target Remote File Systems.2029machine # [ 104.733108] systemd[1]: Reload requested from client PID 4450 ('.switch-to-conf') (unit backdoor.service)...2030machine # [ 104.735258] systemd[1]: Reloading...2031machine # [ 105.446060] systemd[1]: Reloading finished in 707 ms.2032machine # [ 105.607310] systemd[560]: Reexecution requested from client PID 4519 ('switch-to-confi')...2033machine # [ 105.611131] systemd[560]: Reexecuting.2034machine # [ 105.667245] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2035machine # [ 105.872261] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2036machine # [ 105.878854] systemd[560]: Reload requested from client PID 4519 ('switch-to-confi')...2037machine # [ 105.881506] systemd[560]: Reloading...2038machine # [ 106.084103] systemd[560]: Reloading finished in 201 ms.2039machine # [ 106.100176] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2040machine # [ 106.110429] systemd[560]: Stopped Run user-specific NixOS activation.2041machine # [ 106.112921] systemd[560]: Stopping Run user-specific NixOS activation...2042machine # [ 106.115967] systemd[560]: Starting Run user-specific NixOS activation...2043machine # [ 106.164142] systemd[560]: Finished Run user-specific NixOS activation.2044machine # [ 106.168694] systemd[1]: Stopped target Reactivate sysinit units.2045machine # [ 106.171884] systemd[1]: Stopping Reactivate sysinit units...2046machine # [ 106.173701] systemd[1]: Reached target Reactivate sysinit units.2047machine # [ 106.176633] systemd[1]: Reached target Local File Systems.2048machine # [ 106.179537] systemd[1]: Reached target Remote File Systems.2049machine # [ 106.186185] systemd[1]: Started autorestart.service.2050machine # [ 106.189182] systemd[1]: Starting linger-users.service...2051machine # [ 106.196319] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2052machine # [ 106.197789] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2053machine # [ 106.211777] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2054machine # [ 106.217477] systemd[1]: Starting Create SUID/SGID Wrappers...2055machine # [ 106.218433] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2056machine # [ 106.219750] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672057machine # [ 106.228334] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2058machine # [ 106.233030] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2059machine # [ 106.239307] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2060machine # [ 106.269764] systemd[1]: autorestart.service: Deactivated successfully.2061machine # [ 106.308905] systemd[1]: linger-users.service: Deactivated successfully.2062machine # [ 106.311850] systemd[1]: Finished linger-users.service.2063machine # [ 106.609784] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2064machine # [ 106.613046] systemd[1]: Finished Create SUID/SGID Wrappers.2065machine # [ 106.872851] nixos[4450]: finished switching to system configuration /nix/store/3lbv0h1lp0xhm8kpzhfhsdkx77rml1bz-nixos-system-machine-test2066machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.37 seconds)2067machine # [ 106.958127] systemd[1]: Stopped autorestart.service.2068machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&12069machine # [ 107.545212] nixos[4597]: switching to system configuration /nix/store/3lbv0h1lp0xhm8kpzhfhsdkx77rml1bz-nixos-system-machine-test2070machine # [ 107.548401] systemd[1]: Stopped target Local File Systems.2071machine # [ 107.549903] systemd[1]: Stopped target Remote File Systems.2072machine # [ 108.019097] systemd[1]: Reload requested from client PID 4597 ('.switch-to-conf') (unit backdoor.service)...2073machine # [ 108.021640] systemd[1]: Reloading...2074machine # [ 108.675868] systemd[1]: Reloading finished in 651 ms.2075machine # [ 108.830897] systemd[560]: Reexecution requested from client PID 4666 ('switch-to-confi')...2076machine # [ 108.833150] systemd[560]: Reexecuting.2077machine # [ 108.907456] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2078machine # [ 109.117783] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2079machine # [ 109.125256] systemd[560]: Reload requested from client PID 4666 ('switch-to-confi')...2080machine # [ 109.127862] systemd[560]: Reloading...2081machine # [ 109.330557] systemd[560]: Reloading finished in 201 ms.2082machine # [ 109.348258] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2083machine # [ 109.358677] systemd[560]: Stopped Run user-specific NixOS activation.2084machine # [ 109.361405] systemd[560]: Stopping Run user-specific NixOS activation...2085machine # [ 109.365114] systemd[560]: Starting Run user-specific NixOS activation...2086machine # [ 109.419267] systemd[560]: Finished Run user-specific NixOS activation.2087machine # [ 109.423787] systemd[1]: Stopped target Reactivate sysinit units.2088machine # [ 109.427221] systemd[1]: Stopping Reactivate sysinit units...2089machine # [ 109.428826] systemd[1]: Reached target Reactivate sysinit units.2090machine # [ 109.429718] systemd[1]: Reached target Remote File Systems.2091machine # [ 109.433022] systemd[1]: Reached target Local File Systems.2092machine # [ 109.442017] systemd[1]: Started autorestart.service.2093machine # [ 109.445189] systemd[1]: Starting linger-users.service...2094machine # [ 109.460892] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2095machine # [ 109.466302] systemd[1]: Starting Create SUID/SGID Wrappers...2096machine # [ 109.471496] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2097machine # [ 109.474535] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672098machine # [ 109.479051] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2099machine # [ 109.485184] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2100machine # [ 109.489907] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2101machine # [ 109.491898] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2102machine # [ 109.493866] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2103machine # [ 109.522630] systemd[1]: autorestart.service: Deactivated successfully.2104machine # [ 109.563174] systemd[1]: linger-users.service: Deactivated successfully.2105machine # [ 109.565495] systemd[1]: Finished linger-users.service.2106machine # [ 109.857741] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2107machine # [ 109.861108] systemd[1]: Finished Create SUID/SGID Wrappers.2108machine # [ 110.115683] nixos[4597]: finished switching to system configuration /nix/store/3lbv0h1lp0xhm8kpzhfhsdkx77rml1bz-nixos-system-machine-test2109machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartService/bin/switch-to-configuration test 2>&1, in 3.15 seconds)2110machine # [ 110.172491] systemd[1]: Stopped autorestart.service.2111machine: must fail: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&12112machine # [ 110.848309] nixos[4744]: switching to system configuration /nix/store/hidjbzmk3vnmx44yx9z4k5g58mkh8nql-nixos-system-machine-test2113machine # [ 110.851832] systemd[1]: Stopped target Local File Systems.2114machine # [ 110.854565] systemd[1]: Stopped target Remote File Systems.2115machine # [ 111.455594] systemd[1]: Reload requested from client PID 4744 ('.switch-to-conf') (unit backdoor.service)...2116machine # [ 111.458082] systemd[1]: Reloading...2117machine # [ 112.175441] systemd[1]: Reloading finished in 715 ms.2118machine # [ 112.348309] systemd[560]: Reexecution requested from client PID 4813 ('switch-to-confi')...2119machine # [ 112.349799] systemd[560]: Reexecuting.2120machine # [ 112.423041] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2121machine # [ 112.639365] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2122machine # [ 112.647213] systemd[560]: Reload requested from client PID 4813 ('switch-to-confi')...2123machine # [ 112.650916] systemd[560]: Reloading...2124machine # [ 112.857844] systemd[560]: Reloading finished in 208 ms.2125machine # [ 112.872227] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2126machine # [ 112.883767] systemd[560]: Stopped Run user-specific NixOS activation.2127machine # [ 112.886502] systemd[560]: Stopping Run user-specific NixOS activation...2128machine # [ 112.889955] systemd[560]: Starting Run user-specific NixOS activation...2129machine # [ 112.945244] systemd[560]: Finished Run user-specific NixOS activation.2130machine # [ 112.949653] systemd[1]: Stopped target Reactivate sysinit units.2131machine # [ 112.951592] systemd[1]: Stopping Reactivate sysinit units...2132machine # [ 112.953336] systemd[1]: Reached target Reactivate sysinit units.2133machine # [ 112.958703] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2134machine # [ 112.961691] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2135machine # [ 112.965076] systemd[1]: Reached target Local File Systems.2136machine # [ 112.968726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2137machine # [ 112.973312] systemd[1]: Starting Create SUID/SGID Wrappers...2138machine # [ 112.974207] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2139machine # [ 112.975514] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672140machine # [ 112.979858] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2141machine # [ 112.985753] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2142machine # [ 112.987421] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2143machine # [ 112.988935] systemd[1]: Reached target Remote File Systems.2144machine # [ 113.372290] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2145machine # [ 113.376528] systemd[1]: Finished Create SUID/SGID Wrappers.2146machine # [ 113.383126] systemd[1]: Started autorestart.service.2147machine # [ 113.386668] systemd[1]: Starting linger-users.service...2148machine # [ 113.435110] systemd[1]: autorestart.service: Main process exited, code=exited, status=1/FAILURE2149machine # [ 113.438700] systemd[1]: autorestart.service: Failed with result 'exit-code'.2150machine # [ 113.469603] systemd[1]: linger-users.service: Deactivated successfully.2151machine # [ 113.472654] systemd[1]: Finished linger-users.service.2152machine # [ 113.781867] nixos[4744]: switching to system configuration /nix/store/hidjbzmk3vnmx44yx9z4k5g58mkh8nql-nixos-system-machine-test failed (status 4)2153machine: (finished: must fail: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/autorestartServiceFailing/bin/switch-to-configuration test 2>&1, in 3.61 seconds)2154(finished: subtest: failing units, in 16.72 seconds)2155subtest: unit file parser2156machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12157machine # [ 114.344264] nixos[4887]: switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2158machine # [ 114.347047] systemd[1]: Stopped target Remote File Systems.2159machine # [ 114.350128] systemd[1]: Stopped target Local File Systems.2160machine # [ 114.351115] systemd[1]: Stopped autorestart.service.2161machine # [ 114.835592] systemd[1]: Reload requested from client PID 4887 ('.switch-to-conf') (unit backdoor.service)...2162machine # [ 114.837689] systemd[1]: Reloading...2163machine # [ 115.499610] systemd[1]: Reloading finished in 659 ms.2164machine # [ 115.669125] systemd[560]: Reexecution requested from client PID 4955 ('switch-to-confi')...2165machine # [ 115.673942] systemd[560]: Reexecuting.2166machine # [ 115.732436] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2167machine # [ 115.937686] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2168machine # [ 115.945192] systemd[560]: Reload requested from client PID 4955 ('switch-to-confi')...2169machine # [ 115.947746] systemd[560]: Reloading...2170machine # [ 116.151355] systemd[560]: Reloading finished in 202 ms.2171machine # [ 116.165651] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2172machine # [ 116.174504] systemd[560]: Stopped Run user-specific NixOS activation.2173machine # [ 116.176994] systemd[560]: Stopping Run user-specific NixOS activation...2174machine # [ 116.180278] systemd[560]: Starting Run user-specific NixOS activation...2175machine # [ 116.233391] systemd[560]: Finished Run user-specific NixOS activation.2176machine # [ 116.238291] systemd[1]: Stopped target Reactivate sysinit units.2177machine # [ 116.240654] systemd[1]: Stopping Reactivate sysinit units...2178machine # [ 116.243086] systemd[1]: Reached target Reactivate sysinit units.2179machine # [ 116.245783] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2180machine # [ 116.247456] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2181machine # [ 116.252567] systemd[1]: Reached target Remote File Systems.2182machine # [ 116.255856] systemd[1]: Starting linger-users.service...2183machine # [ 116.258816] systemd[1]: Starting test.service...2184machine # [ 116.275571] systemd[1]: Reached target Local File Systems.2185machine # [ 116.280186] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2186machine # [ 116.281792] systemd[1]: Starting Create SUID/SGID Wrappers...2187machine # [ 116.282641] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2188machine # [ 116.283881] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672189machine # [ 116.300498] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2190machine # [ 116.303336] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2191machine # [ 116.306562] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2192machine # [ 116.337131] systemd[1]: Finished test.service.2193machine # [ 116.376532] systemd[1]: linger-users.service: Deactivated successfully.2194machine # [ 116.380244] systemd[1]: Finished linger-users.service.2195machine # [ 116.679210] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2196machine # [ 116.681748] systemd[1]: Finished Create SUID/SGID Wrappers.2197machine # [ 116.943179] nixos[4887]: finished switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2198machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.16 seconds)2199machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&12200machine # [ 117.739860] nixos[5029]: switching to system configuration /nix/store/6zpyjdwc1fwhalkwjpx7qam5d8jzvyn3-nixos-system-machine-test2201machine # [ 117.743879] systemd[1]: Stopped target Local File Systems.2202machine # [ 117.745409] systemd[1]: Stopped target Remote File Systems.2203machine # [ 118.342187] systemd[1]: Reload requested from client PID 5029 ('.switch-to-conf') (unit backdoor.service)...2204machine # [ 118.344416] systemd[1]: Reloading...2205machine # [ 119.058879] systemd[1]: Reloading finished in 711 ms.2206machine # [ 119.237971] systemd[560]: Reexecution requested from client PID 5098 ('switch-to-confi')...2207machine # [ 119.241992] systemd[560]: Reexecuting.2208machine # [ 119.290046] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2209machine # [ 119.494822] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2210machine # [ 119.502278] systemd[560]: Reload requested from client PID 5098 ('switch-to-confi')...2211machine # [ 119.504157] systemd[560]: Reloading...2212machine # [ 119.706542] systemd[560]: Reloading finished in 201 ms.2213machine # [ 119.720177] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2214machine # [ 119.730515] systemd[560]: Stopped Run user-specific NixOS activation.2215machine # [ 119.733014] systemd[560]: Stopping Run user-specific NixOS activation...2216machine # [ 119.736145] systemd[560]: Starting Run user-specific NixOS activation...2217machine # [ 119.784337] systemd[560]: Finished Run user-specific NixOS activation.2218machine # [ 119.788810] systemd[1]: Stopped target Reactivate sysinit units.2219machine # [ 119.790720] systemd[1]: Stopping Reactivate sysinit units...2220machine # [ 119.792571] systemd[1]: Reached target Reactivate sysinit units.2221machine # [ 119.795810] systemd[1]: test.service: Deactivated successfully.2222machine # [ 119.797178] systemd[1]: Stopped test.service.2223machine # [ 119.798022] systemd[1]: Stopping test.service...2224machine # [ 119.799889] systemd[1]: Starting test.service...2225machine # [ 119.836742] systemd[1]: Finished test.service.2226machine # [ 119.842621] systemd[1]: Reached target Remote File Systems.2227machine # [ 119.847814] systemd[1]: Starting linger-users.service...2228machine # [ 119.851725] systemd[1]: Reached target Local File Systems.2229machine # [ 119.860570] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2230machine # [ 119.863156] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2231machine # [ 119.869552] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2232machine # [ 119.874908] systemd[1]: Starting Create SUID/SGID Wrappers...2233machine # [ 119.878008] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2234machine # [ 119.879768] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672235machine # [ 119.882994] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2236machine # [ 119.885369] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2237machine # [ 119.886661] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2238machine # [ 119.949420] systemd[1]: linger-users.service: Deactivated successfully.2239machine # [ 119.952780] systemd[1]: Finished linger-users.service.2240machine # [ 120.246866] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2241machine # [ 120.250011] systemd[1]: Finished Create SUID/SGID Wrappers.2242machine # [ 120.507304] nixos[5029]: finished switching to system configuration /nix/store/6zpyjdwc1fwhalkwjpx7qam5d8jzvyn3-nixos-system-machine-test2243machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraSection/bin/switch-to-configuration test 2>&1, in 3.57 seconds)2244machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&12245machine # [ 121.182731] nixos[5171]: switching to system configuration /nix/store/ivmqygr8w9b4sk0kvi7adi3kanhqm2yw-nixos-system-machine-test2246machine # [ 121.185988] systemd[1]: Stopped target Remote File Systems.2247machine # [ 121.187941] systemd[1]: Stopped target Local File Systems.2248machine # [ 121.761249] systemd[1]: Reload requested from client PID 5171 ('.switch-to-conf') (unit backdoor.service)...2249machine # [ 121.763333] systemd[1]: Reloading...2250machine # [ 122.517809] systemd[1]: Reloading finished in 750 ms.2251machine # [ 122.704549] systemd[560]: Reexecution requested from client PID 5240 ('switch-to-confi')...2252machine # [ 122.706063] systemd[560]: Reexecuting.2253machine # [ 122.767343] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2254machine # [ 122.990783] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2255machine # [ 122.997386] systemd[560]: Reload requested from client PID 5240 ('switch-to-confi')...2256machine # [ 123.000157] systemd[560]: Reloading...2257machine # [ 123.212943] systemd[560]: Reloading finished in 211 ms.2258machine # [ 123.228218] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2259machine # [ 123.240115] systemd[560]: Stopped Run user-specific NixOS activation.2260machine # [ 123.241255] systemd[560]: Stopping Run user-specific NixOS activation...2261machine # [ 123.245256] systemd[560]: Starting Run user-specific NixOS activation...2262machine # [ 123.299169] systemd[560]: Finished Run user-specific NixOS activation.2263machine # [ 123.305287] systemd[1]: Stopped target Reactivate sysinit units.2264machine # [ 123.307180] systemd[1]: Stopping Reactivate sysinit units...2265machine # [ 123.309638] systemd[1]: Reached target Reactivate sysinit units.2266machine # [ 123.312384] systemd[1]: test.service: Deactivated successfully.2267machine # [ 123.313352] systemd[1]: Stopped test.service.2268machine # [ 123.314028] systemd[1]: Stopping test.service...2269machine # [ 123.318962] systemd[1]: Starting test.service...2270machine # [ 123.361122] systemd[1]: Finished test.service.2271machine # [ 123.366263] systemd[1]: Reached target Remote File Systems.2272machine # [ 123.373197] systemd[1]: Starting linger-users.service...2273machine # [ 123.377175] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2274machine # [ 123.378675] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2275machine # [ 123.388265] systemd[1]: Reached target Local File Systems.2276machine # [ 123.392621] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2277machine # [ 123.399310] systemd[1]: Starting Create SUID/SGID Wrappers...2278machine # [ 123.406654] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2279machine # [ 123.407997] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672280machine # [ 123.419351] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2281machine # [ 123.422075] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2282machine # [ 123.424699] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2283machine # [ 123.497068] systemd[1]: linger-users.service: Deactivated successfully.2284machine # [ 123.500409] systemd[1]: Finished linger-users.service.2285machine # [ 123.794213] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2286machine # [ 123.797124] systemd[1]: Finished Create SUID/SGID Wrappers.2287machine # [ 124.055714] nixos[5171]: finished switching to system configuration /nix/store/ivmqygr8w9b4sk0kvi7adi3kanhqm2yw-nixos-system-machine-test2288machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraSectionOtherName/bin/switch-to-configuration test 2>&1, in 3.55 seconds)2289machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12290machine # [ 124.663791] nixos[5313]: switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2291machine # [ 124.666740] systemd[1]: Stopped target Local File Systems.2292machine # [ 124.669108] systemd[1]: Stopped target Remote File Systems.2293machine # [ 125.143570] systemd[1]: Reload requested from client PID 5313 ('.switch-to-conf') (unit backdoor.service)...2294machine # [ 125.145970] systemd[1]: Reloading...2295machine # [ 125.813274] systemd[1]: Reloading finished in 664 ms.2296machine # [ 125.967543] systemd[560]: Reexecution requested from client PID 5382 ('switch-to-confi')...2297machine # [ 125.971604] systemd[560]: Reexecuting.2298machine # [ 126.021427] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2299machine # [ 126.216959] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2300machine # [ 126.225193] systemd[560]: Reload requested from client PID 5382 ('switch-to-confi')...2301machine # [ 126.227718] systemd[560]: Reloading...2302machine # [ 126.421417] systemd[560]: Reloading finished in 192 ms.2303machine # [ 126.436199] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2304machine # [ 126.445451] systemd[560]: Stopped Run user-specific NixOS activation.2305machine # [ 126.448127] systemd[560]: Stopping Run user-specific NixOS activation...2306machine # [ 126.451213] systemd[560]: Starting Run user-specific NixOS activation...2307machine # [ 126.498966] systemd[560]: Finished Run user-specific NixOS activation.2308machine # [ 126.503340] systemd[1]: Stopped target Reactivate sysinit units.2309machine # [ 126.505846] systemd[1]: Stopping Reactivate sysinit units...2310machine # [ 126.506729] systemd[1]: Reached target Reactivate sysinit units.2311machine # [ 126.510332] systemd[1]: test.service: Deactivated successfully.2312machine # [ 126.511255] systemd[1]: Stopped test.service.2313machine # [ 126.511898] systemd[1]: Stopping test.service...2314machine # [ 126.514522] systemd[1]: Starting test.service...2315machine # [ 126.555326] systemd[1]: Finished test.service.2316machine # [ 126.561250] systemd[1]: Reached target Remote File Systems.2317machine # [ 126.566145] systemd[1]: Reached target Local File Systems.2318machine # [ 126.569758] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2319machine # [ 126.575126] systemd[1]: Starting Create SUID/SGID Wrappers...2320machine # [ 126.576961] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2321machine # [ 126.581355] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672322machine # [ 126.584432] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2323machine # [ 126.592000] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2324machine # [ 126.593403] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2325machine # [ 126.594628] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2326machine # [ 126.595879] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2327machine # [ 126.935394] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2328machine # [ 126.940175] systemd[1]: Finished Create SUID/SGID Wrappers.2329machine # [ 126.942671] systemd[1]: Starting linger-users.service...2330machine # [ 127.007976] systemd[1]: linger-users.service: Deactivated successfully.2331machine # [ 127.010973] systemd[1]: Finished linger-users.service.2332machine # [ 127.268415] nixos[5313]: finished switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2333machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 3.21 seconds)2334machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&12335machine # [ 127.886713] nixos[5455]: switching to system configuration /nix/store/afgm2136r4p3baqy1mix3xs083fi8psf-nixos-system-machine-test2336machine # [ 127.890606] systemd[1]: Stopped target Local File Systems.2337machine # [ 127.891854] systemd[1]: Stopped target Remote File Systems.2338machine # [ 128.422084] systemd[1]: Reload requested from client PID 5455 ('.switch-to-conf') (unit backdoor.service)...2339machine # [ 128.424232] systemd[1]: Reloading...2340machine # [ 129.078165] systemd[1]: Reloading finished in 651 ms.2341machine # [ 129.236368] systemd[560]: Reexecution requested from client PID 5524 ('switch-to-confi')...2342machine # [ 129.237781] systemd[560]: Reexecuting.2343machine # [ 129.306252] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2344machine # [ 129.514110] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2345machine # [ 129.522243] systemd[560]: Reload requested from client PID 5524 ('switch-to-confi')...2346machine # [ 129.525141] systemd[560]: Reloading...2347machine # [ 129.718259] systemd[560]: Reloading finished in 192 ms.2348machine # [ 129.732176] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2349machine # [ 129.741770] systemd[560]: Stopped Run user-specific NixOS activation.2350machine # [ 129.744138] systemd[560]: Stopping Run user-specific NixOS activation...2351machine # [ 129.747277] systemd[560]: Starting Run user-specific NixOS activation...2352machine # [ 129.796041] systemd[560]: Finished Run user-specific NixOS activation.2353machine # [ 129.800422] systemd[1]: Stopped target Reactivate sysinit units.2354machine # [ 129.803036] systemd[1]: Stopping Reactivate sysinit units...2355machine # [ 129.804435] systemd[1]: Reached target Reactivate sysinit units.2356machine # [ 129.806851] systemd[1]: Reached target Local File Systems.2357machine # [ 129.810015] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2358machine # [ 129.812501] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2359machine # [ 129.815076] systemd[1]: Reached target Remote File Systems.2360machine # [ 129.820546] systemd[1]: Starting linger-users.service...2361machine # [ 129.826255] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2362machine # [ 129.832903] systemd[1]: Starting Create SUID/SGID Wrappers...2363machine # [ 129.839517] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2364machine # [ 129.843029] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672365machine # [ 129.846036] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2366machine # [ 129.853095] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2367machine # [ 129.854636] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2368machine # [ 129.931968] systemd[1]: linger-users.service: Deactivated successfully.2369machine # [ 129.935207] systemd[1]: Finished linger-users.service.2370machine # [ 130.247401] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2371machine # [ 130.250384] systemd[1]: Finished Create SUID/SGID Wrappers.2372machine # [ 130.510707] nixos[5455]: finished switching to system configuration /nix/store/afgm2136r4p3baqy1mix3xs083fi8psf-nixos-system-machine-test2373machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithInstallSection/bin/switch-to-configuration test 2>&1, in 3.24 seconds)2374machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&12375machine # [ 131.143588] nixos[5595]: switching to system configuration /nix/store/iqawc7f2ic464nsznv6052vfwmyi1m25-nixos-system-machine-test2376machine # [ 131.147357] systemd[1]: Stopped target Local File Systems.2377machine # [ 131.148924] systemd[1]: Stopped target Remote File Systems.2378machine # [ 131.667054] systemd[1]: Reload requested from client PID 5595 ('.switch-to-conf') (unit backdoor.service)...2379machine # [ 131.669684] systemd[1]: Reloading...2380machine # [ 132.345889] systemd[1]: Reloading finished in 671 ms.2381machine # [ 132.494096] systemd[560]: Reexecution requested from client PID 5664 ('switch-to-confi')...2382machine # [ 132.498565] systemd[560]: Reexecuting.2383machine # [ 132.553890] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2384machine # [ 132.749124] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2385machine # [ 132.756468] systemd[560]: Reload requested from client PID 5664 ('switch-to-confi')...2386machine # [ 132.758905] systemd[560]: Reloading...2387machine # [ 132.954847] systemd[560]: Reloading finished in 194 ms.2388machine # [ 132.968363] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2389machine # [ 132.979930] systemd[560]: Stopped Run user-specific NixOS activation.2390machine # [ 132.982758] systemd[560]: Stopping Run user-specific NixOS activation...2391machine # [ 132.985911] systemd[560]: Starting Run user-specific NixOS activation...2392machine # [ 133.036517] systemd[560]: Finished Run user-specific NixOS activation.2393machine # [ 133.041213] systemd[1]: Stopped target Reactivate sysinit units.2394machine # [ 133.043535] systemd[1]: Stopping Reactivate sysinit units...2395machine # [ 133.045454] systemd[1]: Reached target Reactivate sysinit units.2396machine # [ 133.049124] systemd[1]: test.service: Deactivated successfully.2397machine # [ 133.050157] systemd[1]: Stopped test.service.2398machine # [ 133.050886] systemd[1]: Stopping test.service...2399machine # [ 133.053740] systemd[1]: Starting test.service...2400machine # [ 133.094611] systemd[1]: Finished test.service.2401machine # [ 133.099143] systemd[1]: Reached target Local File Systems.2402machine # [ 133.103205] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2403machine # [ 133.111082] systemd[1]: Starting Create SUID/SGID Wrappers...2404machine # [ 133.114047] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2405machine # [ 133.116735] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672406machine # [ 133.119027] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2407machine # [ 133.130690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2408machine # [ 133.133528] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2409machine # [ 133.134805] systemd[1]: Reached target Remote File Systems.2410machine # [ 133.135635] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2411machine # [ 133.138416] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2412machine # [ 133.477411] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2413machine # [ 133.481727] systemd[1]: Finished Create SUID/SGID Wrappers.2414machine # [ 133.484697] systemd[1]: Starting linger-users.service...2415machine # [ 133.546139] systemd[1]: linger-users.service: Deactivated successfully.2416machine # [ 133.547889] systemd[1]: Finished linger-users.service.2417machine # [ 133.806991] nixos[5595]: finished switching to system configuration /nix/store/iqawc7f2ic464nsznv6052vfwmyi1m25-nixos-system-machine-test2418machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKey/bin/switch-to-configuration test 2>&1, in 3.30 seconds)2419machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&12420machine # [ 134.458727] nixos[5737]: switching to system configuration /nix/store/9fhawrfasw72i2y21b6483pphcn8avc7-nixos-system-machine-test2421machine # [ 134.461957] systemd[1]: Stopped target Local File Systems.2422machine # [ 134.463433] systemd[1]: Stopped target Remote File Systems.2423machine # [ 135.007529] systemd[1]: Reload requested from client PID 5737 ('.switch-to-conf') (unit backdoor.service)...2424machine # [ 135.009947] systemd[1]: Reloading...2425machine # [ 135.685774] systemd[1]: Reloading finished in 673 ms.2426machine # [ 135.836083] systemd[560]: Reexecution requested from client PID 5806 ('switch-to-confi')...2427machine # [ 135.837574] systemd[560]: Reexecuting.2428machine # [ 135.906294] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2429machine # [ 136.105751] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2430machine # [ 136.112814] systemd[560]: Reload requested from client PID 5806 ('switch-to-confi')...2431machine # [ 136.115713] systemd[560]: Reloading...2432machine # [ 136.307331] systemd[560]: Reloading finished in 190 ms.2433machine # [ 136.321959] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2434machine # [ 136.331360] systemd[560]: Stopped Run user-specific NixOS activation.2435machine # [ 136.333863] systemd[560]: Stopping Run user-specific NixOS activation...2436machine # [ 136.336754] systemd[560]: Starting Run user-specific NixOS activation...2437machine # [ 136.389215] systemd[560]: Finished Run user-specific NixOS activation.2438machine # [ 136.394258] systemd[1]: Stopped target Reactivate sysinit units.2439machine # [ 136.399761] systemd[1]: Stopping Reactivate sysinit units...2440machine # [ 136.401559] systemd[1]: Reached target Reactivate sysinit units.2441machine # [ 136.403903] systemd[1]: test.service: Deactivated successfully.2442machine # [ 136.405029] systemd[1]: Stopped test.service.2443machine # [ 136.406174] systemd[1]: Stopping test.service...2444machine # [ 136.407340] systemd[1]: Starting test.service...2445machine # [ 136.445247] systemd[1]: Finished test.service.2446machine # [ 136.448265] systemd[1]: Reached target Remote File Systems.2447machine # [ 136.450295] systemd[1]: Reached target Local File Systems.2448machine # [ 136.455266] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2449machine # [ 136.458484] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2450machine # [ 136.462659] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2451machine # [ 136.467581] systemd[1]: Starting Create SUID/SGID Wrappers...2452machine # [ 136.469807] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2453machine # [ 136.473011] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672454machine # [ 136.475157] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2455machine # [ 136.484311] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2456machine # [ 136.485653] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2457machine # [ 136.806394] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2458machine # [ 136.810671] systemd[1]: Finished Create SUID/SGID Wrappers.2459machine # [ 136.813220] systemd[1]: Starting linger-users.service...2460machine # [ 136.877122] systemd[1]: linger-users.service: Deactivated successfully.2461machine # [ 136.879270] systemd[1]: Finished linger-users.service.2462machine # [ 137.138851] nixos[5737]: finished switching to system configuration /nix/store/9fhawrfasw72i2y21b6483pphcn8avc7-nixos-system-machine-test2463machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherValue/bin/switch-to-configuration test 2>&1, in 3.33 seconds)2464machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&12465machine # [ 137.762026] nixos[5879]: switching to system configuration /nix/store/5j3sfphjy0kzlxd0lxh0rl3dh7c43a5n-nixos-system-machine-test2466machine # [ 137.764894] systemd[1]: Stopped target Remote File Systems.2467machine # [ 137.767114] systemd[1]: Stopped target Local File Systems.2468machine # [ 138.257153] systemd[1]: Reload requested from client PID 5879 ('.switch-to-conf') (unit backdoor.service)...2469machine # [ 138.259286] systemd[1]: Reloading...2470machine # [ 138.911178] systemd[1]: Reloading finished in 649 ms.2471machine # [ 139.062299] systemd[560]: Reexecution requested from client PID 5948 ('switch-to-confi')...2472machine # [ 139.063682] systemd[560]: Reexecuting.2473machine # [ 139.134654] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2474machine # [ 139.328975] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2475machine # [ 139.335744] systemd[560]: Reload requested from client PID 5948 ('switch-to-confi')...2476machine # [ 139.338062] systemd[560]: Reloading...2477machine # [ 139.529493] systemd[560]: Reloading finished in 189 ms.2478machine # [ 139.544183] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2479machine # [ 139.554263] systemd[560]: Stopped Run user-specific NixOS activation.2480machine # [ 139.556674] systemd[560]: Stopping Run user-specific NixOS activation...2481machine # [ 139.559746] systemd[560]: Starting Run user-specific NixOS activation...2482machine # [ 139.606693] systemd[560]: Finished Run user-specific NixOS activation.2483machine # [ 139.610888] systemd[1]: Stopped target Reactivate sysinit units.2484machine # [ 139.612940] systemd[1]: Stopping Reactivate sysinit units...2485machine # [ 139.614432] systemd[1]: Reached target Reactivate sysinit units.2486machine # [ 139.617890] systemd[1]: test.service: Deactivated successfully.2487machine # [ 139.619180] systemd[1]: Stopped test.service.2488machine # [ 139.620212] systemd[1]: Stopping test.service...2489machine # [ 139.622244] systemd[1]: Starting test.service...2490machine # [ 139.659942] systemd[1]: Finished test.service.2491machine # [ 139.666240] systemd[1]: Reached target Local File Systems.2492machine # [ 139.670113] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2493machine # [ 139.674398] systemd[1]: Starting Create SUID/SGID Wrappers...2494machine # [ 139.675317] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2495machine # [ 139.684688] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672496machine # [ 139.686952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2497machine # [ 139.693672] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2498machine # [ 139.695048] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2499machine # [ 139.696762] systemd[1]: Reached target Remote File Systems.2500machine # [ 139.697628] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2501machine # [ 139.698935] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2502machine # [ 140.026867] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2503machine # [ 140.031459] systemd[1]: Finished Create SUID/SGID Wrappers.2504machine # [ 140.035835] systemd[1]: Starting linger-users.service...2505machine # [ 140.099624] systemd[1]: linger-users.service: Deactivated successfully.2506machine # [ 140.102482] systemd[1]: Finished linger-users.service.2507machine # [ 140.360698] nixos[5879]: finished switching to system configuration /nix/store/5j3sfphjy0kzlxd0lxh0rl3dh7c43a5n-nixos-system-machine-test2508machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceWithExtraKeyOtherName/bin/switch-to-configuration test 2>&1, in 3.22 seconds)2509machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&12510machine # [ 140.938283] nixos[6021]: switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2511machine # [ 140.940863] systemd[1]: Stopped target Remote File Systems.2512machine # [ 140.943245] systemd[1]: Stopped target Local File Systems.2513machine # [ 141.384883] systemd[1]: Reload requested from client PID 6021 ('.switch-to-conf') (unit backdoor.service)...2514machine # [ 141.386923] systemd[1]: Reloading...2515machine # [ 141.963654] systemd[1]: Reloading finished in 574 ms.2516machine # [ 142.104451] systemd[560]: Reexecution requested from client PID 6090 ('switch-to-confi')...2517machine # [ 142.105904] systemd[560]: Reexecuting.2518machine # [ 142.170732] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2519machine # [ 142.364081] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2520machine # [ 142.371894] systemd[560]: Reload requested from client PID 6090 ('switch-to-confi')...2521machine # [ 142.374267] systemd[560]: Reloading...2522machine # [ 142.563177] systemd[560]: Reloading finished in 187 ms.2523machine # [ 142.576185] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2524machine # [ 142.586722] systemd[560]: Stopped Run user-specific NixOS activation.2525machine # [ 142.589447] systemd[560]: Stopping Run user-specific NixOS activation...2526machine # [ 142.592632] systemd[560]: Starting Run user-specific NixOS activation...2527machine # [ 142.643335] systemd[560]: Finished Run user-specific NixOS activation.2528machine # [ 142.648816] systemd[1]: Stopped target Reactivate sysinit units.2529machine # [ 142.649807] systemd[1]: Stopping Reactivate sysinit units...2530machine # [ 142.650705] systemd[1]: Reached target Reactivate sysinit units.2531machine # [ 142.653479] systemd[1]: test.service: Deactivated successfully.2532machine # [ 142.656350] systemd[1]: Stopped test.service.2533machine # [ 142.657135] systemd[1]: Stopping test.service...2534machine # [ 142.660652] systemd[1]: Starting test.service...2535machine # [ 142.697382] systemd[1]: Finished test.service.2536machine # [ 142.702546] systemd[1]: Reached target Remote File Systems.2537machine # [ 142.708417] systemd[1]: Starting linger-users.service...2538machine # [ 142.711831] systemd[1]: Reached target Local File Systems.2539machine # [ 142.724953] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2540machine # [ 142.734135] systemd[1]: Starting Create SUID/SGID Wrappers...2541machine # [ 142.735199] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2542machine # [ 142.740406] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672543machine # [ 142.744425] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2544machine # [ 142.748515] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2545machine # [ 142.751545] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2546machine # [ 142.753807] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2547machine # [ 142.755333] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2548machine # [ 142.812255] systemd[1]: linger-users.service: Deactivated successfully.2549machine # [ 142.815991] systemd[1]: Finished linger-users.service.2550machine # [ 143.087959] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2551machine # [ 143.091394] systemd[1]: Finished Create SUID/SGID Wrappers.2552machine # [ 143.349723] nixos[6021]: finished switching to system configuration /nix/store/cxhwm59m0fgjyyhyqnb02hzn66jf0prk-nixos-system-machine-test2553machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNostop/bin/switch-to-configuration test 2>&1, in 2.99 seconds)2554machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&12555machine # [ 143.959055] nixos[6163]: switching to system configuration /nix/store/sdr9pirzmqvw2armwa6ssjkbiiyl2khq-nixos-system-machine-test2556machine # [ 143.961880] systemd[1]: Stopped target Remote File Systems.2557machine # [ 143.964194] systemd[1]: Stopped target Local File Systems.2558machine # [ 144.458061] systemd[1]: Reload requested from client PID 6163 ('.switch-to-conf') (unit backdoor.service)...2559machine # [ 144.460131] systemd[1]: Reloading...2560machine # [ 145.085428] systemd[1]: Reloading finished in 622 ms.2561machine # [ 145.229478] systemd[560]: Reexecution requested from client PID 6232 ('switch-to-confi')...2562machine # [ 145.233850] systemd[560]: Reexecuting.2563machine # [ 145.293741] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2564machine # [ 145.480296] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2565machine # [ 145.487431] systemd[560]: Reload requested from client PID 6232 ('switch-to-confi')...2566machine # [ 145.491333] systemd[560]: Reloading...2567machine # [ 145.677351] systemd[560]: Reloading finished in 187 ms.2568machine # [ 145.692187] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2569machine # [ 145.702369] systemd[560]: Stopped Run user-specific NixOS activation.2570machine # [ 145.703400] systemd[560]: Stopping Run user-specific NixOS activation...2571machine # [ 145.706326] systemd[560]: Starting Run user-specific NixOS activation...2572machine # [ 145.756474] systemd[560]: Finished Run user-specific NixOS activation.2573machine # [ 145.760678] systemd[1]: Stopped target Reactivate sysinit units.2574machine # [ 145.764428] systemd[1]: Stopping Reactivate sysinit units...2575machine # [ 145.765324] systemd[1]: Reached target Reactivate sysinit units.2576machine # [ 145.768764] systemd[1]: Reloading test.service...2577machine # [ 145.802858] systemd[1]: Reloaded test.service.2578machine # [ 145.806074] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2579machine # [ 145.812272] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2580machine # [ 145.814189] systemd[1]: Reached target Local File Systems.2581machine # [ 145.816169] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2582machine # [ 145.817672] systemd[1]: Starting Create SUID/SGID Wrappers...2583machine # [ 145.818480] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2584machine # [ 145.819654] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672585machine # [ 145.835084] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2586machine # [ 145.838789] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2587machine # [ 145.840454] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2588machine # [ 145.842290] systemd[1]: Reached target Remote File Systems.2589machine # [ 146.145793] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2590machine # [ 146.150532] systemd[1]: Finished Create SUID/SGID Wrappers.2591machine # [ 146.153652] systemd[1]: Starting linger-users.service...2592machine # [ 146.216907] systemd[1]: linger-users.service: Deactivated successfully.2593machine # [ 146.217948] systemd[1]: Finished linger-users.service.2594machine # [ 146.476712] nixos[6163]: finished switching to system configuration /nix/store/sdr9pirzmqvw2armwa6ssjkbiiyl2khq-nixos-system-machine-test2595machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.13 seconds)2596machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&12597machine # [ 147.104934] nixos[6305]: switching to system configuration /nix/store/67czsjcjx7iav9b53xlxf69swkfpsyp3-nixos-system-machine-test2598machine # [ 147.108000] systemd[1]: Stopped target Local File Systems.2599machine # [ 147.109499] systemd[1]: Stopped target Remote File Systems.2600machine # [ 147.608595] systemd[1]: Reload requested from client PID 6305 ('.switch-to-conf') (unit backdoor.service)...2601machine # [ 147.610548] systemd[1]: Reloading...2602machine # [ 148.247506] systemd[1]: Reloading finished in 634 ms.2603machine # [ 148.390674] systemd[560]: Reexecution requested from client PID 6374 ('switch-to-confi')...2604machine # [ 148.395314] systemd[560]: Reexecuting.2605machine # [ 148.445689] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2606machine # [ 148.637422] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2607machine # [ 148.645003] systemd[560]: Reload requested from client PID 6374 ('switch-to-confi')...2608machine # [ 148.647466] systemd[560]: Reloading...2609machine # [ 148.839039] systemd[560]: Reloading finished in 190 ms.2610machine # [ 148.852498] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2611machine # [ 148.863243] systemd[560]: Stopped Run user-specific NixOS activation.2612machine # [ 148.865898] systemd[560]: Stopping Run user-specific NixOS activation...2613machine # [ 148.868900] systemd[560]: Starting Run user-specific NixOS activation...2614machine # [ 148.919030] systemd[560]: Finished Run user-specific NixOS activation.2615machine # [ 148.923936] systemd[1]: Stopped target Reactivate sysinit units.2616machine # [ 148.928948] systemd[1]: Stopping Reactivate sysinit units...2617machine # [ 148.930100] systemd[1]: Reached target Reactivate sysinit units.2618machine # [ 148.932908] systemd[1]: Reloading test.service...2619machine # [ 148.972743] systemd[1]: Reloaded test.service.2620machine # [ 148.975516] systemd[1]: Reached target Remote File Systems.2621machine # [ 148.982642] systemd[1]: Starting linger-users.service...2622machine # [ 148.994078] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2623machine # [ 148.995588] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2624machine # [ 148.999563] systemd[1]: Reached target Local File Systems.2625machine # [ 149.010887] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2626machine # [ 149.015087] systemd[1]: Starting Create SUID/SGID Wrappers...2627machine # [ 149.020959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2628machine # [ 149.022279] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672629machine # [ 149.026103] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2630machine # [ 149.028593] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2631machine # [ 149.029955] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2632machine # [ 149.085952] systemd[1]: linger-users.service: Deactivated successfully.2633machine # [ 149.090154] systemd[1]: Finished linger-users.service.2634machine # [ 149.363884] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2635machine # [ 149.367404] systemd[1]: Finished Create SUID/SGID Wrappers.2636machine # [ 149.624635] nixos[6305]: finished switching to system configuration /nix/store/67czsjcjx7iav9b53xlxf69swkfpsyp3-nixos-system-machine-test2637machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModified/bin/switch-to-configuration test 2>&1, in 3.15 seconds)2638machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&12639machine # [ 150.243326] nixos[6447]: switching to system configuration /nix/store/dv9q0v3k93qj62y1v4b5fi1kqjdizy9i-nixos-system-machine-test2640machine # [ 150.246278] systemd[1]: Stopped target Remote File Systems.2641machine # [ 150.248586] systemd[1]: Stopped target Local File Systems.2642machine # [ 150.748476] systemd[1]: Reload requested from client PID 6447 ('.switch-to-conf') (unit backdoor.service)...2643machine # [ 150.750596] systemd[1]: Reloading...2644machine # [ 151.385206] systemd[1]: Reloading finished in 632 ms.2645machine # [ 151.527412] systemd[560]: Reexecution requested from client PID 6516 ('switch-to-confi')...2646machine # [ 151.531681] systemd[560]: Reexecuting.2647machine # [ 151.578075] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2648machine # [ 151.768919] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2649machine # [ 151.776190] systemd[560]: Reload requested from client PID 6516 ('switch-to-confi')...2650machine # [ 151.778545] systemd[560]: Reloading...2651machine # [ 151.968733] systemd[560]: Reloading finished in 188 ms.2652machine # [ 151.981604] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2653machine # [ 151.989553] systemd[560]: Stopped Run user-specific NixOS activation.2654machine # [ 151.991920] systemd[560]: Stopping Run user-specific NixOS activation...2655machine # [ 151.994828] systemd[560]: Starting Run user-specific NixOS activation...2656machine # [ 152.040344] systemd[560]: Finished Run user-specific NixOS activation.2657machine # [ 152.044429] systemd[1]: Stopped target Reactivate sysinit units.2658machine # [ 152.045741] systemd[1]: Stopping Reactivate sysinit units...2659machine # [ 152.046566] systemd[1]: Reached target Reactivate sysinit units.2660machine # [ 152.048967] systemd[1]: test.service: Deactivated successfully.2661machine # [ 152.052100] systemd[1]: Stopped test.service.2662machine # [ 152.052932] systemd[1]: Stopping test.service...2663machine # [ 152.055139] systemd[1]: Starting test.service...2664machine # [ 152.091337] systemd[1]: Finished test.service.2665machine # [ 152.094259] systemd[1]: Reached target Remote File Systems.2666machine # [ 152.103055] systemd[1]: Starting linger-users.service...2667machine # [ 152.106293] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2668machine # [ 152.107633] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2669machine # [ 152.119206] systemd[1]: Reached target Local File Systems.2670machine # [ 152.121887] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2671machine # [ 152.125392] systemd[1]: Starting Create SUID/SGID Wrappers...2672machine # [ 152.126326] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2673machine # [ 152.127537] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672674machine # [ 152.132108] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2675machine # [ 152.138220] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2676machine # [ 152.141630] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2677machine # [ 152.196665] systemd[1]: linger-users.service: Deactivated successfully.2678machine # [ 152.199602] systemd[1]: Finished linger-users.service.2679machine # [ 152.471517] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2680machine # [ 152.474696] systemd[1]: Finished Create SUID/SGID Wrappers.2681machine # [ 152.731162] nixos[6447]: finished switching to system configuration /nix/store/dv9q0v3k93qj62y1v4b5fi1kqjdizy9i-nixos-system-machine-test2682machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedAndSomethingElse/bin/switch-to-configuration test 2>&1, in 3.11 seconds)2683machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&12684machine # [ 153.281666] nixos[6589]: switching to system configuration /nix/store/iqawc7f2ic464nsznv6052vfwmyi1m25-nixos-system-machine-test2685machine # [ 153.285327] systemd[1]: Stopped target Remote File Systems.2686machine # [ 153.286575] systemd[1]: Stopped target Local File Systems.2687machine # [ 153.714680] systemd[1]: Reload requested from client PID 6589 ('.switch-to-conf') (unit backdoor.service)...2688machine # [ 153.716909] systemd[1]: Reloading...2689machine # [ 154.289685] systemd[1]: Reloading finished in 570 ms.2690machine # [ 154.431498] systemd[560]: Reexecution requested from client PID 6658 ('switch-to-confi')...2691machine # [ 154.435176] systemd[560]: Reexecuting.2692machine # [ 154.498022] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2693machine # [ 154.685637] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2694machine # [ 154.691922] systemd[560]: Reload requested from client PID 6658 ('switch-to-confi')...2695machine # [ 154.694323] systemd[560]: Reloading...2696machine # [ 154.880750] systemd[560]: Reloading finished in 185 ms.2697machine # [ 154.896165] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2698machine # [ 154.904838] systemd[560]: Stopped Run user-specific NixOS activation.2699machine # [ 154.907104] systemd[560]: Stopping Run user-specific NixOS activation...2700machine # [ 154.909922] systemd[560]: Starting Run user-specific NixOS activation...2701machine # [ 154.957082] systemd[560]: Finished Run user-specific NixOS activation.2702machine # [ 154.961256] systemd[1]: Stopped target Reactivate sysinit units.2703machine # [ 154.963056] systemd[1]: Stopping Reactivate sysinit units...2704machine # [ 154.964786] systemd[1]: Reached target Reactivate sysinit units.2705machine # [ 154.968145] systemd[1]: Reached target Local File Systems.2706machine # [ 154.968989] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2707machine # [ 154.973870] systemd[1]: Starting Create SUID/SGID Wrappers...2708machine # [ 154.974736] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2709machine # [ 154.975917] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672710machine # [ 154.986127] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2711machine # [ 154.990624] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2712machine # [ 154.993143] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2713machine # [ 154.996327] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2714machine # [ 154.998020] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2715machine # [ 154.999867] systemd[1]: Reached target Remote File Systems.2716machine # [ 155.301430] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2717machine # [ 155.305935] systemd[1]: Finished Create SUID/SGID Wrappers.2718machine # [ 155.308612] systemd[1]: Starting linger-users.service...2719machine # [ 155.369171] systemd[1]: linger-users.service: Deactivated successfully.2720machine # [ 155.371461] systemd[1]: Finished linger-users.service.2721machine # [ 155.630854] nixos[6589]: finished switching to system configuration /nix/store/iqawc7f2ic464nsznv6052vfwmyi1m25-nixos-system-machine-test2722machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceReloadTriggerModifiedSomethingElse/bin/switch-to-configuration test 2>&1, in 2.90 seconds)2723(finished: subtest: unit file parser, in 41.85 seconds)2724subtest: restart and reload by activation script2725machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&12726machine # [ 156.187528] nixos[6729]: switching to system configuration /nix/store/nx6l9vsfd584jdskbz0fsnwf1p8m5iii-nixos-system-machine-test2727machine # [ 156.190707] systemd[1]: Stopped target Local File Systems.2728machine # [ 156.192447] systemd[1]: Stopped target Remote File Systems.2729machine # [ 156.626949] systemd[1]: Reload requested from client PID 6729 ('.switch-to-conf') (unit backdoor.service)...2730machine # [ 156.629134] systemd[1]: Reloading...2731machine # [ 157.205213] systemd[1]: Reloading finished in 573 ms.2732machine # [ 157.350512] systemd[560]: Reexecution requested from client PID 6798 ('switch-to-confi')...2733machine # [ 157.351921] systemd[560]: Reexecuting.2734machine # [ 157.422205] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2735machine # [ 157.617273] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2736machine # [ 157.624331] systemd[560]: Reload requested from client PID 6798 ('switch-to-confi')...2737machine # [ 157.626485] systemd[560]: Reloading...2738machine # [ 157.814764] systemd[560]: Reloading finished in 187 ms.2739machine # [ 157.828175] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2740machine # [ 157.836888] systemd[560]: Stopped Run user-specific NixOS activation.2741machine # [ 157.839273] systemd[560]: Stopping Run user-specific NixOS activation...2742machine # [ 157.842111] systemd[560]: Starting Run user-specific NixOS activation...2743machine # [ 157.888492] systemd[560]: Finished Run user-specific NixOS activation.2744machine # [ 157.892495] systemd[1]: Stopped target Reactivate sysinit units.2745machine # [ 157.894855] systemd[1]: Stopping Reactivate sysinit units...2746machine # [ 157.895990] systemd[1]: Reached target Reactivate sysinit units.2747machine # [ 157.900895] systemd[1]: Reached target Remote File Systems.2748machine # [ 157.906011] systemd[1]: Starting linger-users.service...2749machine # [ 157.908445] systemd[1]: Reached target Local File Systems.2750machine # [ 157.916496] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2751machine # [ 157.917852] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2752machine # [ 157.923482] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2753machine # [ 157.928453] systemd[1]: Starting Create SUID/SGID Wrappers...2754machine # [ 157.929293] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2755machine # [ 157.930490] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672756machine # [ 157.932875] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2757machine # [ 157.934719] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2758machine # [ 157.937981] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2759machine # [ 158.002135] systemd[1]: linger-users.service: Deactivated successfully.2760machine # [ 158.005506] systemd[1]: Finished linger-users.service.2761machine # [ 158.277205] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2762machine # [ 158.280281] systemd[1]: Finished Create SUID/SGID Wrappers.2763machine # [ 158.539760] nixos[6729]: finished switching to system configuration /nix/store/nx6l9vsfd584jdskbz0fsnwf1p8m5iii-nixos-system-machine-test2764machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/simpleServiceNorestart/bin/switch-to-configuration test 2>&1, in 2.91 seconds)2765machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12766machine # [ 159.183486] nixos[6869]: switching to system configuration /nix/store/sb0vw2d0cv06wcfwa0idkaxz06q2m046-nixos-system-machine-test2767machine # [ 159.187304] systemd[1]: Stopped target Local File Systems.2768machine # [ 159.188948] systemd[1]: Stopped target Remote File Systems.2769machine # [ 159.189756] systemd[1]: test.service: Deactivated successfully.2770machine # [ 159.190580] systemd[1]: Stopped test.service.2771machine # [ 159.707094] systemd[1]: Reload requested from client PID 6869 ('.switch-to-conf') (unit backdoor.service)...2772machine # [ 159.709331] systemd[1]: Reloading...2773machine # [ 160.398724] systemd[1]: Reloading finished in 687 ms.2774machine # [ 160.544440] systemd[560]: Reexecution requested from client PID 6939 ('switch-to-confi')...2775machine # [ 160.545815] systemd[560]: Reexecuting.2776machine # [ 160.601898] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2777machine # [ 160.801789] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2778machine # [ 160.808930] systemd[560]: Reload requested from client PID 6939 ('switch-to-confi')...2779machine # [ 160.811413] systemd[560]: Reloading...2780machine # [ 161.026644] systemd[560]: Reloading finished in 214 ms.2781machine # [ 161.040149] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2782machine # [ 161.050769] systemd[560]: Stopped Run user-specific NixOS activation.2783machine # [ 161.053322] systemd[560]: Stopping Run user-specific NixOS activation...2784machine # [ 161.056396] systemd[560]: Starting Run user-specific NixOS activation...2785machine # [ 161.107215] systemd[560]: Finished Run user-specific NixOS activation.2786machine # [ 161.111955] systemd[1]: Stopped target Reactivate sysinit units.2787machine # [ 161.115050] systemd[1]: Stopping Reactivate sysinit units...2788machine # [ 161.115868] systemd[1]: Reached target Reactivate sysinit units.2789machine # [ 161.123780] systemd[1]: Starting simple-service.service...2790machine # [ 161.131700] systemd[1]: Starting simple-restart-service.service...2791machine # [ 161.149634] systemd[1]: Created slice Slice /system/templated-simple-reload-service.2792machine # [ 161.155671] systemd[1]: Starting templated-simple-reload-service@instance.service...2793machine # [ 161.169244] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2794machine # [ 161.182270] systemd[1]: Created slice Slice /system/templated-simple-service.2795machine # [ 161.194077] systemd[1]: Starting templated-simple-service@instance.service...2796machine # [ 161.209964] systemd[1]: Created slice Slice /system/templated-simple-restart-service.2797machine # [ 161.215578] systemd[1]: Starting templated-simple-restart-service@instance.service...2798machine # [ 161.230096] systemd[1]: Created slice Slice /system/templated-no-restart-service.2799machine # [ 161.235083] systemd[1]: Starting templated-no-restart-service@instance.service...2800machine # [ 161.259659] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart-by-as.2801machine # [ 161.280377] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2802machine # [ 161.281757] systemd[1]: Reached target Local File Systems.2803machine # [ 161.306152] systemd[1]: Created slice Slice /system/templated-reload-triggers.2804machine # [ 161.315600] systemd[1]: Created slice Slice /system/templated-reload-triggers-and-restart.2805machine # [ 161.341479] systemd[1]: Reached target Remote File Systems.2806machine # [ 161.342670] systemd[1]: Starting linger-users.service...2807machine # [ 161.343457] systemd[1]: Starting reload-triggers-and-restart.service...2808machine # [ 161.398902] systemd[1]: Starting reload-triggers.service...2809machine # [ 161.414484] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2810machine # [ 161.415761] systemd[1]: Starting templated-reload-triggers@instance.service...2811machine # [ 161.450684] systemd[1]: Finished simple-service.service.2812machine # [ 161.456747] systemd[1]: Finished simple-restart-service.service.2813machine # [ 161.478937] systemd[1]: Starting no-restart-service.service...2814machine # [ 161.486106] systemd[1]: Finished templated-simple-reload-service@instance.service.2815machine # [ 161.496295] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2816machine # [ 161.506004] systemd[1]: Finished templated-simple-service@instance.service.2817machine # [ 161.512883] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2818machine # [ 161.516621] systemd[1]: Starting Create SUID/SGID Wrappers...2819machine # [ 161.517449] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2820machine # [ 161.518689] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672821machine # [ 161.533949] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2822machine # [ 161.540281] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2823machine # [ 161.541922] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2824machine # [ 161.544715] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2825machine # [ 161.546716] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2826machine # [ 161.550799] systemd[1]: Finished templated-no-restart-service@instance.service.2827machine # [ 161.551912] systemd[1]: Finished templated-simple-restart-service@instance.service.2828machine # [ 161.553666] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2829machine # [ 161.555220] systemd[1]: Finished reload-triggers-and-restart.service.2830machine # [ 161.556792] systemd[1]: Finished reload-triggers.service.2831machine # [ 161.558950] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.2832machine # [ 161.566148] systemd[1]: Finished templated-reload-triggers@instance.service.2833machine # [ 161.567168] systemd[1]: Finished no-restart-service.service.2834machine # [ 161.591058] systemd[1]: linger-users.service: Deactivated successfully.2835machine # [ 161.595272] systemd[1]: Finished linger-users.service.2836machine # [ 161.898466] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2837machine # [ 161.901173] systemd[1]: Finished Create SUID/SGID Wrappers.2838machine # [ 161.906056] systemd[1]: Starting simple-reload-service.service...2839machine # [ 161.941539] systemd[1]: Finished simple-reload-service.service.2840machine # [ 162.202724] nixos[6869]: finished switching to system configuration /nix/store/sb0vw2d0cv06wcfwa0idkaxz06q2m046-nixos-system-machine-test2841machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.67 seconds)2842machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&12843machine # [ 162.891598] nixos[7040]: switching to system configuration /nix/store/sb0vw2d0cv06wcfwa0idkaxz06q2m046-nixos-system-machine-test2844machine # [ 162.894956] systemd[1]: Stopped target Local File Systems.2845machine # [ 162.896390] systemd[1]: Stopped target Remote File Systems.2846machine # [ 163.367599] systemd[1]: Reload requested from client PID 7040 ('.switch-to-conf') (unit backdoor.service)...2847machine # [ 163.369803] systemd[1]: Reloading...2848machine # [ 163.963644] systemd[1]: Reloading finished in 591 ms.2849machine # [ 164.104627] systemd[560]: Reexecution requested from client PID 7111 ('switch-to-confi')...2850machine # [ 164.107995] systemd[560]: Reexecuting.2851machine # [ 164.169663] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2852machine # [ 164.352816] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2853machine # [ 164.359504] systemd[560]: Reload requested from client PID 7111 ('switch-to-confi')...2854machine # [ 164.361564] systemd[560]: Reloading...2855machine # [ 164.539456] systemd[560]: Reloading finished in 176 ms.2856machine # [ 164.552134] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2857machine # [ 164.562041] systemd[560]: Stopped Run user-specific NixOS activation.2858machine # [ 164.563004] systemd[560]: Stopping Run user-specific NixOS activation...2859machine # [ 164.565807] systemd[560]: Starting Run user-specific NixOS activation...2860machine # [ 164.611188] systemd[560]: Finished Run user-specific NixOS activation.2861machine # [ 164.614944] systemd[1]: Stopped target Reactivate sysinit units.2862machine # [ 164.617681] systemd[1]: Stopping Reactivate sysinit units...2863machine # [ 164.618537] systemd[1]: Reached target Reactivate sysinit units.2864machine # [ 164.623657] systemd[1]: Reloading reload-triggers-and-restart.service...2865machine # [ 164.628596] systemd[1]: Reloading reload-triggers.service...2866machine # [ 164.636312] systemd[1]: Reloading simple-reload-service.service...2867machine # [ 164.643323] systemd[1]: Reloading templated-reload-triggers-and-restart@instance.service...2868machine # [ 164.652910] systemd[1]: Reloading templated-reload-triggers@instance.service...2869machine # [ 164.661552] systemd[1]: Reloading templated-simple-reload-service@instance.service...2870machine # [ 164.722048] systemd[1]: Reloaded reload-triggers-and-restart.service.2871machine # [ 164.727015] systemd[1]: Reloaded simple-reload-service.service.2872machine # [ 164.729046] systemd[1]: Reloaded templated-reload-triggers-and-restart@instance.service.2873machine # [ 164.730829] systemd[1]: Reloaded reload-triggers.service.2874machine # [ 164.737941] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2875machine # [ 164.741770] systemd[1]: Reloaded templated-reload-triggers@instance.service.2876machine # [ 164.744579] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2877machine # [ 164.747628] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2878machine # [ 164.749265] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2879machine # [ 164.751438] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2880machine # [ 164.755779] systemd[1]: simple-restart-service.service: Deactivated successfully.2881machine # [ 164.760526] systemd[1]: Stopped simple-restart-service.service.2882machine # [ 164.764335] systemd[1]: Stopping simple-restart-service.service...2883machine # [ 164.765719] systemd[1]: Starting simple-restart-service.service...2884machine # [ 164.773933] systemd[1]: simple-service.service: Deactivated successfully.2885machine # [ 164.782343] systemd[1]: Stopped simple-service.service.2886machine # [ 164.783123] systemd[1]: Stopping simple-service.service...2887machine # [ 164.783879] systemd[1]: Starting simple-service.service...2888machine # [ 164.794777] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2889machine # [ 164.801256] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2890machine # [ 164.809273] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2891machine # [ 164.816908] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2892machine # [ 164.823853] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2893machine # [ 164.836513] systemd[1]: Stopped templated-simple-restart-service@instance.service.2894machine # [ 164.841681] systemd[1]: Stopping templated-simple-restart-service@instance.service...2895machine # [ 164.846232] systemd[1]: Starting templated-simple-restart-service@instance.service...2896machine # [ 164.848211] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2897machine # [ 164.851626] systemd[1]: Stopped templated-simple-service@instance.service.2898machine # [ 164.867950] systemd[1]: Stopping templated-simple-service@instance.service...2899machine # [ 164.870004] systemd[1]: Starting templated-simple-service@instance.service...2900machine # [ 164.871250] systemd[1]: Finished simple-restart-service.service.2901machine # [ 164.873926] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2902machine # [ 164.875700] systemd[1]: Finished simple-service.service.2903machine # [ 164.877117] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2904machine # [ 164.885669] systemd[1]: Finished templated-simple-service@instance.service.2905machine # [ 164.891005] systemd[1]: Finished templated-simple-restart-service@instance.service.2906machine # [ 164.895842] systemd[1]: Reached target Local File Systems.2907machine # [ 164.898521] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2908machine # [ 164.902381] systemd[1]: Starting Create SUID/SGID Wrappers...2909machine # [ 164.908290] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2910machine # [ 164.909529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672911machine # [ 164.911675] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2912machine # [ 164.918344] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2913machine # [ 164.921532] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2914machine # [ 164.924506] systemd[1]: Reached target Remote File Systems.2915machine # [ 164.926524] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2916machine # [ 164.928308] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2917machine # [ 165.222013] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2918machine # [ 165.226280] systemd[1]: Finished Create SUID/SGID Wrappers.2919machine # [ 165.228791] systemd[1]: Starting linger-users.service...2920machine # [ 165.283436] systemd[1]: linger-users.service: Deactivated successfully.2921machine # [ 165.285682] systemd[1]: Finished linger-users.service.2922machine # [ 165.543953] nixos[7040]: finished switching to system configuration /nix/store/sb0vw2d0cv06wcfwa0idkaxz06q2m046-nixos-system-machine-test2923machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration test 2>&1, in 3.34 seconds)2924machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&12925machine # [ 166.285419] nixos[7206]: switching to system configuration /nix/store/bdhmzwhf9zh1w76cmy9dqqska5r2nilp-nixos-system-machine-test2926machine # [ 166.288055] systemd[1]: Stopped target Remote File Systems.2927machine # [ 166.290270] systemd[1]: Stopped target Local File Systems.2928machine # [ 166.812955] systemd[1]: Reload requested from client PID 7206 ('.switch-to-conf') (unit backdoor.service)...2929machine # [ 166.814858] systemd[1]: Reloading...2930machine # [ 167.458872] systemd[1]: Reloading finished in 641 ms.2931machine # [ 167.600169] systemd[560]: Reexecution requested from client PID 7277 ('switch-to-confi')...2932machine # [ 167.603441] systemd[560]: Reexecuting.2933machine # [ 167.665812] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2934machine # [ 167.850367] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2935machine # [ 167.856602] systemd[560]: Reload requested from client PID 7277 ('switch-to-confi')...2936machine # [ 167.858812] systemd[560]: Reloading...2937machine # [ 168.037748] systemd[560]: Reloading finished in 177 ms.2938machine # [ 168.048135] systemd[560]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2939machine # [ 168.057802] systemd[560]: Stopped Run user-specific NixOS activation.2940machine # [ 168.058768] systemd[560]: Stopping Run user-specific NixOS activation...2941machine # [ 168.061400] systemd[560]: Starting Run user-specific NixOS activation...2942machine # [ 168.098291] systemd[560]: Finished Run user-specific NixOS activation.2943machine # [ 168.101980] systemd[1]: Stopped target Reactivate sysinit units.2944machine # [ 168.106420] systemd[1]: Stopping Reactivate sysinit units...2945machine # [ 168.107679] systemd[1]: Reached target Reactivate sysinit units.2946machine # [ 168.112522] systemd[1]: Reloading reload-triggers.service...2947machine # [ 168.114530] systemd[1]: Reloading simple-reload-service.service...2948machine # [ 168.131039] systemd[1]: Reloading templated-reload-triggers@instance.service...2949machine # [ 168.135808] systemd[1]: Reloading templated-simple-reload-service@instance.service...2950machine # [ 168.175717] systemd[1]: Reloaded reload-triggers.service.2951machine # [ 168.179843] systemd[1]: Reloaded simple-reload-service.service.2952machine # [ 168.188916] systemd[1]: Reloaded templated-reload-triggers@instance.service.2953machine # [ 168.192156] systemd[1]: Reloaded templated-simple-reload-service@instance.service.2954machine # [ 168.195579] systemd[1]: reload-triggers-and-restart-by-as.service: Deactivated successfully.2955machine # [ 168.197274] systemd[1]: Stopped reload-triggers-and-restart-by-as.service.2956machine # [ 168.198361] systemd[1]: Stopping reload-triggers-and-restart-by-as.service...2957machine # [ 168.201201] systemd[1]: Starting reload-triggers-and-restart-by-as.service...2958machine # [ 168.204547] systemd[1]: reload-triggers-and-restart.service: Deactivated successfully.2959machine # [ 168.206071] systemd[1]: Stopped reload-triggers-and-restart.service.2960machine # [ 168.210614] systemd[1]: Stopping reload-triggers-and-restart.service...2961machine # [ 168.215233] systemd[1]: Starting reload-triggers-and-restart.service...2962machine # [ 168.217546] systemd[1]: simple-restart-service.service: Deactivated successfully.2963machine # [ 168.225043] systemd[1]: Stopped simple-restart-service.service.2964machine # [ 168.226535] systemd[1]: Stopping simple-restart-service.service...2965machine # [ 168.234754] systemd[1]: Starting simple-restart-service.service...2966machine # [ 168.246138] systemd[1]: simple-service.service: Deactivated successfully.2967machine # [ 168.247258] systemd[1]: Stopped simple-service.service.2968machine # [ 168.264419] systemd[1]: Stopping simple-service.service...2969machine # [ 168.269213] systemd[1]: Starting simple-service.service...2970machine # [ 168.279320] systemd[1]: templated-reload-triggers-and-restart-by-as@instance.service: Deactivated successfully.2971machine # [ 168.293602] systemd[1]: Stopped templated-reload-triggers-and-restart-by-as@instance.service.2972machine # [ 168.305364] systemd[1]: Stopping templated-reload-triggers-and-restart-by-as@instance.service...2973machine # [ 168.308888] systemd[1]: Starting templated-reload-triggers-and-restart-by-as@instance.service...2974machine # [ 168.323135] systemd[1]: templated-reload-triggers-and-restart@instance.service: Deactivated successfully.2975machine # [ 168.340824] systemd[1]: Stopped templated-reload-triggers-and-restart@instance.service.2976machine # [ 168.349811] systemd[1]: Stopping templated-reload-triggers-and-restart@instance.service...2977machine # [ 168.353682] systemd[1]: Starting templated-reload-triggers-and-restart@instance.service...2978machine # [ 168.355620] systemd[1]: templated-simple-restart-service@instance.service: Deactivated successfully.2979machine # [ 168.357528] systemd[1]: Stopped templated-simple-restart-service@instance.service.2980machine # [ 168.358797] systemd[1]: Stopping templated-simple-restart-service@instance.service...2981machine # [ 168.360721] systemd[1]: Starting templated-simple-restart-service@instance.service...2982machine # [ 168.362311] systemd[1]: templated-simple-service@instance.service: Deactivated successfully.2983machine # [ 168.364081] systemd[1]: Stopped templated-simple-service@instance.service.2984machine # [ 168.365601] systemd[1]: Stopping templated-simple-service@instance.service...2985machine # [ 168.369292] systemd[1]: Starting templated-simple-service@instance.service...2986machine # [ 168.374249] systemd[1]: Finished reload-triggers-and-restart.service.2987machine # [ 168.375211] systemd[1]: Finished reload-triggers-and-restart-by-as.service.2988machine # [ 168.378177] systemd[1]: Finished simple-restart-service.service.2989machine # [ 168.379124] systemd[1]: Finished templated-reload-triggers-and-restart-by-as@instance.service.2990machine # [ 168.382245] systemd[1]: Finished simple-service.service.2991machine # [ 168.383002] systemd[1]: Finished templated-reload-triggers-and-restart@instance.service.2992machine # [ 168.385238] systemd[1]: Finished templated-simple-service@instance.service.2993machine # [ 168.388539] systemd[1]: Finished templated-simple-restart-service@instance.service.2994machine # [ 168.390150] systemd[1]: Reached target Remote File Systems.2995machine # [ 168.392408] systemd[1]: Reached target Local File Systems.2996machine # [ 168.395970] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2997machine # [ 168.400694] systemd[1]: Starting Create SUID/SGID Wrappers...2998machine # [ 168.401566] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2999machine # [ 168.402782] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc673000machine # [ 168.406072] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore3001machine # [ 168.416900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3002machine # [ 168.418173] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os3003machine # [ 168.419320] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os3004machine # [ 168.420740] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki3005machine # [ 168.726001] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.3006machine # [ 168.729748] systemd[1]: Finished Create SUID/SGID Wrappers.3007machine # [ 168.732375] systemd[1]: Starting linger-users.service...3008machine # [ 168.788807] systemd[1]: linger-users.service: Deactivated successfully.3009machine # [ 168.790971] systemd[1]: Finished linger-users.service.3010machine # [ 169.048838] nixos[7206]: finished switching to system configuration /nix/store/bdhmzwhf9zh1w76cmy9dqqska5r2nilp-nixos-system-machine-test3011machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script-modified/bin/switch-to-configuration test 2>&1, in 3.51 seconds)3012machine: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&13013machine: (finished: must succeed: /nix/store/mz8nm7vaaymc5cdybb1zwav6fvrbm7rw-nixos-system-machine-test/specialisation/restart-and-reload-by-activation-script/bin/switch-to-configuration dry-activate 2>&1, in 0.83 seconds)3014(finished: subtest: restart and reload by activation script, in 14.25 seconds)3015(finished: run the VM test script, in 170.88 seconds)3016test script finished in 170.92s3017cleanup3018kill QemuMachine (pid 45)3019machine # qemu-system-aarch64: terminating on signal 15 from pid 39 (/nix/store/lfh101cfjj8p8npcari9hk0lbqi33yvh-python3-3.14.7/bin/python3.14)3020(finished: cleanup, in 0.18 seconds)3021additionally exposed symbols:3022 machine, other,3023 vlan1,3024 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