vm-test-run-switch-test-user
checks.x86_64-linux.switchTest-user
· build #23
· raw
1tribuchet: building on jamie2Machine 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: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.u481O19WYs', 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: e580b36b-5d45-4852-a008-0c4c44cea3c317machine # 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 # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)27machine # 28machine # 29machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0030machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...31machine # 32machine # 33machine # 34machine # 35machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0036machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...37machine # 38machine # 39machine # Booting from ROM...40machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.49 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Wed Sep 2 12:31:51 UTC 202641machine # [ 0.000000] Command line: console=ttyS0 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/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/init regInfo=/nix/store/mah82mmkvh29r55va6b2932qq5l93d38-closure-info/registration console=ttyS0,115200n8 console=tty042machine # [ 0.000000] BIOS-provided physical RAM map:43machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable44machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved45machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved46machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable47machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved48machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved49machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved52machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved53machine # [ 0.000000] NX (Execute Disable) protection: active54machine # [ 0.000000] APIC: Static calls initialized55machine # [ 0.000000] SMBIOS 2.8 present.56machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201457machine # [ 0.000000] DMI: Memory slots populated: 1/158machine # [ 0.000000] Hypervisor detected: KVM59machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000060machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0061machine # [ 0.000001] kvm-clock: using sched offset of 522864065 cycles62machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns63machine # [ 0.000005] tsc: Detected 2400.012 MHz processor64machine # [ 0.000805] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000842] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000845] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002756] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002767] Using GB pages for direct mapping69machine # [ 0.002881] RAMDISK: [mem 0x3e42b000-0x3ffcffff]70machine # [ 0.002890] ACPI: Early table checksum verification disabled71machine # [ 0.002893] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002896] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002900] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002908] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002910] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002911] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002913] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002914] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002916] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002917] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002918] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002919] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002919] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002920] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002920] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002920] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003402] No NUMA configuration found88machine # [ 0.003404] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003407] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.005720] Zone ranges:91machine # [ 0.005720] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.005722] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.005723] Normal empty94machine # [ 0.005724] Device empty95machine # [ 0.005724] Movable zone start for each node96machine # [ 0.005725] Early memory node ranges97machine # [ 0.005725] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.005727] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.005727] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.005747] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.006022] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.024071] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.025186] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.025199] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.025229] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.025231] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.025233] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.025234] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.025235] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.025235] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.025238] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.025239] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.025243] TSC deadline timer available114machine # [ 0.025246] CPU topo: Max. logical packages: 1115machine # [ 0.025247] CPU topo: Max. logical dies: 1116machine # [ 0.025247] CPU topo: Max. dies per package: 1117machine # [ 0.025251] CPU topo: Max. threads per core: 1118machine # [ 0.025251] CPU topo: Num. cores per package: 1119machine # [ 0.025252] CPU topo: Num. threads per package: 1120machine # [ 0.025252] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.025269] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.025303] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.025304] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.025306] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.025307] Booting paravirtualized kernel on KVM126machine # [ 0.025309] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.029743] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.031981] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.032027] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.032028] Kernel command line: console=ttyS0 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/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/init regInfo=/nix/store/mah82mmkvh29r55va6b2932qq5l93d38-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.032116] Unknown kernel command line parameters "regInfo=/nix/store/mah82mmkvh29r55va6b2932qq5l93d38-closure-info/registration", will be passed to user space.132machine # [ 0.032129] random: crng init done133machine # [ 0.032129] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.032150] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.032162] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.032195] Fallback order for Node 0: 0137machine # [ 0.032198] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.032199] Policy zone: DMA32139machine # [ 0.034718] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.037036] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.039514] allocated 2097152 bytes of page_ext142machine # [ 0.048871] ftrace: allocating 48732 entries in 192 pages143machine # [ 0.048873] ftrace: allocated 192 pages with 2 groups144machine # [ 0.049694] Dynamic Preempt: lazy145machine # [ 0.049816] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.049817] rcu: RCU event tracing is enabled.147machine # [ 0.049817] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.049819] Trampoline variant of Tasks RCU enabled.149machine # [ 0.049819] Rude variant of Tasks RCU enabled.150machine # [ 0.049819] Tracing variant of Tasks RCU enabled.151machine # [ 0.049820] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.049821] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.049855] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.049856] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.049857] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.054144] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.054409] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.054417] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.054517] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.061813] Console: colour VGA+ 80x25161machine # [ 0.061817] printk: legacy console [tty0] enabled162machine # [ 0.104796] printk: legacy console [ttyS0] enabled163machine # [ 0.303863] ACPI: Core revision 20250807164machine # [ 0.305470] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.308273] APIC: Switch to symmetric I/O mode setup166machine # [ 0.310039] x2apic enabled167machine # [ 0.311292] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.314138] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.315974] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns170machine # [ 0.319087] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)171machine # [ 0.321204] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.322244] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.323086] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.324090] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.325086] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.327086] Transient Scheduler Attacks: Mitigation: Clear CPU buffers177machine # [ 0.328085] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.329086] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.331085] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.333094] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.335087] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.336086] active return thunk: srso_alias_return_thunk183machine # [ 0.338114] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.339085] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.341085] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.343085] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.344085] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.345086] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.346085] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.347086] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'191machine # [ 0.349086] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'192machine # [ 0.350086] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256193machine # [ 0.351085] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64194machine # [ 0.352085] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512195machine # [ 0.353085] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024196machine # [ 0.354086] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8197machine # [ 0.355085] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16198machine # [ 0.357085] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24199machine # [ 0.359085] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.200machine # [ 0.394364] Freeing SMP alternatives memory: 44K201machine # [ 0.395088] pid_max: default: 32768 minimum: 301202machine # [ 0.397178] LSM: initializing lsm=capability,landlock,yama,bpf,ima203machine # [ 0.398194] landlock: Up and running.204machine # [ 0.399085] Yama: becoming mindful.205machine # [ 0.400300] LSM support for eBPF active206machine # [ 0.402182] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)207machine # [ 0.403111] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)208machine # [ 0.405891] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)209machine # [ 0.406638] Performance Events: Fam17h+ core perfctr, AMD PMU driver.210machine # [ 0.407090] ... version: 2211machine # [ 0.408088] ... bit width: 48212machine # [ 0.409100] ... generic counters: 6213machine # [ 0.410087] ... generic bitmap: 000000000000003f214machine # [ 0.411087] ... fixed-purpose counters: 0215machine # [ 0.412087] ... fixed-purpose bitmap: 0000000000000000216machine # [ 0.413087] ... value mask: 0000ffffffffffff217machine # [ 0.414087] ... max period: 00007fffffffffff218machine # [ 0.415087] ... global_ctrl mask: 000000000000003f219machine # [ 0.416203] signal: max sigframe size: 3376220machine # [ 0.417183] rcu: Hierarchical SRCU implementation.221machine # [ 0.418092] rcu: Max phase no-delay instances is 400.222machine # [ 0.423746] smp: Bringing up secondary CPUs ...223machine # [ 0.424104] smp: Brought up 1 node, 1 CPU224machine # [ 0.425090] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)225machine # [ 0.426271] Memory: 941864K/1048024K available (17215K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98832K reserved, 0K cma-reserved)226machine # [ 0.427327] devtmpfs: initialized227machine # [ 0.428345] x86/mm: Memory block size: 128MB228machine # [ 0.430140] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)229machine # [ 0.431117] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).230machine # [ 0.432207] pinctrl core: initialized pinctrl subsystem231machine # [ 0.433424] PM: RTC time: 02:36:01, date: 2026-09-06232machine # [ 0.436812] NET: Registered PF_NETLINK/PF_ROUTE protocol family233machine # [ 0.438462] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations234machine # [ 0.439108] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations235machine # [ 0.440240] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations236machine # [ 0.441098] audit: initializing netlink subsys (disabled)237machine # [ 0.442394] thermal_sys: Registered thermal governor 'fair_share'238machine # [ 0.442397] thermal_sys: Registered thermal governor 'bang_bang'239machine # [ 0.443088] thermal_sys: Registered thermal governor 'step_wise'240machine # [ 0.444091] audit: type=2000 audit(1788662161.297:1): state=initialized audit_enabled=0 res=1241machine # [ 0.446090] thermal_sys: Registered thermal governor 'user_space'242machine # [ 0.446092] thermal_sys: Registered thermal governor 'power_allocator'243machine # [ 0.447110] cpuidle: using governor menu244machine # [ 0.450284] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5245machine # [ 0.451420] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]246machine # [ 0.452090] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry247machine # [ 0.453100] PCI: Using configuration type 1 for base access248machine # [ 0.454297] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.249machine # [ 0.461386] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages250machine # [ 0.462088] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page251machine # [ 0.467088] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages252machine # [ 0.468089] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page253machine # [ 0.476685] ACPI: Added _OSI(Module Device)254machine # [ 0.477088] ACPI: Added _OSI(Processor Device)255machine # [ 0.478089] ACPI: Added _OSI(Processor Aggregator Device)256machine # [ 0.487667] ACPI: 1 ACPI AML tables successfully acquired and loaded257machine # [ 0.491533] ACPI: Interpreter enabled258machine # [ 0.492107] ACPI: PM: (supports S0 S3 S4 S5)259machine # [ 0.493089] ACPI: Using IOAPIC for interrupt routing260machine # [ 0.494147] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug261machine # [ 0.497086] PCI: Using E820 reservations for host bridge windows262machine # [ 0.498248] ACPI: Enabled 2 GPEs in block 00 to 3F263machine # [ 0.508045] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])264machine # [ 0.509093] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]265machine # [ 0.510195] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]266machine # [ 0.511218] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]267machine # [ 0.512735] PCI host bridge to bus 0000:00268machine # [ 0.513130] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]269machine # [ 0.514089] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]270machine # [ 0.515089] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]271machine # [ 0.516088] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]272machine # [ 0.517089] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]273machine # [ 0.518088] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]274machine # [ 0.519090] pci_bus 0000:00: root bus resource [bus 00-ff]275machine # [ 0.520271] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint276machine # [ 0.521994] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint277machine # [ 0.525177] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]278machine # [ 0.526115] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]279machine # [ 0.527142] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]280machine # [ 0.528305] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]281machine # [ 0.530118] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint282machine # [ 0.533126] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]283machine # [ 0.534102] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]284machine # [ 0.535141] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]285machine # [ 0.536103] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]286machine # [ 0.538370] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint287machine # [ 0.540804] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]288machine # [ 0.542102] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]289machine # [ 0.543141] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]290machine # [ 0.545340] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.547889] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]292machine # [ 0.549102] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]293machine # [ 0.550141] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]294machine # [ 0.552338] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.554947] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]296machine # [ 0.556114] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]297machine # [ 0.557141] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]298machine # [ 0.559337] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint299machine # [ 0.561788] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]300machine # [ 0.562103] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]301machine # [ 0.563153] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]302machine # [ 0.565314] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint303machine # [ 0.567704] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]304machine # [ 0.568102] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]305machine # [ 0.569141] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]306machine # [ 0.571381] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint307machine # [ 0.573124] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]308machine # [ 0.574102] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]309machine # [ 0.575141] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]310machine # [ 0.576103] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]311machine # [ 0.578670] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint312machine # [ 0.581115] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]313machine # [ 0.582141] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]314machine # [ 0.584336] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint315machine # [ 0.586552] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]316machine # [ 0.587103] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]317machine # [ 0.588141] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]318machine # [ 0.590670] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint319machine # [ 0.593102] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]320machine # [ 0.594102] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]321machine # [ 0.595141] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]322machine # [ 0.597616] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.600163] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]324machine # [ 0.601464] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint325machine # [ 0.603175] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]326machine # [ 0.604453] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint327machine # [ 0.606063] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]328machine # [ 0.607496] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint329machine # [ 0.609103] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]330machine # [ 0.610545] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint331machine # [ 0.611787] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO332machine # [ 0.613558] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint333machine # [ 0.615154] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]334machine # [ 0.616182] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]335machine # [ 0.618003] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint336machine # [ 0.619989] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]337machine # [ 0.627652] ACPI: PCI: Interrupt link LNKA configured for IRQ 10338machine # [ 0.628250] ACPI: PCI: Interrupt link LNKB configured for IRQ 10339machine # [ 0.629242] ACPI: PCI: Interrupt link LNKC configured for IRQ 11340machine # [ 0.630247] ACPI: PCI: Interrupt link LNKD configured for IRQ 11341machine # [ 0.631244] ACPI: PCI: Interrupt link LNKE configured for IRQ 10342machine # [ 0.632250] ACPI: PCI: Interrupt link LNKF configured for IRQ 10343machine # [ 0.633253] ACPI: PCI: Interrupt link LNKG configured for IRQ 11344machine # [ 0.634249] ACPI: PCI: Interrupt link LNKH configured for IRQ 11345machine # [ 0.635154] ACPI: PCI: Interrupt link GSIA configured for IRQ 16346machine # [ 0.636112] ACPI: PCI: Interrupt link GSIB configured for IRQ 17347machine # [ 0.637109] ACPI: PCI: Interrupt link GSIC configured for IRQ 18348machine # [ 0.638105] ACPI: PCI: Interrupt link GSID configured for IRQ 19349machine # [ 0.639106] ACPI: PCI: Interrupt link GSIE configured for IRQ 20350machine # [ 0.640105] ACPI: PCI: Interrupt link GSIF configured for IRQ 21351machine # [ 0.641109] ACPI: PCI: Interrupt link GSIG configured for IRQ 22352machine # [ 0.642104] ACPI: PCI: Interrupt link GSIH configured for IRQ 23353machine # [ 0.644192] iommu: Default domain type: Translated354machine # [ 0.645097] iommu: DMA domain TLB invalidation policy: lazy mode355machine # [ 0.646375] ACPI: bus type USB registered356machine # [ 0.647159] usbcore: registered new interface driver usbfs357machine # [ 0.648109] usbcore: registered new interface driver hub358machine # [ 0.649121] usbcore: registered new device driver usb359machine # [ 0.651021] NetLabel: Initializing360machine # [ 0.651940] NetLabel: domain hash size = 128361machine # [ 0.653087] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO362machine # [ 0.654154] NetLabel: unlabeled traffic allowed by default363machine # [ 0.655102] PCI: Using ACPI for IRQ routing364machine # [ 0.763273] pci 0000:00:01.0: vgaarb: setting as boot VGA device365machine # [ 0.764082] pci 0000:00:01.0: vgaarb: bridge control possible366machine # [ 0.764082] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none367machine # [ 0.764091] vgaarb: loaded368machine # [ 0.765266] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0369machine # [ 0.766087] hpet0: 3 comparators, 64-bit 100.000000 MHz counter370machine # [ 0.771189] clocksource: Switched to clocksource kvm-clock371machine # [ 0.775544] VFS: Disk quotas dquot_6.6.0372machine # [ 0.776844] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)373machine # [ 0.779167] pnp: PnP ACPI init374machine # [ 0.780446] ACPI: IRQ 4 override to edge(!), high(!)375machine # [ 0.782155] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved376machine # [ 0.784808] pnp: PnP ACPI: found 6 devices377machine # [ 0.793048] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns378machine # [ 0.795694] clocksource: Switched to clocksource acpi_pm379machine # [ 0.797410] NET: Registered PF_INET protocol family380machine # [ 0.799194] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)381machine # [ 0.817870] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)382machine # [ 0.820396] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)383machine # [ 0.822775] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)384machine # [ 0.825153] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)385machine # [ 0.827374] TCP: Hash tables configured (established 8192 bind 8192)386machine # [ 0.829426] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)387machine # [ 0.831745] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)388machine # [ 0.833774] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)389machine # [ 0.835947] NET: Registered PF_UNIX/PF_LOCAL protocol family390machine # [ 0.837758] NET: Registered PF_XDP protocol family391machine # [ 0.839297] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]392machine # [ 0.841172] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]393machine # [ 0.843036] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]394machine # [ 0.845092] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]395machine # [ 0.847149] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]396machine # [ 0.849206] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]397machine # [ 0.852091] ACPI: \_SB_.GSIA: Enabled at IRQ 16398machine # [ 0.854975] ACPI: \_SB_.GSIB: Enabled at IRQ 17399machine # [ 0.857808] ACPI: \_SB_.GSIC: Enabled at IRQ 18400machine # [ 0.860507] ACPI: \_SB_.GSID: Enabled at IRQ 19401machine # [ 0.863037] PCI: CLS 0 bytes, default 64402machine # [ 0.864598] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns403machine # [ 0.867721] Trying to unpack rootfs image as initramfs...404machine # [ 0.913933] Initialise system trusted keyrings405machine # [ 0.919778] workingset: timestamp_bits=40 max_order=18 bucket_order=0406machine # [ 0.944986] Key type asymmetric registered407machine # [ 0.946341] Asymmetric key parser 'x509' registered408machine # [ 0.949780] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)409machine # [ 0.956776] io scheduler mq-deadline registered410machine # [ 0.958241] io scheduler kyber registered411machine # [ 0.962328] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled412machine # [ 0.964603] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A413machine # [ 0.973775] Linux agpgart interface v0.103414machine # [ 0.975174] ACPI: bus type drm_connector registered415machine # [ 0.981234] usbcore: registered new interface driver usbserial_generic416machine # [ 0.983222] usbserial: USB Serial support registered for generic417machine # [ 0.988723] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.418machine # [ 0.988723] Please enable it if your BIOS has the CPPC option.419machine # [ 0.992749] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled420machine # [ 1.001852] drop_monitor: Initializing network drop monitor service421machine # [ 1.003932] NET: Registered PF_INET6 protocol family422machine # [ 1.009197] Segment Routing with IPv6423machine # [ 1.013723] In-situ OAM (IOAM) with IPv6424machine # [ 1.015392] IPI shorthand broadcast: enabled425machine # [ 1.024301] sched_clock: Marking stable (766029955, 257665617)->(1235431683, -211736111)426machine # [ 1.031907] registered taskstats version 1427machine # [ 1.033500] Loading compiled-in X.509 certificates428machine # [ 1.056724] Demotion targets for Node 0: null429machine # [ 1.059762] Key type .fscrypt registered430machine # [ 1.061043] Key type fscrypt-provisioning registered431machine # [ 1.065844] ima: No TPM chip found, activating TPM-bypass!432machine # [ 1.067519] ima: Allocated hash algorithm: sha1433machine # [ 1.072746] ima: No architecture policies found434machine # [ 1.074383] PM: Magic number: 10:259:611435machine # [ 1.076557] RAS: Correctable Errors collector initialized.436machine # [ 1.088524] clk: Disabling unused clocks437machine # [ 1.093721] PM: genpd: Disabling unused power domains438machine # [ 1.226189] Freeing initrd memory: 28308K439machine # [ 1.230131] Freeing unused decrypted memory: 2028K440machine # [ 1.233629] Freeing unused kernel image (initmem) memory: 3644K441machine # [ 1.235558] Write protecting the kernel read-only data: 32768k442machine # [ 1.238329] Freeing unused kernel image (text/rodata gap) memory: 1216K443machine # [ 1.240804] Freeing unused kernel image (rodata/data gap) memory: 752K444machine # [ 1.293898] x86/mm: Checked W+X mappings: passed, no W+X pages found.445machine # [ 1.295922] Run /init as init process446machine # [ 1.307296] systemd[1]: Inserted module 'autofs4'447machine # [ 1.324964] fuse: init (API version 7.45)448machine # [ 1.331613] ACPI: \_SB_.GSIG: Enabled at IRQ 22449machine # [ 1.335415] ACPI: \_SB_.GSIH: Enabled at IRQ 23450machine # [ 1.340126] ACPI: \_SB_.GSIE: Enabled at IRQ 20451machine # [ 1.343835] ACPI: \_SB_.GSIF: Enabled at IRQ 21452machine # [ 1.379645] systemd[1]: Successfully made /usr/ read-only.453machine # [ 1.719035] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)454machine # [ 1.740892] systemd[1]: Detected virtualization kvm.455machine # [ 1.744839] systemd[1]: Detected architecture x86-64.456machine # [ 1.748770] systemd[1]: Running in initrd.457machine # [ 1.752972] systemd[1]: Initializing machine ID from random generator.458machine # [ 1.758348] systemd[1]: Hostname set to <machine>.459machine # [ 1.845588] systemd[1]: bpf-restrict-fs: LSM BPF program attached460machine # [ 1.887808] systemd[1]: Queued start job for default target Initrd Default Target.461machine # [ 1.892289] systemd[1]: Created slice Slice /system/modprobe.462machine # [ 1.894383] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.463machine # [ 1.896909] systemd[1]: Expecting device /dev/disk/by-label/nixos...464machine # [ 1.898972] systemd[1]: Reached target Path Units.465machine # [ 1.900565] systemd[1]: Reached target Slice Units.466machine # [ 1.902208] systemd[1]: Reached target Swaps.467machine # [ 1.903747] systemd[1]: Reached target Timer Units.468machine # [ 1.905480] systemd[1]: Listening on D-Bus System Message Bus Socket.469machine # [ 1.907748] systemd[1]: Listening on Journal Socket (/dev/log).470machine # [ 1.909894] systemd[1]: Listening on Journal Sockets.471machine # [ 1.911642] systemd[1]: Listening on udev Kernel Socket.472machine # [ 1.913401] systemd[1]: Reached target Socket Units.473machine # [ 1.916131] systemd[1]: Starting Create List of Static Device Nodes...474machine # [ 1.937371] systemd[1]: Starting Load Kernel Module 9pnet_virtio...475machine # [ 1.946941] systemd[1]: Starting Load Kernel Module configfs...476machine # [ 1.959469] systemd[1]: Starting Journal Service...477machine # [ 1.977796] systemd[1]: Starting Load Kernel Modules...478machine # [ 1.982849] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os479machine # [ 1.992215] netfs: FS-Cache loaded480machine # [ 1.995950] systemd[1]: Starting Coldplug All udev Devices...481machine # [ 2.014934] 9pnet: Installing 9P2000 support482machine # [ 2.021800] systemd[1]: Finished Create List of Static Device Nodes.483machine # [ 2.028589] systemd-journald[67]: Collecting audit messages is disabled.484machine # [ 2.036562] systemd[1]: modprobe@configfs.service: Deactivated successfully.485machine # [ 2.050375] systemd[1]: Finished Load Kernel Module configfs.486machine # [ 2.059409] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.487machine # [ 2.063021] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.488machine # [ 2.072388] systemd[1]: Finished Load Kernel Module 9pnet_virtio.489machine # [ 2.076810] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev490machine # [ 2.082576] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config491machine # [ 2.102938] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...492machine # [ 2.124259] systemd[1]: Finished Load Kernel Modules.493machine # [ 2.137156] systemd[1]: Starting Apply Kernel Variables...494machine # [ 2.156845] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.495machine # [ 2.170059] systemd[1]: Starting Create Static Device Nodes in /dev...496machine # [ 2.188893] systemd[1]: Finished Apply Kernel Variables.497machine # [ 2.210865] systemd[1]: Finished Create Static Device Nodes in /dev.498machine # [ 2.217087] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 2.223804] systemd[1]: Reached target Local File Systems.500machine # [ 2.233014] systemd[1]: Starting Rule-based Manager for Device Events and Files...501machine # [ 1.985099] systemd-modules-load[68]: Inserted module 'dm_mod'502machine # [ 1.988501] systemd-modules-load[68]: Inserted module 'virtio_balloon'503machine # [ 1.991502] systemd-modules-load[68]: Inserted module 'virtio_gpu'504machine # [ 2.254050] systemd[1]: Started Journal Service.505machine # [ 2.022436] systemd[1]: Starting Create System Files and Directories...506machine # [ 2.054296] systemd-udevd[74]: Using default interface naming scheme 'v261'.507machine # [ 2.057754] systemd[1]: Finished Create System Files and Directories.508machine # [ 2.092419] systemd[1]: Started Rule-based Manager for Device Events and Files.509machine # [ 2.133237] systemd[1]: Finished Coldplug All udev Devices.510machine # [ 2.137115] systemd[1]: Reached target System Initialization.511machine # [ 2.138673] systemd[1]: Reached target Basic System.512machine # [ 2.676119] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12513machine # [ 2.685337] virtio_blk virtio5: 1/0/0 default/read/poll queues514machine # [ 2.713818] serio: i8042 KBD port at 0x60,0x64 irq 1515machine # [ 2.715118] serio: i8042 AUX port at 0x60,0x64 irq 12516machine # [ 2.718586] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)517machine # [ 2.737469] ehci-pci 0000:00:1d.7: EHCI Host Controller518machine # [ 2.739000] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1519machine # [ 2.743171] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000520machine # [ 2.750019] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00521machine # [ 2.753656] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18522machine # [ 2.757531] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1523machine # [ 2.761614] usb usb1: Product: EHCI Host Controller524machine # [ 2.764164] usb usb1: Manufacturer: Linux 6.18.49 ehci_hcd525machine # [ 2.767301] usb usb1: SerialNumber: 0000:00:1d.7526machine # [ 2.769893] hub 1-0:1.0: USB hub found527machine # [ 2.772445] hub 1-0:1.0: 6 ports detected528machine # [ 2.778739] uhci_hcd 0000:00:1d.0: UHCI Host Controller529machine # [ 2.780045] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2530machine # [ 2.800734] uhci_hcd 0000:00:1d.0: detected 2 ports531machine # [ 2.810304] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200532machine # [ 2.823575] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18533machine # [ 2.841723] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1534machine # [ 2.591955] systemd[1]: Starting Virtual Console Setup...535machine # [ 2.854497] SCSI subsystem initialized536machine # [ 2.867379] usb usb2: Product: UHCI Host Controller537machine # [ 2.617193] (udev-worker)[94]: Network interface NamePolicy= disabled on kernel command line.538machine # [ 2.879518] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0539machine # [ 2.635806] systemd-vconsole-setup[98]: Configuration of first virtual console was skipped, ignoring remaining ones.540machine # [ 2.642104] systemd[1]: Finished Virtual Console Setup.541machine # [ 2.902629] usb usb2: Manufacturer: Linux 6.18.49 uhci_hcd542machine # [ 2.648133] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.543machine # [ 2.652115] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.544machine # [ 2.921715] usb usb2: SerialNumber: 0000:00:1d.0545machine # [ 2.933088] hub 2-0:1.0: USB hub found546machine # [ 2.940712] hub 2-0:1.0: 2 ports detected547machine # [ 2.952745] uhci_hcd 0000:00:1d.1: UHCI Host Controller548machine # [ 2.954046] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3549machine # [ 2.699502] systemd[1]: Found device /dev/disk/by-label/nixos.550machine # [ 2.702753] systemd[1]: Reached target Initrd Root Device.551machine # [ 2.705105] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...552machine # [ 2.971772] uhci_hcd 0000:00:1d.1: detected 2 ports553machine # [ 2.973501] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220554machine # [ 2.982849] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18555machine # [ 2.989786] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1556machine # [ 2.743152] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks557machine # [ 3.005361] usb usb3: Product: UHCI Host Controller558machine # [ 3.009883] usb 1-1: new high-speed USB device number 2 using ehci-pci559machine # [ 2.754409] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.560machine # [ 2.761125] systemd[1]: Mounting /sysroot...561machine # [ 3.020088] usb usb3: Manufacturer: Linux 6.18.49 uhci_hcd562machine # [ 3.025961] usb usb3: SerialNumber: 0000:00:1d.1563machine # [ 3.031114] hub 3-0:1.0: USB hub found564machine # [ 3.033917] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode565machine # [ 3.039225] hub 3-0:1.0: 2 ports detected566machine # [ 3.048755] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)567machine # [ 3.050839] uhci_hcd 0000:00:1d.2: UHCI Host Controller568machine # [ 3.056837] ahci 0000:00:1f.2: flags: 64bit ncq only569machine # [ 3.064128] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4570machine # [ 3.079731] uhci_hcd 0000:00:1d.2: detected 2 ports571machine # [ 3.084215] scsi host0: ahci572machine # [ 3.087603] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240573machine # [ 3.094940] scsi host1: ahci574machine # [ 3.105583] scsi host2: ahci575machine # [ 3.109250] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18576machine # [ 3.117900] scsi host3: ahci577machine # [ 3.123260] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1578machine # [ 3.127913] scsi host4: ahci579machine # [ 3.133273] scsi host5: ahci580machine # [ 3.139784] usb usb4: Product: UHCI Host Controller581machine # [ 3.144045] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1582machine # [ 3.152962] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00583machine # [ 3.154997] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10584machine # [ 3.158715] usb 1-1: Product: QEMU USB Tablet585machine # [ 3.160972] usb usb4: Manufacturer: Linux 6.18.49 uhci_hcd586machine # [ 3.162536] usb 1-1: Manufacturer: QEMU587machine # [ 3.164215] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1588machine # [ 3.166186] usb usb4: SerialNumber: 0000:00:1d.2589machine # [ 3.170336] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1590machine # [ 3.174656] hub 4-0:1.0: USB hub found591machine # [ 3.177734] hub 4-0:1.0: 2 ports detected592machine # [ 3.184260] EXT4-fs (vda): mounted filesystem e580b36b-5d45-4852-a008-0c4c44cea3c3 r/w with ordered data mode. Quota mode: none.593machine # [ 2.933051] systemd[1]: Mounted /sysroot.594machine # [ 2.935119] systemd[1]: Reached target Initrd Root File System.595machine # [ 2.940119] systemd[1]: Mounting /sysroot/nix/.ro-store...596machine # [ 2.950157] systemd[1]: Mounting /sysroot/nix/.rw-store...597machine # [ 2.957684] systemd[1]: Mounting /sysroot/run...598machine # [ 3.216828] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1599machine # [ 2.965410] systemd[1]: Mounting /sysroot/tmp/shared...600machine # [ 2.977204] systemd[1]: Mounting /sysroot/tmp/xchg...601machine # [ 3.238781] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1602machine # [ 2.985195] systemd[1]: Starting Mountpoints Configured in the Real Root...603machine # [ 3.265731] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1604machine # [ 3.281935] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1605machine # [ 3.030797] systemd-sysroot-fstab-check[133]: /sysroot should be mounted in the initrd, will request daemon-reload.606machine # [ 3.311347] hid: raw HID events driver (C) Jiri Kosina607machine # [ 3.313308] 9p: Installing v9fs 9p2000 file system support608machine # [ 3.065099] systemd[1]: Mounted /sysroot/nix/.rw-store.609machine # [ 3.066639] systemd[1]: Mounted /sysroot/run.610machine # [ 3.076208] systemd[1]: Mounted /sysroot/tmp/xchg.611machine # [ 3.078853] systemd[1]: Mounted /sysroot/tmp/shared.612machine # [ 3.081327] systemd[1]: Reload requested from client PID 133 ('systemd-sysroot') (unit initrd-parse-etc.service)...613machine # [ 3.083991] systemd[1]: Reloading...614machine # [ 3.151127] systemd[1]: Reloading finished in 65 ms.615machine # [ 3.161377] systemd-sysroot-fstab-check[133]: Requesting initrd-fs.target/start/replace...616machine # [ 3.167288] systemd-sysroot-fstab-check[133]: Requesting swap.target/start/replace...617machine # [ 3.169604] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio618machine # [ 3.173957] systemd[1]: Starting rw-sysroot-nix-store.service...619machine # [ 3.179300] systemd[1]: initrd-parse-etc.service: Deactivated successfully.620machine # [ 3.183210] systemd[1]: Finished Mountpoints Configured in the Real Root.621machine # [ 3.185265] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.622machine # [ 3.196695] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.623machine # [ 3.199419] systemd[1]: Finished rw-sysroot-nix-store.service.624machine # [ 3.610928] ata1: SATA link down (SStatus 0 SControl 300)625machine # [ 3.615055] ata6: SATA link down (SStatus 0 SControl 300)626machine # [ 3.619883] ata4: SATA link down (SStatus 0 SControl 300)627machine # [ 3.624075] ata5: SATA link down (SStatus 0 SControl 300)628machine # [ 3.628392] ata2: SATA link down (SStatus 0 SControl 300)629machine # [ 3.632647] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)630machine # [ 3.637614] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100631machine # [ 3.641396] ata3.00: applying bridge limits632machine # [ 3.645134] ata3.00: configured for UDMA/100633machine # [ 3.649877] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5634machine # [ 3.416208] systemd[1]: Mounted /sysroot/nix/.ro-store.635machine # [ 3.707367] usbcore: registered new interface driver usbhid636machine # [ 3.711255] usbhid: USB HID core driver637machine # [ 3.720297] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2638machine # [ 3.723600] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray639machine # [ 3.725306] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0640machine # [ 3.738884] cdrom: Uniform CD-ROM driver Revision: 3.20641machine # [ 3.760908] systemd[1]: Mounting /sysroot/nix/store...642machine # [ 3.813730] systemd[1]: Mounted /sysroot/nix/store.643machine # [ 3.815878] systemd[1]: Reached target Initrd File Systems.644machine # [ 3.818730] systemd[1]: Starting Find NixOS closure...645machine # [ 3.823207] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...646machine # [ 3.846179] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.647machine # [ 3.850084] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.648machine # [ 3.860425] systemd[1]: Finished Find NixOS closure.649machine # [ 3.862580] systemd[1]: Reached target Initrd Default Target.650machine # [ 3.865346] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...651machine # [ 3.881142] systemd[1]: Stopped target Initrd Default Target.652machine # [ 3.882886] systemd[1]: Stopped target Basic System.653machine # [ 3.884570] systemd[1]: Stopped target Initrd Root Device.654machine # [ 3.887196] systemd[1]: Stopped target Path Units.655machine # [ 3.888739] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.656machine # [ 3.890693] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.657machine # [ 3.892667] systemd[1]: Stopped target Slice Units.658machine # [ 3.894652] systemd[1]: Stopped target Socket Units.659machine # [ 3.896686] systemd[1]: Stopped target System Initialization.660machine # [ 3.898794] systemd[1]: Stopped target Swaps.661machine # [ 3.901232] systemd[1]: Stopped target Timer Units.662machine # [ 3.902722] systemd[1]: dbus.socket: Deactivated successfully.663machine # [ 3.904392] systemd[1]: Closed D-Bus System Message Bus Socket.664machine # [ 3.906342] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.665machine # [ 3.908914] systemd[1]: Stopped Find NixOS closure.666machine # [ 3.911231] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio667machine # [ 3.913960] systemd[1]: Starting rw-sysroot-nix-store.service...668machine # [ 3.915618] systemd[1]: systemd-sysctl.service: Deactivated successfully.669machine # [ 3.918204] systemd[1]: Stopped Apply Kernel Variables.670machine # [ 3.919765] systemd[1]: systemd-modules-load.service: Deactivated successfully.671machine # [ 3.922216] systemd[1]: Stopped Load Kernel Modules.672machine # [ 3.924256] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.673machine # [ 3.926398] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.674machine # [ 3.929221] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.675machine # [ 3.931149] systemd[1]: Stopped Create System Files and Directories.676machine # [ 3.933504] systemd[1]: Stopped target Local File Systems.677machine # [ 3.935489] systemd[1]: Stopped target Preparation for Local File Systems.678machine # [ 3.938405] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.679machine # [ 3.941361] systemd[1]: Stopped Coldplug All udev Devices.680machine # [ 3.943214] systemd[1]: Stopping Rule-based Manager for Device Events and Files...681machine # [ 3.946815] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.682machine # [ 3.948743] systemd[1]: Stopped Virtual Console Setup.683machine # [ 3.961207] systemd[1]: initrd-cleanup.service: Deactivated successfully.684machine # [ 3.966171] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.685machine # [ 3.968878] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.686machine # [ 3.972117] systemd[1]: Finished rw-sysroot-nix-store.service.687machine # [ 3.973790] systemd[1]: systemd-udevd.service: Deactivated successfully.688machine # [ 3.976373] systemd[1]: Stopped Rule-based Manager for Device Events and Files.689machine # [ 3.982993] systemd[1]: Starting Cleanup udev Database...690machine # [ 3.985282] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.691machine # [ 3.987305] systemd[1]: Stopped Create Static Device Nodes in /dev.692machine # [ 3.989231] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.693machine # [ 3.992247] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.694machine # [ 3.994169] systemd[1]: kmod-static-nodes.service: Deactivated successfully.695machine # [ 3.995975] systemd[1]: Stopped Create List of Static Device Nodes.696machine # [ 4.013925] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.697machine # [ 4.016758] systemd[1]: Finished Cleanup udev Database.698machine # [ 4.019224] systemd[1]: Reached target Switch Root.699machine # [ 4.021328] systemd[1]: Starting NixOS Activation...700machine # [ 4.197060] initrd-nixos-activation-start[198]: booting system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test701machine # [ 4.266160] initrd-nixos-activation-start[198]: running activation script...702machine # [ 4.689731] initrd-nixos-activation-start[221]: setting up /etc...703machine # [ 4.989355] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.704machine # [ 4.992164] systemd[1]: Finished NixOS Activation.705machine # [ 4.995251] systemd[1]: Starting Switch Root...706machine # [ 5.010319] systemd[1]: Switching root.707machine # [ 5.399131] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).708machine # [ 5.536044] NET: Registered PF_VSOCK protocol family709machine # [ 5.922769] systemd[1]: systemd 262-9457f81 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)710machine # [ 5.929446] systemd[1]: Detected virtualization kvm.711machine # [ 5.930784] systemd[1]: Detected architecture x86-64.712machine # [ 5.932113] systemd[1]: Detected first boot.713machine # [ 5.936782] systemd[1]: Initializing machine ID from random generator.714machine # [ 6.046950] systemd[1]: bpf-restrict-fs: LSM BPF program attached715machine # [ 6.182416] systemd[1]: Applying preset policy.716machine # [ 6.730105] systemd[1]: Populated /etc with preset unit settings.717machine # [ 7.312909] systemd[1]: initrd-switch-root.service: Deactivated successfully.718machine # [ 7.315486] systemd[1]: Stopped initrd-switch-root.service.719machine # [ 7.319138] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.720machine # [ 7.322596] systemd[1]: Created slice Slice /system/getty.721machine # [ 7.324787] systemd[1]: Created slice User and Session Slice.722machine # [ 7.326556] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.723machine # [ 7.328641] systemd[1]: Started Forward Password Requests to Wall Directory Watch.724machine # [ 7.330542] systemd[1]: Expecting device /dev/hvc0...725machine # [ 7.344222] systemd[1]: Expecting device /dev/ttyS0...726machine # [ 7.345589] systemd[1]: Reached target Local Encrypted Volumes.727machine # [ 7.347109] systemd[1]: Stopped target initrd-fs.target.728machine # [ 7.348464] systemd[1]: Stopped target initrd-root-fs.target.729machine # [ 7.349959] systemd[1]: Stopped target initrd-switch-root.target.730machine # [ 7.351510] systemd[1]: Reached target Virtual Machines and Containers.731machine # [ 7.353208] systemd[1]: Reached target Path Units.732machine # [ 7.354492] systemd[1]: Reached target Remote File Systems.733machine # [ 7.356007] systemd[1]: Reached target Slice Units.734machine # [ 7.357329] systemd[1]: Reached target Swaps.735machine # [ 7.361891] systemd[1]: Listening on Query the User Interactively for a Password.736machine # [ 7.367398] systemd[1]: Listening on Process Core Dump Socket.737machine # [ 7.371890] systemd[1]: Listening on Credential Encryption/Decryption.738machine # [ 7.375948] systemd[1]: Listening on Factory Reset Management.739machine # [ 7.377574] systemd[1]: Listening on Hostname Service Socket.740machine # [ 7.383238] systemd[1]: Starting Journal Log Access Socket...741machine # [ 7.385600] systemd[1]: Listening on Journal Audit Socket.742machine # [ 7.389619] systemd[1]: Listening on Console Output Muting Service Socket.743machine # [ 7.391830] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.744machine # [ 7.394510] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os745machine # [ 7.396818] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki746machine # [ 7.409440] systemd[1]: Listening on Disk Repartitioning Service Socket.747machine # [ 7.411334] systemd[1]: Listening on udev Varlink Socket.748machine # [ 7.416366] systemd[1]: Mounting Huge Pages File System...749machine # [ 7.420589] systemd[1]: Mounting POSIX Message Queue File System...750machine # [ 7.428348] systemd[1]: Mounting Kernel Debug File System...751machine # [ 7.435950] systemd[1]: Mounting Kernel Trace File System...752machine # [ 7.443364] systemd[1]: Starting Create List of Static Device Nodes...753machine # [ 7.446712] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio754machine # [ 7.461620] systemd[1]: Starting Load Kernel Module configfs...755machine # [ 7.463586] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm756machine # [ 7.466534] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore757machine # [ 7.473759] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse758machine # [ 7.484968] systemd[1]: Mounting FUSE Control File System...759machine # [ 7.489129] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67760machine # [ 7.509417] systemd[1]: Starting Journal Service...761machine # [ 7.537212] systemd[1]: Starting Load Kernel Modules...762machine # [ 7.559105] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...763machine # [ 7.572299] systemd[1]: Starting Remount Root and Kernel File Systems...764machine # [ 7.580200] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os765machine # [ 7.605914] systemd[1]: Starting Coldplug All udev Devices...766machine # [ 7.607758] systemd-journald[292]: Collecting audit messages is enabled.767machine # [ 7.637198] systemd[1]: Listening on Journal Log Access Socket.768machine # [ 7.641975] loop: module loaded769machine # [ 7.646516] systemd[1]: Mounted Huge Pages File System.770machine # [ 7.654072] systemd[1]: Mounted POSIX Message Queue File System.771machine # [ 7.404769] systemd[1]: Queued start job for default target Multi-User System.[ 7.664414] systemd[1]: Started Journal Service.772machine # 773machine # [ 7.410326] systemd[1]: systemd-journald.service: Deactivated successfully.774machine # [ 7.669647] EXT4-fs (vda): re-mounted e580b36b-5d45-4852-a008-0c4c44cea3c3.775machine # [ 7.417549] systemd-modules-load[293]: Inserted module 'loop'776machine # [ 7.422717] systemd[1]: Mounted Kernel Debug File System.777machine # [ 7.429634] systemd[1]: Mounted Kernel Trace File System.778machine # [ 7.433701] systemd[1]: Finished Create List of Static Device Nodes.779machine # [ 7.436969] systemd[1]: modprobe@configfs.service: Deactivated successfully.780machine # [ 7.442172] systemd[1]: Finished Load Kernel Module configfs.781machine # [ 7.446314] systemd[1]: Mounted FUSE Control File System.782machine # [ 7.451261] systemd[1]: Finished Load Kernel Modules.783machine # [ 7.456138] systemd[1]: Finished Remount Root and Kernel File Systems.784machine # [ 7.474893] systemd[1]: Listening on Disk Image Download Service Socket.785machine # [ 7.482108] systemd[1]: Mounting Kernel Configuration File System...786machine # [ 7.487651] systemd-oomd[295]: No swap; memory pressure usage will be degraded787machine # [ 7.494432] systemd[1]: Starting Firewall...788machine # [ 7.509162] systemd[1]: Starting Flush Journal to Persistent Storage...789machine # [ 7.511215] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore790machine # [ 7.522164] systemd[1]: Starting Load/Save OS Random Seed...791machine # [ 7.535526] systemd[1]: Starting Apply Kernel Variables...792machine # [ 7.551252] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...793machine # [ 7.553343] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os794machine # [ 7.557484] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.795machine # [ 7.850200] systemd-journald[292]: Received client request to flush runtime journal.796machine # [ 7.863724] systemd[1]: Mounted Kernel Configuration File System.797machine # [ 7.866821] systemd[1]: Finished Load/Save OS Random Seed.798machine # [ 7.872613] systemd[1]: Reached target First Boot Complete.799machine # [ 7.875475] systemd[1]: Finished Apply Kernel Variables.800machine # [ 7.877988] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.801machine # [ 7.881339] systemd[1]: Starting Create Static Device Nodes in /dev...802machine # [ 7.885288] systemd[1]: Finished Create Static Device Nodes in /dev.803machine # [ 7.887049] systemd[1]: Finished Flush Journal to Persistent Storage.804machine # [ 7.895285] systemd[1]: Reached target Preparation for Local File Systems.805machine # [ 7.899203] systemd[1]: Starting Rule-based Manager for Device Events and Files...806machine # [ 7.993497] systemd[1]: Finished Coldplug All udev Devices.807machine # [ 8.000575] systemd-udevd[335]: Using default interface naming scheme 'v261'.808machine # [ 8.060373] systemd[1]: Mounting /run/wrappers...809machine # [ 8.107917] systemd[1]: Mounted /run/wrappers.810machine # [ 8.110147] systemd[1]: Reached target Local File Systems.811machine # [ 8.118220] systemd[1]: Listening on Boot Loader Control Service Socket.812machine # [ 8.123200] systemd[1]: Starting register-nix-paths.service...813machine # [ 8.128989] systemd[1]: Starting Create SUID/SGID Wrappers...814machine # [ 8.132303] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.815machine # [ 8.141270] systemd[1]: Starting Save Transient machine-id to Disk...816machine # [ 8.151494] systemd[1]: Starting Create System Files and Directories...817machine # [ 8.184126] systemd[1]: Started Rule-based Manager for Device Events and Files.818machine # [ 8.227826] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.819machine # [ 8.235910] systemd[1]: Finished Save Transient machine-id to Disk.820machine # [ 8.316875] systemd[1]: Finished Create System Files and Directories.821machine # [ 8.332315] systemd[1]: Starting Rebuild Journal Catalog...822machine # [ 8.338663] systemd[1]: Starting Record System Boot/Shutdown in UTMP...823machine # [ 8.434978] systemd[1]: Finished Record System Boot/Shutdown in UTMP.824machine # [ 8.484967] systemd[1]: Finished Rebuild Journal Catalog.825machine # [ 8.495766] systemd[1]: Starting Update is Completed...826machine # [ 8.562489] systemd[1]: Finished Update is Completed.827machine # [ 8.741223] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse828machine # [ 8.793912] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.829machine # [ 8.801178] systemd[1]: Finished Create SUID/SGID Wrappers.830machine # [ 8.934511] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.831machine # [ 8.982718] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.832machine # [ 9.066320] (udev-worker)[418]: Network interface NamePolicy= disabled on kernel command line.833machine # [ 9.069935] (udev-worker)[424]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.834machine # [ 9.074783] (udev-worker)[424]: Network interface NamePolicy= disabled on kernel command line.835machine # [ 9.196141] systemd[1]: Finished register-nix-paths.service.836machine # [ 9.200128] systemd[1]: Finished Firewall.837machine # [ 9.203419] systemd[1]: Reached target System Initialization.838machine # [ 9.205619] systemd[1]: Started Discard unused filesystem blocks once a week.839machine # [ 9.208166] systemd[1]: Started Daily Cleanup of Temporary Directories.840machine # [ 9.211365] systemd[1]: Reached target Timer Units.841machine # [ 9.212786] systemd[1]: Listening on D-Bus System Message Bus Socket.842machine # [ 9.214503] systemd[1]: Listening on Nix Daemon Socket.843machine # [ 9.218425] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.844machine # [ 9.220696] systemd[1]: Reached target Socket Units.845machine # [ 9.223253] systemd[1]: Reached target Basic System.846machine # [ 9.228159] systemd[1]: Started backdoor.service.847machine # [ 9.233353] systemd[1]: Starting Import lastlog data into lastlog2 database...848machine # [ 9.247137] systemd[1]: Starting Name Service Cache Daemon (nsncd)...849machine # [ 9.258911] systemd[1]: Starting Post-Boot Actions...850machine # [ 9.271137] systemd[1]: Started Reset console on configuration changes.851machine # [ 9.293988] systemd[1]: Starting resolvconf update...852machine # [ 9.308665] systemd[1]: Condition check resulted in Virtio network device being skipped.853machine # [ 9.343306] systemd[1]: Starting D-Bus System Message Bus...854machine # connecting to host...855machine # [ 9.380375] systemd[1]: Finished Post-Boot Actions.856machine # [ 9.400566] nsncd[506]: Sep 06 02:36:10.509 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"857machine # [ 9.405878] systemd[1]: Started Name Service Cache Daemon (nsncd).858machine: Guest shell says: b'Spawning backdoor root shell...\n'859machine: connected to guest root shell860machine: (connecting took 10.43 seconds)861machine: (finished: waiting for the VM to finish booting, in 10.63 seconds)862machine # [ 9.438943] systemd[1]: Reached target Host and Network Name Lookups.863machine # [ 9.440752] systemd[1]: Reached target User and Group Name Lookups.864machine # [ 9.461405] systemd[1]: Starting User Login Management...865machine # [ 9.475243] systemd[1]: Finished Import lastlog data into lastlog2 database.866machine # [ 9.564693] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...867machine # [ 9.631686] systemd-logind[533]: New seat seat0.868machine # [ 9.640616] systemd[1]: Started User Login Management.869machine # [ 9.650800] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'870machine # [ 9.654332] dbus-broker-launch[513]: Invalid user-name in /nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"871machine # [ 9.658625] systemd[1]: Stopped target Host and Network Name Lookups.872machine # [ 9.661319] systemd[1]: Stopping Host and Network Name Lookups...873machine # [ 9.663538] systemd[1]: Stopped target User and Group Name Lookups.874machine # [ 9.666161] systemd[1]: Stopping User and Group Name Lookups...875machine # [ 9.671772] systemd[1]: Starting linger-users.service...876machine # [ 9.674519] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...877machine # [ 9.686908] systemd[1]: nscd.service: Deactivated successfully.878machine # [ 9.691698] systemd[1]: Stopped Name Service Cache Daemon (nsncd).879machine # [ 9.716287] systemd[1]: Started D-Bus System Message Bus.880machine # [ 9.733333] systemd[1]: Starting Name Service Cache Daemon (nsncd)...881machine # [ 9.771332] dbus-broker-launch[513]: Ready882machine # [ 10.045590] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3883machine # [ 10.065364] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console884machine # [ 10.069543] mousedev: PS/2 mouse device common for all mice885machine # [ 9.825054] systemd[1]: Started Name Service Cache Daemon (nsncd).886machine # [ 9.828152] nsncd[589]: Sep 06 02:36:10.940 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"887machine # [ 9.857985] systemd[1]: Created slice Slice /user/1001.888machine # [ 9.862932] systemd[1]: Reached target Host and Network Name Lookups.889machine # [ 9.882961] systemd[1]: Reached target User and Group Name Lookups.890machine # [ 9.895558] systemd[1]: Starting User Runtime Directory /run/user/1001...891machine # [ 9.899929] systemd[1]: linger-users.service: Deactivated successfully.892machine # [ 9.915721] systemd[1]: Finished linger-users.service.893machine # [ 9.937527] systemd[1]: Finished resolvconf update.894machine # [ 9.940361] systemd[1]: Reached target Preparation for Network.895machine # [ 9.952833] systemd[1]: Starting DHCP Client...896machine # [ 9.960811] systemd[1]: Starting Address configuration of eth1...897machine # [ 9.976215] systemd[1]: Starting Extra networking commands....898machine # [ 10.044271] systemd[1]: Finished User Runtime Directory /run/user/1001.899machine # [ 10.139846] ACPI: button: Power Button [PWRF]900machine # [ 10.053471] systemd[1]: Starting User Manager for UID 1001...901machine # [ 10.232194] rtc_cmos 00:04: RTC can wake from S4902machine # [ 10.256433] rtc_cmos 00:04: registered as rtc0903machine # [ 10.256582] rtc_cmos 00:04: setting system clock to 2026-09-06T02:36:11 UTC (1788662171)904machine # [ 10.259756] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs905machine # [ 10.279025] parport_pc 00:02: reported by Plug and Play ACPI906machine # [ 10.279164] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]907machine # [ 10.290802] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4908machine # [ 10.182296] (systemd)[621]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)909machine # [ 10.305850] Console: switching to colour dummy device 80x25910machine # [ 10.371574] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized911machine # [ 10.220904] systemd-logind[533]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.912machine # [ 10.462377] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt913machine # [ 10.462522] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD914machine # [ 10.521466] [drm] Found bochs VGA, ID 0xb0c5.915machine # [ 10.274758] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy916machine # [ 10.544508] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.917machine # [ 10.549489] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6918machine # [ 10.554382] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5919machine # [ 10.571203] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic920machine # [ 10.597025] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0921machine # [ 10.507052] network-addresses-eth1-start[618]: adding address 192.168.1.1/24... done922machine # [ 10.545778] systemd[1]: Starting Virtual Console Setup...923machine # [ 10.552341] systemd-logind[533]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)924machine # [ 10.564359] network-addresses-eth1-start[618]: adding address 2001:db8:1::1/64... done925machine # [ 10.575335] systemd[621]: Queued start job for default target Main User Target.926machine # [ 10.590867] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy927machine # [ 10.828507] Console: switching to colour frame buffer device 160x50928machine # [ 10.638808] systemd[621]: Created slice User Application Slice.929machine # [ 10.918138] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device930machine # [ 10.667925] systemd[621]: Started Daily Cleanup of User's Temporary Directories.931machine # [ 10.693375] systemd[621]: Reached target Paths.932machine # [ 10.732964] systemd[621]: Reached target Timers.933machine # [ 10.736145] systemd[621]: Starting D-Bus User Message Bus Socket...934machine # [ 10.747656] systemd[621]: Starting Create User Files and Directories...935machine # [ 10.751404] systemd-logind[533]: Watching system buttons on /dev/input/event2 (Power Button)936machine # [ 10.769572] systemd[621]: Finished Create User Files and Directories.937machine # [ 11.036890] ppdev: user-space parallel port driver938machine # [ 10.784841] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.939machine # [ 10.787677] systemd[1]: Stopped Virtual Console Setup.940machine # [ 10.810671] systemd[1]: Starting Virtual Console Setup...941machine # [ 10.813219] systemd[621]: Listening on D-Bus User Message Bus Socket.942machine # [ 10.820146] systemd[621]: Reached target Sockets.943machine # [ 10.826439] systemd[621]: Reached target Basic System.944machine # [ 10.842132] systemd[1]: Started User Manager for UID 1001.945machine # [ 10.843916] systemd[621]: Starting Run user-specific NixOS activation...946machine # [ 10.845849] systemd[1]: Finished Address configuration of eth1.947machine # [ 11.105719] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)948machine # [ 10.858681] systemd[621]: Finished Run user-specific NixOS activation.949machine # [ 10.860465] systemd[621]: Reached target Main User Target.950machine # [ 10.867303] systemd[621]: Startup finished in 551ms.951machine # [ 10.880696] systemd-logind[533]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)952machine # [ 11.147486] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)953machine # [ 10.941226] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.954machine # [ 10.944238] systemd[1]: Stopped Virtual Console Setup.955machine # [ 10.951414] systemd[1]: Starting Virtual Console Setup...956machine # [ 11.048673] dhcpcd[637]: dhcpcd-10.3.2 starting957machine # [ 11.063776] systemd[1]: Finished Extra networking commands..958machine # [ 11.065590] systemd[1]: Reached target Network.959machine # [ 11.068985] systemd[1]: Starting Permit User Sessions...960machine # [ 11.074486] dhcpcd[716]: dev: loaded udev961machine # [ 11.368096] 8021q: 802.1Q VLAN Support v1.8962machine # [ 11.369488] 8021q: adding VLAN 0 to HW filter on device eth1963machine # [ 11.391013] kvm_amd: TSC scaling supported964machine # [ 11.393109] kvm_amd: Nested Virtualization enabled965machine # [ 11.397624] kvm_amd: Nested Paging enabled966machine # [ 11.401872] kvm_amd: LBR virtualization supported967machine # [ 11.406114] kvm_amd: Virtual VMLOAD VMSAVE supported968machine # [ 11.414817] kvm_amd: Virtual GIF supported969machine # [ 11.420016] kvm_amd: Virtual NMI enabled970machine # [ 11.168319] systemd[1]: Finished Permit User Sessions.971machine # [ 11.180686] systemd[1]: Started Getty on tty1.972machine # [ 11.182839] systemd[1]: Reached target Login Prompts.973machine # [ 11.494194] cfg80211: Loading compiled-in X.509 certificates for regulatory database974machine # [ 11.507915] EDAC MC: Ver: 3.0.0975machine # [ 11.533708] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'976machine # [ 11.535154] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'977machine # [ 11.540530] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2978machine # [ 11.542138] cfg80211: failed to load regulatory.db979machine # [ 11.297857] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.980machine # [ 11.626611] 8021q: adding VLAN 0 to HW filter on device eth0981machine # [ 11.370848] dhcpcd[716]: eth0: waiting for carrier982machine # [ 11.372604] dhcpcd[716]: eth0: carrier acquired983machine # [ 11.384429] dhcpcd[716]: DUID 00:01:00:01:32:2f:8e:1c:52:54:00:12:34:56984machine # [ 11.386253] dhcpcd[716]: eth0: IAID 00:12:34:56985machine # [ 11.387901] dhcpcd[716]: eth0: adding address fe80::5054:ff:fe12:3456986machine # [ 11.684284] systemd-vconsole-setup[699]: Configuration of first virtual console was skipped, ignoring remaining ones.987machine # [ 11.691861] systemd[1]: Finished Virtual Console Setup.988machine # [ 12.951861] dhcpcd[716]: eth0: soliciting a DHCP lease989machine # [ 13.247346] NET: Registered PF_PACKET protocol family990machine # [ 12.999232] dhcpcd[716]: eth0: offered 10.0.2.15 from 10.0.2.2991machine # [ 13.004585] dhcpcd[716]: eth0: probing address 10.0.2.15/24992machine # [ 14.043598] dhcpcd[716]: eth0: soliciting an IPv6 router993machine # [ 14.047679] dhcpcd[716]: eth0: Router Advertisement from fe80::2994machine # [ 14.051825] dhcpcd[716]: eth0: adding address fec0::5054:ff:fe12:3456/64995machine # [ 14.056182] dhcpcd[716]: eth0: adding route to fec0::/64996machine # [ 14.059997] dhcpcd[716]: eth0: adding default route via fe80::2997machine # [ 18.174636] dhcpcd[716]: eth0: leased 10.0.2.15 for 86400 seconds998machine # [ 18.179780] dhcpcd[716]: eth0: adding route to 10.0.2.0/24999machine # [ 18.184682] dhcpcd[716]: eth0: adding default route via 10.0.2.21000machine # [ 18.340634] systemd[1]: Started DHCP Client.1001machine # [ 18.342980] systemd[1]: Reached target Multi-User System.1002machine # [ 18.345439] systemd[1]: Startup finished in 1.042s (kernel) + 4.171s (initrd) + 13.131s (userspace) = 18.344s.1003machine: (finished: waiting for unit multi-user.target, in 20.03 seconds)1004machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test1005machine # Checking switch inhibitors... done1006machine # [ 19.451538] nixos[872]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1007machine # [ 19.455905] systemd[1]: Stopped target Remote File Systems.1008machine # activating the configuration...1009machine # [ 19.459263] systemd[1]: Stopped target Local File Systems.1010machine # setting up /etc...1011machine # [ 19.922955] systemd[1]: Reload requested from client PID 872 ('.switch-to-conf') (unit backdoor.service)...1012machine # [ 19.925908] systemd[1]: Reloading...1013machine # [ 20.571146] systemd[1]: Reloading finished in 641 ms.1014machine # reloading user units for usertest...1015machine # [ 20.613834] systemd[621]: Created slice User Core Session Slice.1016machine # [ 20.618503] systemd[621]: Starting D-Bus User Message Bus...1017machine # [ 20.684656] dbus-broker-launch[942]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/services/org.freedesktop.import1.service'1018machine # [ 20.689574] dbus-broker-launch[942]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1019machine # [ 20.694478] dbus-broker-launch[942]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1020machine # [ 20.699641] dbus-broker-launch[942]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1021machine # [ 20.705099] systemd[621]: Started D-Bus User Message Bus.1022machine # [ 20.707123] dbus-broker-launch[942]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1023machine # [ 20.709939] dbus-broker-launch[942]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1024machine # [ 20.721517] dbus-broker-launch[942]: Ready1025machine # [ 20.847429] systemd[621]: Reexecution requested from client PID 941 ('switch-to-confi')...1026machine # [ 20.850271] systemd[621]: Reexecuting.1027machine # [ 20.876985] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1028machine # [ 21.044637] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1029machine # [ 21.053076] systemd[621]: Reload requested from client PID 941 ('switch-to-confi')...1030machine # [ 21.055971] systemd[621]: Reloading...1031machine # [ 21.206191] systemd[621]: Reloading finished in 147 ms.1032machine # [ 21.210969] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1033machine # restarting the following user units: nixos-activation.service1034machine # [ 21.222522] systemd[621]: Stopped Run user-specific NixOS activation.1035machine # [ 21.225198] systemd[621]: Stopping Run user-specific NixOS activation...1036machine # [ 21.229437] systemd[621]: Starting Run user-specific NixOS activation...1037machine # [ 21.268439] systemd[621]: Finished Run user-specific NixOS activation.1038machine # restarting sysinit-reactivation.target1039machine # [ 21.285349] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1040machine # [ 21.423236] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1041machine # [ 21.427267] systemd[1]: Reached target Reactivate sysinit units.1042machine # [ 21.431948] systemd[1]: Reached target Local File Systems.1043machine # [ 21.435287] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1044machine # [ 21.438208] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1045machine # [ 21.442127] systemd[1]: Reached target Remote File Systems.1046machine # [ 21.453434] systemd[1]: Starting linger-users.service...1047machine # [ 21.459883] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1048machine # [ 21.466421] systemd[1]: Starting Create SUID/SGID Wrappers...1049machine # [ 21.469573] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1050machine # [ 21.472752] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671051machine # [ 21.480156] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1052machine # [ 21.482950] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1053machine # [ 21.485290] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 21.549785] systemd[1]: linger-users.service: Deactivated successfully.1055machine # [ 21.552886] systemd[1]: Finished linger-users.service.1056machine # [ 21.849851] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1057machine # [ 21.852684] systemd[1]: Finished Create SUID/SGID Wrappers.1058machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1059machine # [ 22.119534] nixos[872]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1060machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test, in 3.29 seconds)1061machine: must succeed: rm /etc/NIXOS1062machine: (finished: must succeed: rm /etc/NIXOS, in 0.04 seconds)1063machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/ar0p913h22ms3z31yf3h5w722ca0fcqx-nixos-system-other-test/bin/switch-to-configuration test1064machine # this should succeed (config: /nix/store/ar0p913h22ms3z31yf3h5w722ca0fcqx-nixos-system-other-test, action: test)1065machine # Checking switch inhibitors... done1066machine # [ 22.764286] nixos[1020]: switching to system configuration /nix/store/ar0p913h22ms3z31yf3h5w722ca0fcqx-nixos-system-other-test1067machine # [ 22.768791] systemd[1]: Stopped target Remote File Systems.1068machine # [ 22.771732] systemd[1]: Stopped target Local File Systems.1069machine # activating the configuration...1070machine # removing user ‘usertest’1071machine # setting up /etc...1072machine # [ 23.308976] systemd[1]: Reload requested from client PID 1020 ('.switch-to-conf') (unit backdoor.service)...1073machine # [ 23.311847] systemd[1]: Reloading...1074machine # [ 24.004245] systemd[1]: Reloading finished in 689 ms.1075machine # reloading user units for usertest...1076machine # [ 24.192822] systemd[621]: Reexecution requested from client PID 1091 ('switch-to-confi')...1077machine # [ 24.197147] systemd[621]: Reexecuting.1078machine # [ 24.225251] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1079machine # [ 24.417953] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1080machine # [ 24.426825] systemd[621]: Reload requested from client PID 1091 ('switch-to-confi')...1081machine # [ 24.429403] systemd[621]: Reloading...1082machine # [ 24.595978] systemd[621]: Reloading finished in 163 ms.1083machine # [ 24.601991] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1084machine # restarting the following user units: nixos-activation.service1085machine # [ 24.614113] systemd[621]: Stopped Run user-specific NixOS activation.1086machine # [ 24.617222] systemd[621]: Stopping Run user-specific NixOS activation...1087machine # [ 24.620811] systemd[621]: Starting Run user-specific NixOS activation...1088machine # [ 24.665115] systemd[621]: Finished Run user-specific NixOS activation.1089machine # restarting sysinit-reactivation.target1090machine # [ 24.671056] systemd[1]: Stopped target Reactivate sysinit units.1091machine # reloading the following units: [ 24.674554] systemd[1]: Stopping Reactivate sysinit units...1092machine # dbus-broker.service1093machine # [ 24.676932] systemd[1]: Reached target Reactivate sysinit units.1094machine # [ 24.679933] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1095machine # [ 24.682926] systemd[1]: Reloading D-Bus System Message Bus...1096machine # [ 24.707110] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1097machine # [ 24.710406] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1098machine # [ 24.712379] dbus-broker-launch[513]: Invalid user-name in /nix/store/yl6shlfxw95siygj3vrskihkygz4y1d6-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1099machine # [ 24.741124] systemd[1]: Reloaded D-Bus System Message Bus.1100machine # restarting the following units: network-addresses-eth1.service1101machine # [ 24.749759] systemd[1]: Stopping Address configuration of eth1...1102machine # [ 24.789305] network-addresses-eth1-pre-stop[1104]: deleting address 192.168.1.1/24... done1103machine # [ 24.800066] network-addresses-eth1-pre-stop[1104]: deleting address 2001:db8:1::1/64... done1104machine # [ 24.809683] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1105machine # [ 24.812786] systemd[1]: Stopped Address configuration of eth1.1106machine # [ 24.817884] systemd[1]: Starting Address configuration of eth1...1107machine # [ 24.875271] network-addresses-eth1-start[1109]: adding address 192.168.1.2/24... done1108machine # [ 24.886810] network-addresses-eth1-start[1109]: adding address 2001:db8:1::2/64... done1109machine # [ 24.900835] systemd[1]: Finished Address configuration of eth1.1110machine # [ 24.905570] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1111machine # [ 24.908691] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1112machine # [ 24.916095] systemd[1]: Reached target Remote File Systems.1113machine # [ 24.920690] systemd[1]: Starting linger-users.service...1114machine # [ 24.929381] systemd[1]: Reached target Local File Systems.1115machine # [ 24.930944] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1116machine # [ 24.937867] systemd[1]: Starting Create SUID/SGID Wrappers...1117machine # [ 24.939530] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1118machine # [ 24.942713] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671119machine # [ 24.947907] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1120machine # [ 24.952737] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1121machine # [ 24.956433] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1122machine # [ 24.997489] linger-users-start[1123]: id: ‘usertest’: no such user1123machine # [ 25.000815] linger-users-start[1120]: Removing linger for missing user usertest1124machine # [ 25.009703] systemd[1]: linger-users.service: Deactivated successfully.1125machine # [ 25.012728] systemd[1]: Finished linger-users.service.1126machine # [ 25.287145] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1127machine # [ 25.290181] systemd[1]: Finished Create SUID/SGID Wrappers.1128machine # [ 25.552919] nixos[1020]: finished switching to system configuration /nix/store/ar0p913h22ms3z31yf3h5w722ca0fcqx-nixos-system-other-test1129machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/ar0p913h22ms3z31yf3h5w722ca0fcqx-nixos-system-other-test/bin/switch-to-configuration test, in 3.39 seconds)1130machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&11131machine # [ 26.135530] nixos[1187]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1132machine # [ 26.139591] systemd[1]: Stopped target Local File Systems.1133machine # [ 26.142503] systemd[1]: Stopped target Remote File Systems.1134machine # [ 26.603799] systemd[1]: Reload requested from client PID 1187 ('.switch-to-conf') (unit backdoor.service)...1135machine # [ 26.606697] systemd[1]: Reloading...1136machine # [ 27.214924] systemd[1]: Reloading finished in 605 ms.1137machine # [ 27.382583] systemd[621]: Reexecution requested from client PID 1256 ('switch-to-confi')...1138machine # [ 27.386604] systemd[621]: Reexecuting.1139machine # [ 27.413583] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1140machine # [ 27.570916] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1141machine # [ 27.578465] systemd[621]: Reload requested from client PID 1256 ('switch-to-confi')...1142machine # [ 27.581568] systemd[621]: Reloading...1143machine # [ 27.748810] systemd[621]: Reloading finished in 165 ms.1144machine # [ 27.755980] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1145machine # [ 27.768269] systemd[621]: Stopped Run user-specific NixOS activation.1146machine # [ 27.770148] systemd[621]: Stopping Run user-specific NixOS activation...1147machine # [ 27.774503] systemd[621]: Starting Run user-specific NixOS activation...1148machine # [ 27.818457] systemd[621]: Finished Run user-specific NixOS activation.1149machine # [ 27.823421] systemd[1]: Stopped target Reactivate sysinit units.1150machine # [ 27.825914] systemd[1]: Stopping Reactivate sysinit units...1151machine # [ 27.827511] systemd[1]: Reached target Reactivate sysinit units.1152machine # [ 27.831305] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1153machine # [ 27.834255] systemd[1]: Reloading D-Bus System Message Bus...1154machine # [ 27.850994] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1155machine # [ 27.854330] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1156machine # [ 27.856931] dbus-broker-launch[513]: Invalid user-name in /nix/store/wvv8w41xz1ya3m77c3n7zq9mi8fszl1v-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1157machine # [ 27.884705] systemd[1]: Reloaded D-Bus System Message Bus.1158machine # [ 27.891932] systemd[1]: Stopping Address configuration of eth1...1159machine # [ 27.941290] network-addresses-eth1-pre-stop[1269]: deleting address 192.168.1.2/24... done1160machine # [ 27.954115] network-addresses-eth1-pre-stop[1269]: deleting address 2001:db8:1::2/64... done1161machine # [ 27.964240] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1162machine # [ 27.967122] systemd[1]: Stopped Address configuration of eth1.1163machine # [ 27.972947] systemd[1]: Starting Address configuration of eth1...1164machine # [ 28.040787] network-addresses-eth1-start[1274]: adding address 192.168.1.1/24... done1165machine # [ 28.054103] network-addresses-eth1-start[1274]: adding address 2001:db8:1::1/64... done1166machine # [ 28.070642] systemd[1]: Finished Address configuration of eth1.1167machine # [ 28.080944] systemd[1]: Reached target Local File Systems.1168machine # [ 28.084162] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1169machine # [ 28.087861] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1170machine # [ 28.091488] systemd[1]: Reached target Remote File Systems.1171machine # [ 28.096953] systemd[1]: Starting linger-users.service...1172machine # [ 28.101898] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1173machine # [ 28.108484] systemd[1]: Starting Create SUID/SGID Wrappers...1174machine # [ 28.110307] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1175machine # [ 28.114185] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671176machine # [ 28.117926] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1177machine # [ 28.122502] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1178machine # [ 28.126670] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1179machine # [ 28.173299] systemd[1]: linger-users.service: Deactivated successfully.1180machine # [ 28.176375] systemd[1]: Finished linger-users.service.1181machine # [ 28.483460] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1182machine # [ 28.487420] systemd[1]: Finished Create SUID/SGID Wrappers.1183machine # [ 28.743116] nixos[1187]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1184machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.18 seconds)1185subtest: user services1186machine: waiting for unit user@1001.service1187machine: (finished: waiting for unit user@1001.service, in 0.17 seconds)1188machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11189machine # [ 29.518657] nixos[1347]: switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1190machine # [ 29.522637] systemd[1]: Stopped target Remote File Systems.1191machine # [ 29.525454] systemd[1]: Stopped target Local File Systems.1192machine # [ 30.029313] systemd[1]: Reload requested from client PID 1347 ('.switch-to-conf') (unit backdoor.service)...1193machine # [ 30.032264] systemd[1]: Reloading...1194machine # [ 30.649225] systemd[1]: Reloading finished in 613 ms.1195machine # [ 30.838292] systemd[621]: Reexecution requested from client PID 1416 ('switch-to-confi')...1196machine # [ 30.842193] systemd[621]: Reexecuting.1197machine # [ 30.872613] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1198machine # [ 31.029994] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1199machine # [ 31.039680] systemd[621]: Reload requested from client PID 1416 ('switch-to-confi')...1200machine # [ 31.042497] systemd[621]: Reloading...1201machine # [ 31.191200] systemd[621]: Reloading finished in 146 ms.1202machine # [ 31.197969] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1203machine # [ 31.207671] systemd[621]: Starting usertest.service...1204machine # [ 31.237758] systemd[621]: Finished usertest.service.1205machine # [ 31.241474] systemd[621]: Stopped Run user-specific NixOS activation.1206machine # [ 31.244191] systemd[621]: Stopping Run user-specific NixOS activation...1207machine # [ 31.247638] systemd[621]: Starting Run user-specific NixOS activation...1208machine # [ 31.284670] systemd[621]: Finished Run user-specific NixOS activation.1209machine # [ 31.289788] systemd[1]: Stopped target Reactivate sysinit units.1210machine # [ 31.292631] systemd[1]: Stopping Reactivate sysinit units...1211machine # [ 31.294297] systemd[1]: Reached target Reactivate sysinit units.1212machine # [ 31.297608] systemd[1]: Reached target Remote File Systems.1213machine # [ 31.306079] systemd[1]: Reached target Local File Systems.1214machine # [ 31.309476] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1215machine # [ 31.312722] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1216machine # [ 31.315948] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1217machine # [ 31.321284] systemd[1]: Starting Create SUID/SGID Wrappers...1218machine # [ 31.322925] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1219machine # [ 31.326921] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671220machine # [ 31.332463] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1221machine # [ 31.335316] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1222machine # [ 31.338168] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1223machine # [ 31.683318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1224machine # [ 31.686344] systemd[1]: Finished Create SUID/SGID Wrappers.1225machine # [ 31.692459] systemd[1]: Starting linger-users.service...1226machine # [ 31.750208] systemd[1]: linger-users.service: Deactivated successfully.1227machine # [ 31.754202] systemd[1]: Finished linger-users.service.1228machine # [ 32.014362] nixos[1347]: finished switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1229machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.11 seconds)1230machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1231machine # [ 32.123638] sudo[1487]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1232machine # [ 32.140846] sudo[1487]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1233machine # [ 32.154762] sudo[1487]: pam_unix(sudo:session): session closed for user usertest1234machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1235machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11236machine # [ 32.844542] nixos[1496]: switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1237machine # [ 32.848749] systemd[1]: Stopped target Remote File Systems.1238machine # [ 32.851439] systemd[1]: Stopped target Local File Systems.1239machine # [ 33.323352] systemd[1]: Reload requested from client PID 1496 ('.switch-to-conf') (unit backdoor.service)...1240machine # [ 33.326082] systemd[1]: Reloading...1241machine # [ 33.948933] systemd[1]: Reloading finished in 619 ms.1242machine # [ 34.141056] systemd[621]: Reexecution requested from client PID 1565 ('switch-to-confi')...1243machine # [ 34.145227] systemd[621]: Reexecuting.1244machine # [ 34.176361] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1245machine # [ 34.338590] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1246machine # [ 34.346743] systemd[621]: Reload requested from client PID 1565 ('switch-to-confi')...1247machine # [ 34.349185] systemd[621]: Reloading...1248machine # [ 34.504145] systemd[621]: Reloading finished in 153 ms.1249machine # [ 34.509975] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1250machine # [ 34.520480] systemd[621]: Stopped Run user-specific NixOS activation.1251machine # [ 34.523203] systemd[621]: Stopping Run user-specific NixOS activation...1252machine # [ 34.527430] systemd[621]: Starting Run user-specific NixOS activation...1253machine # [ 34.567816] systemd[621]: Finished Run user-specific NixOS activation.1254machine # [ 34.572472] systemd[1]: Stopped target Reactivate sysinit units.1255machine # [ 34.574581] systemd[1]: Stopping Reactivate sysinit units...1256machine # [ 34.576566] systemd[1]: Reached target Reactivate sysinit units.1257machine # [ 34.579143] systemd[1]: Reached target Local File Systems.1258machine # [ 34.582718] systemd[1]: Reached target Remote File Systems.1259machine # [ 34.587972] systemd[1]: Starting linger-users.service...1260machine # [ 34.606122] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1261machine # [ 34.609537] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1262machine # [ 34.614201] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1263machine # [ 34.620542] systemd[1]: Starting Create SUID/SGID Wrappers...1264machine # [ 34.623325] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1265machine # [ 34.625569] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671266machine # [ 34.629347] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1267machine # [ 34.632735] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1268machine # [ 34.635488] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 34.689724] systemd[1]: linger-users.service: Deactivated successfully.1270machine # [ 34.692347] systemd[1]: Finished linger-users.service.1271machine # [ 34.953502] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1272machine # [ 34.956363] systemd[1]: Finished Create SUID/SGID Wrappers.1273machine # [ 35.217537] nixos[1496]: finished switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1274machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.07 seconds)1275machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11276machine # [ 35.868422] nixos[1637]: switching to system configuration /nix/store/9bmiphy24jvlz0a2g94lv2hf02j0sm6v-nixos-system-machine-test1277machine # [ 35.872427] systemd[1]: Stopped target Local File Systems.1278machine # [ 35.875327] systemd[1]: Stopped target Remote File Systems.1279machine # [ 36.407904] systemd[1]: Reload requested from client PID 1637 ('.switch-to-conf') (unit backdoor.service)...1280machine # [ 36.410798] systemd[1]: Reloading...1281machine # [ 37.051134] systemd[1]: Reloading finished in 637 ms.1282machine # [ 37.270373] systemd[621]: Stopped usertest.service.1283machine # [ 37.274287] systemd[621]: Reexecution requested from client PID 1706 ('switch-to-confi')...1284machine # [ 37.276578] systemd[621]: Reexecuting.1285machine # [ 37.305428] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1286machine # [ 37.499216] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1287machine # [ 37.507598] systemd[621]: Reload requested from client PID 1706 ('switch-to-confi')...1288machine # [ 37.510294] systemd[621]: Reloading...1289machine # [ 37.696427] systemd[621]: Reloading finished in 183 ms.1290machine # [ 37.702055] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1291machine # [ 37.711190] systemd[621]: Starting usertest.service...1292machine # [ 37.749137] systemd[621]: Finished usertest.service.1293machine # [ 37.752837] systemd[621]: Stopped Run user-specific NixOS activation.1294machine # [ 37.754659] systemd[621]: Stopping Run user-specific NixOS activation...1295machine # [ 37.759664] systemd[621]: Starting Run user-specific NixOS activation...1296machine # [ 37.803384] systemd[621]: Finished Run user-specific NixOS activation.1297machine # [ 37.808394] systemd[1]: Stopped target Reactivate sysinit units.1298machine # [ 37.811764] systemd[1]: Stopping Reactivate sysinit units...1299machine # [ 37.813330] systemd[1]: Reached target Reactivate sysinit units.1300machine # [ 37.817680] systemd[1]: Reached target Local File Systems.1301machine # [ 37.820459] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1302machine # [ 37.827054] systemd[1]: Starting Create SUID/SGID Wrappers...1303machine # [ 37.828584] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1304machine # [ 37.832473] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671305machine # [ 37.837577] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1306machine # [ 37.841751] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1307machine # [ 37.845494] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1308machine # [ 37.849487] systemd[1]: Reached target Remote File Systems.1309machine # [ 37.861501] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1310machine # [ 37.864941] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1311machine # [ 38.207087] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1312machine # [ 38.210315] systemd[1]: Finished Create SUID/SGID Wrappers.1313machine # [ 38.216534] systemd[1]: Starting linger-users.service...1314machine # [ 38.274103] systemd[1]: linger-users.service: Deactivated successfully.1315machine # [ 38.277098] systemd[1]: Finished linger-users.service.1316machine # [ 38.536138] nixos[1637]: finished switching to system configuration /nix/store/9bmiphy24jvlz0a2g94lv2hf02j0sm6v-nixos-system-machine-test1317machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 3.32 seconds)1318machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1319machine # [ 38.634730] sudo[1777]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1320machine # [ 38.654095] sudo[1777]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1321machine # [ 38.667866] sudo[1777]: pam_unix(sudo:session): session closed for user usertest1322machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1323machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11324machine # [ 39.333075] nixos[1786]: switching to system configuration /nix/store/y72dzqy73wi1sz4d5rv3a49fya0awc91-nixos-system-machine-test1325machine # [ 39.337267] systemd[1]: Stopped target Remote File Systems.1326machine # [ 39.340333] systemd[1]: Stopped target Local File Systems.1327machine # [ 39.866418] systemd[1]: Reload requested from client PID 1786 ('.switch-to-conf') (unit backdoor.service)...1328machine # [ 39.869406] systemd[1]: Reloading...1329machine # [ 40.477525] systemd[1]: Reloading finished in 605 ms.1330machine # [ 40.681377] systemd[621]: Reexecution requested from client PID 1855 ('switch-to-confi')...1331machine # [ 40.684747] systemd[621]: Reexecuting.1332machine # [ 40.716247] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1333machine # [ 40.893188] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1334machine # [ 40.902348] systemd[621]: Reload requested from client PID 1855 ('switch-to-confi')...1335machine # [ 40.904655] systemd[621]: Reloading...1336machine # [ 41.093206] systemd[621]: Reloading finished in 185 ms.1337machine # [ 41.098992] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1338machine # [ 41.105196] systemd[621]: Stopped usertest.service.1339machine # [ 41.106703] systemd[621]: Stopping usertest.service...1340machine # [ 41.111261] systemd[621]: Starting usertest.service...1341machine # [ 41.146300] systemd[621]: Finished usertest.service.1342machine # [ 41.153981] systemd[621]: Stopped Run user-specific NixOS activation.1343machine # [ 41.155887] systemd[621]: Stopping Run user-specific NixOS activation...1344machine # [ 41.160845] systemd[621]: Starting Run user-specific NixOS activation...1345machine # [ 41.205144] systemd[621]: Finished Run user-specific NixOS activation.1346machine # [ 41.209864] systemd[1]: Stopped target Reactivate sysinit units.1347machine # [ 41.212525] systemd[1]: Stopping Reactivate sysinit units...1348machine # [ 41.214220] systemd[1]: Reached target Reactivate sysinit units.1349machine # [ 41.217097] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1350machine # [ 41.220968] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1351machine # [ 41.224682] systemd[1]: Reached target Remote File Systems.1352machine # [ 41.232939] systemd[1]: Reached target Local File Systems.1353machine # [ 41.236314] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1354machine # [ 41.242278] systemd[1]: Starting Create SUID/SGID Wrappers...1355machine # [ 41.243996] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1356machine # [ 41.249367] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671357machine # [ 41.255422] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1358machine # [ 41.258287] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1359machine # [ 41.260579] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1360machine # [ 41.618656] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1361machine # [ 41.621593] systemd[1]: Finished Create SUID/SGID Wrappers.1362machine # [ 41.628199] systemd[1]: Starting linger-users.service...1363machine # [ 41.689967] systemd[1]: linger-users.service: Deactivated successfully.1364machine # [ 41.692668] systemd[1]: Finished linger-users.service.1365machine # [ 41.953843] nixos[1786]: finished switching to system configuration /nix/store/y72dzqy73wi1sz4d5rv3a49fya0awc91-nixos-system-machine-test1366machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 3.29 seconds)1367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1368machine # [ 42.046419] sudo[1926]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1369machine # [ 42.065480] sudo[1926]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1370machine # [ 42.080079] sudo[1926]: pam_unix(sudo:session): session closed for user usertest1371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.12 seconds)1372machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11373machine # [ 42.769072] nixos[1935]: switching to system configuration /nix/store/ib9j29pldkybxzw2f3ni2his5ipp41v5-nixos-system-machine-test1374machine # [ 42.773128] systemd[1]: Stopped target Local File Systems.1375machine # [ 42.775901] systemd[1]: Stopped target Remote File Systems.1376machine # [ 43.328698] systemd[1]: Reload requested from client PID 1935 ('.switch-to-conf') (unit backdoor.service)...1377machine # [ 43.331708] systemd[1]: Reloading...1378machine # [ 43.954076] systemd[1]: Reloading finished in 618 ms.1379machine # [ 44.131920] systemd[621]: Reexecution requested from client PID 2004 ('switch-to-confi')...1380machine # [ 44.135888] systemd[621]: Reexecuting.1381machine # [ 44.168585] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1382machine # [ 44.349309] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1383machine # [ 44.358045] systemd[621]: Reload requested from client PID 2004 ('switch-to-confi')...1384machine # [ 44.360934] systemd[621]: Reloading...1385machine # [ 44.534705] systemd[621]: Reloading finished in 171 ms.1386machine # [ 44.540986] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1387machine # [ 44.549956] systemd[621]: Reloading usertest.service...1388machine # [ 44.580846] systemd[621]: Reloaded usertest.service.1389machine # [ 44.588109] systemd[621]: Stopped Run user-specific NixOS activation.1390machine # [ 44.590840] systemd[621]: Stopping Run user-specific NixOS activation...1391machine # [ 44.594726] systemd[621]: Starting Run user-specific NixOS activation...1392machine # [ 44.634546] systemd[621]: Finished Run user-specific NixOS activation.1393machine # [ 44.639485] systemd[1]: Stopped target Reactivate sysinit units.1394machine # [ 44.641635] systemd[1]: Stopping Reactivate sysinit units...1395machine # [ 44.643502] systemd[1]: Reached target Reactivate sysinit units.1396machine # [ 44.651115] systemd[1]: Reached target Local File Systems.1397machine # [ 44.652593] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1398machine # [ 44.658292] systemd[1]: Starting Create SUID/SGID Wrappers...1399machine # [ 44.659972] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1400machine # [ 44.664261] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671401machine # [ 44.671505] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1402machine # [ 44.675218] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1403machine # [ 44.677648] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1404machine # [ 44.683187] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1405machine # [ 44.685465] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1406machine # [ 44.687714] systemd[1]: Reached target Remote File Systems.1407machine # [ 45.009577] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1408machine # [ 45.012527] systemd[1]: Finished Create SUID/SGID Wrappers.1409machine # [ 45.018480] systemd[1]: Starting linger-users.service...1410machine # [ 45.070993] systemd[1]: linger-users.service: Deactivated successfully.1411machine # [ 45.072986] systemd[1]: Finished linger-users.service.1412machine # [ 45.333616] nixos[1935]: finished switching to system configuration /nix/store/ib9j29pldkybxzw2f3ni2his5ipp41v5-nixos-system-machine-test1413machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 3.26 seconds)1414machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1415machine # [ 45.414793] sudo[2075]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1416machine # [ 45.432438] sudo[2075]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1417machine # [ 45.445747] sudo[2075]: pam_unix(sudo:session): session closed for user usertest1418machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1419machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11420machine # [ 46.163107] nixos[2084]: switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1421machine # [ 46.168837] systemd[1]: Stopped target Remote File Systems.1422machine # [ 46.170606] systemd[1]: Stopped target Local File Systems.1423machine # [ 46.663322] systemd[1]: Reload requested from client PID 2084 ('.switch-to-conf') (unit backdoor.service)...1424machine # [ 46.666150] systemd[1]: Reloading...1425machine # [ 47.287349] systemd[1]: Reloading finished in 618 ms.1426machine # [ 47.456930] systemd[621]: Stopped usertest.service.1427machine # [ 47.459272] systemd[621]: Reexecution requested from client PID 2153 ('switch-to-confi')...1428machine # [ 47.463093] systemd[621]: Reexecuting.1429machine # [ 47.493274] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1430machine # [ 47.674551] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1431machine # [ 47.684272] systemd[621]: Reload requested from client PID 2153 ('switch-to-confi')...1432machine # [ 47.687055] systemd[621]: Reloading...1433machine # [ 47.872283] systemd[621]: Reloading finished in 183 ms.1434machine # [ 47.878043] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1435machine # [ 47.887373] systemd[621]: Starting usertest.service...1436machine # [ 47.924279] systemd[621]: Finished usertest.service.1437machine # [ 47.928122] systemd[621]: Stopped Run user-specific NixOS activation.1438machine # [ 47.929857] systemd[621]: Stopping Run user-specific NixOS activation...1439machine # [ 47.934756] systemd[621]: Starting Run user-specific NixOS activation...1440machine # [ 47.977706] systemd[621]: Finished Run user-specific NixOS activation.1441machine # [ 47.982504] systemd[1]: Stopped target Reactivate sysinit units.1442machine # [ 47.984879] systemd[1]: Stopping Reactivate sysinit units...1443machine # [ 47.986563] systemd[1]: Reached target Reactivate sysinit units.1444machine # [ 47.990141] systemd[1]: Reached target Remote File Systems.1445machine # [ 47.995327] systemd[1]: Starting linger-users.service...1446machine # [ 48.000654] systemd[1]: Reached target Local File Systems.1447machine # [ 48.012893] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1448machine # [ 48.016380] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1449machine # [ 48.022471] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1450machine # [ 48.030432] systemd[1]: Starting Create SUID/SGID Wrappers...1451machine # [ 48.033815] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1452machine # [ 48.036151] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671453machine # [ 48.040325] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1454machine # [ 48.043572] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1455machine # [ 48.046568] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1456machine # [ 48.103961] systemd[1]: linger-users.service: Deactivated successfully.1457machine # [ 48.107178] systemd[1]: Finished linger-users.service.1458machine # [ 48.412133] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1459machine # [ 48.415120] systemd[1]: Finished Create SUID/SGID Wrappers.1460machine # [ 48.676756] nixos[2084]: finished switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1461machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.23 seconds)1462machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1463machine # [ 48.719790] sudo[2223]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1464machine # [ 48.736319] sudo[2223]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1465machine # [ 48.748366] sudo[2223]: pam_unix(sudo:session): session closed for user usertest1466machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1467machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11468machine # [ 49.369405] nixos[2232]: switching to system configuration /nix/store/ajjx6ri2j2r0850zjka1f38k6xij6xj4-nixos-system-machine-test1469machine # [ 49.373152] systemd[1]: Stopped target Remote File Systems.1470machine # [ 49.375955] systemd[1]: Stopped target Local File Systems.1471machine # [ 49.847151] systemd[1]: Reload requested from client PID 2232 ('.switch-to-conf') (unit backdoor.service)...1472machine # [ 49.850104] systemd[1]: Reloading...1473machine # [ 50.386525] systemd[1]: Reloading finished in 533 ms.1474machine # [ 50.569831] systemd[621]: Reexecution requested from client PID 2301 ('switch-to-confi')...1475machine # [ 50.573814] systemd[621]: Reexecuting.1476machine # [ 50.604914] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1477machine # [ 50.784396] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1478machine # [ 50.792216] systemd[621]: Reload requested from client PID 2301 ('switch-to-confi')...1479machine # [ 50.795118] systemd[621]: Reloading...1480machine # [ 50.968380] systemd[621]: Reloading finished in 171 ms.1481machine # [ 50.975054] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1482machine # [ 50.984165] systemd[621]: Reloading usertest.service...1483machine # [ 51.019657] systemd[621]: Reloaded usertest.service.1484machine # [ 51.028101] systemd[621]: Stopped Run user-specific NixOS activation.1485machine # [ 51.029834] systemd[621]: Stopping Run user-specific NixOS activation...1486machine # [ 51.033578] systemd[621]: Starting Run user-specific NixOS activation...1487machine # [ 51.076323] systemd[621]: Finished Run user-specific NixOS activation.1488machine # [ 51.081839] systemd[1]: Stopped target Reactivate sysinit units.1489machine # [ 51.083984] systemd[1]: Stopping Reactivate sysinit units...1490machine # [ 51.086611] systemd[1]: Reached target Reactivate sysinit units.1491machine # [ 51.089994] systemd[1]: Reached target Local File Systems.1492machine # [ 51.092780] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1493machine # [ 51.098855] systemd[1]: Starting Create SUID/SGID Wrappers...1494machine # [ 51.100496] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1495machine # [ 51.104817] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671496machine # [ 51.109317] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1497machine # [ 51.114407] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1498machine # [ 51.117536] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1499machine # [ 51.122356] systemd[1]: Reached target Remote File Systems.1500machine # [ 51.128888] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1501machine # [ 51.131875] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1502machine # [ 51.454220] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1503machine # [ 51.457164] systemd[1]: Finished Create SUID/SGID Wrappers.1504machine # [ 51.463404] systemd[1]: Starting linger-users.service...1505machine # [ 51.526145] systemd[1]: linger-users.service: Deactivated successfully.1506machine # [ 51.528163] systemd[1]: Finished linger-users.service.1507machine # [ 51.788136] nixos[2232]: finished switching to system configuration /nix/store/ajjx6ri2j2r0850zjka1f38k6xij6xj4-nixos-system-machine-test1508machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 3.05 seconds)1509machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1510machine # [ 51.874689] sudo[2371]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1511machine # [ 51.891399] sudo[2371]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1512machine # [ 51.904126] sudo[2371]: pam_unix(sudo:session): session closed for user usertest1513machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.11 seconds)1514machine: must fail: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11515machine # [ 52.543138] nixos[2380]: switching to system configuration /nix/store/bfr2wnag6yn11wfil9f0qc5k0jnp80n8-nixos-system-machine-test1516machine # [ 52.547152] systemd[1]: Stopped target Local File Systems.1517machine # [ 52.550555] systemd[1]: Stopped target Remote File Systems.1518machine # [ 53.078390] systemd[1]: Reload requested from client PID 2380 ('.switch-to-conf') (unit backdoor.service)...1519machine # [ 53.081240] systemd[1]: Reloading...1520machine # [ 53.702073] systemd[1]: Reloading finished in 617 ms.1521machine # [ 53.881872] systemd[621]: Stopped usertest.service.1522machine # [ 53.885101] systemd[621]: Reexecution requested from client PID 2449 ('switch-to-confi')...1523machine # [ 53.887348] systemd[621]: Reexecuting.1524machine # [ 53.916810] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1525machine # [ 54.088406] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1526machine # [ 54.097386] systemd[621]: Reload requested from client PID 2449 ('switch-to-confi')...1527machine # [ 54.099848] systemd[621]: Reloading...1528machine # [ 54.248563] systemd[621]: Reloading finished in 146 ms.1529machine # [ 54.253972] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1530machine # [ 54.264983] systemd[621]: Starting usertest.service...1531machine # [ 54.292495] systemd[621]: usertest.service: Main process exited, code=exited, status=1/FAILURE1532machine # [ 54.294915] systemd[621]: usertest.service: Failed with result 'exit-code'.1533machine # [ 54.297948] systemd[621]: Failed to start usertest.service.1534machine # [ 54.301983] systemd[621]: Stopped Run user-specific NixOS activation.1535machine # [ 54.304767] systemd[621]: Stopping Run user-specific NixOS activation...1536machine # [ 54.308521] systemd[621]: Starting Run user-specific NixOS activation...1537machine # [ 54.342902] systemd[621]: Finished Run user-specific NixOS activation.1538machine # [ 54.347710] systemd[1]: Stopped target Reactivate sysinit units.1539machine # [ 54.349895] systemd[1]: Stopping Reactivate sysinit units...1540machine # [ 54.351932] systemd[1]: Reached target Reactivate sysinit units.1541machine # [ 54.355519] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1542machine # [ 54.358642] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1543machine # [ 54.362504] systemd[1]: Reached target Remote File Systems.1544machine # [ 54.369828] systemd[1]: Reached target Local File Systems.1545machine # [ 54.376585] systemd[1]: Starting linger-users.service...1546machine # [ 54.387291] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1547machine # [ 54.395198] systemd[1]: Starting Create SUID/SGID Wrappers...1548machine # [ 54.396930] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1549machine # [ 54.400325] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671550machine # [ 54.404733] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1551machine # [ 54.408584] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1552machine # [ 54.410802] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1553machine # [ 54.467820] systemd[1]: linger-users.service: Deactivated successfully.1554machine # [ 54.471130] systemd[1]: Finished linger-users.service.1555machine # [ 54.754301] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1556machine # [ 54.757490] systemd[1]: Finished Create SUID/SGID Wrappers.1557machine # [ 55.018352] nixos[2380]: switching to system configuration /nix/store/bfr2wnag6yn11wfil9f0qc5k0jnp80n8-nixos-system-machine-test failed (status 4)1558machine: (finished: must fail: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1559machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11560machine # [ 55.634634] nixos[2522]: switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1561machine # [ 55.638560] systemd[1]: Stopped target Remote File Systems.1562machine # [ 55.641386] systemd[1]: Stopped target Local File Systems.1563machine # [ 56.132251] systemd[1]: Reload requested from client PID 2522 ('.switch-to-conf') (unit backdoor.service)...1564machine # [ 56.135252] systemd[1]: Reloading...1565machine # [ 56.755976] systemd[1]: Reloading finished in 618 ms.1566machine # [ 56.888262] systemd[621]: Reexecution requested from client PID 2591 ('switch-to-confi')...1567machine # [ 56.892306] systemd[621]: Reexecuting.1568machine # [ 56.916768] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1569machine # [ 57.082728] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1570machine # [ 57.091242] systemd[621]: Reload requested from client PID 2591 ('switch-to-confi')...1571machine # [ 57.093533] systemd[621]: Reloading...1572machine # [ 57.244992] systemd[621]: Reloading finished in 148 ms.1573machine # [ 57.250990] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1574machine # [ 57.262804] systemd[621]: Starting usertest.service...1575machine # [ 57.291994] systemd[621]: Finished usertest.service.1576machine # [ 57.296463] systemd[621]: Stopped Run user-specific NixOS activation.1577machine # [ 57.298166] systemd[621]: Stopping Run user-specific NixOS activation...1578machine # [ 57.301803] systemd[621]: Starting Run user-specific NixOS activation...1579machine # [ 57.336359] systemd[621]: Finished Run user-specific NixOS activation.1580machine # [ 57.341784] systemd[1]: Stopped target Reactivate sysinit units.1581machine # [ 57.343925] systemd[1]: Stopping Reactivate sysinit units...1582machine # [ 57.346253] systemd[1]: Reached target Reactivate sysinit units.1583machine # [ 57.349703] systemd[1]: Reached target Local File Systems.1584machine # [ 57.357591] systemd[1]: Reached target Remote File Systems.1585machine # [ 57.362708] systemd[1]: Starting linger-users.service...1586machine # [ 57.365724] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1587machine # [ 57.369699] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1588machine # [ 57.379114] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1589machine # [ 57.387850] systemd[1]: Starting Create SUID/SGID Wrappers...1590machine # [ 57.390689] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1591machine # [ 57.393206] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671592machine # [ 57.397732] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1593machine # [ 57.400653] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1594machine # [ 57.402920] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1595machine # [ 57.460118] systemd[1]: linger-users.service: Deactivated successfully.1596machine # [ 57.462601] systemd[1]: Finished linger-users.service.1597machine # [ 57.747668] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1598machine # [ 57.750545] systemd[1]: Finished Create SUID/SGID Wrappers.1599machine # [ 58.011811] nixos[2522]: finished switching to system configuration /nix/store/pmzqvif7cb0hwi86rspmgmwsqlgf05z2-nixos-system-machine-test1600machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.99 seconds)1601machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1602machine # [ 58.087994] sudo[2661]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1603machine # [ 58.104615] sudo[2661]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1604machine # [ 58.117411] sudo[2661]: pam_unix(sudo:session): session closed for user usertest1605machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1606machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&11607machine # [ 58.687264] nixos[2670]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1608machine # [ 58.692478] systemd[1]: Stopped target Local File Systems.1609machine # [ 58.694669] systemd[1]: Stopped target Remote File Systems.1610machine # [ 59.130455] systemd[1]: Reload requested from client PID 2670 ('.switch-to-conf') (unit backdoor.service)...1611machine # [ 59.133403] systemd[1]: Reloading...1612machine # [ 59.724272] systemd[1]: Reloading finished in 585 ms.1613machine # [ 59.859725] systemd[621]: Stopped usertest.service.1614machine # [ 59.863843] systemd[621]: Reexecution requested from client PID 2739 ('switch-to-confi')...1615machine # [ 59.866840] systemd[621]: Reexecuting.1616machine # [ 59.897896] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1617machine # [ 60.055945] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1618machine # [ 60.064384] systemd[621]: Reload requested from client PID 2739 ('switch-to-confi')...1619machine # [ 60.067154] systemd[621]: Reloading...1620machine # [ 60.237585] systemd[621]: Reloading finished in 168 ms.1621machine # [ 60.243122] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1622machine # [ 60.255413] systemd[621]: Stopped Run user-specific NixOS activation.1623machine # [ 60.257197] systemd[621]: Stopping Run user-specific NixOS activation...1624machine # [ 60.261480] systemd[621]: Starting Run user-specific NixOS activation...1625machine # [ 60.306269] systemd[621]: Finished Run user-specific NixOS activation.1626machine # [ 60.310915] systemd[1]: Stopped target Reactivate sysinit units.1627machine # [ 60.313524] systemd[1]: Stopping Reactivate sysinit units...1628machine # [ 60.315205] systemd[1]: Reached target Reactivate sysinit units.1629machine # [ 60.318653] systemd[1]: Reached target Remote File Systems.1630machine # [ 60.323699] systemd[1]: Starting linger-users.service...1631machine # [ 60.327183] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1632machine # [ 60.330733] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1633machine # [ 60.341708] systemd[1]: Reached target Local File Systems.1634machine # [ 60.343258] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1635machine # [ 60.348835] systemd[1]: Starting Create SUID/SGID Wrappers...1636machine # [ 60.350601] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1637machine # [ 60.354724] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671638machine # [ 60.361244] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1639machine # [ 60.365368] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1640machine # [ 60.372248] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1641machine # [ 60.423325] systemd[1]: linger-users.service: Deactivated successfully.1642machine # [ 60.426197] systemd[1]: Finished linger-users.service.1643machine # [ 60.710839] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1644machine # [ 60.712860] systemd[1]: Finished Create SUID/SGID Wrappers.1645machine # [ 60.970105] nixos[2670]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1646machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.85 seconds)1647machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1648machine # [ 61.022456] sudo[2807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1649machine # [ 61.038950] sudo[2807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1650machine # [ 61.053406] sudo[2807]: pam_unix(sudo:session): session closed for user usertest1651machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1652machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1653machine # [ 61.102336] sudo[2813]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1654machine # [ 61.118651] sudo[2813]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1655machine # [ 61.132850] sudo[2813]: pam_unix(sudo:session): session closed for user usertest1656machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1657machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1658machine # [ 61.181495] sudo[2819]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1659machine # [ 61.198539] sudo[2819]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1660machine # [ 61.214230] sudo[2819]: pam_unix(sudo:session): session closed for user usertest1661machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.08 seconds)1662machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1663machine # [ 61.262854] sudo[2825]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1664machine # [ 61.280741] sudo[2825]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1665machine # [ 61.296231] sudo[2825]: pam_unix(sudo:session): session closed for user usertest1666machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1667machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1668machine # [ 61.341963] sudo[2831]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1669machine # [ 61.361543] sudo[2831]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1670machine # [ 61.373621] systemd[621]: Reload requested from client PID 2836 ('systemctl')...1671machine # [ 61.376230] systemd[621]: Reloading...1672machine # [ 61.536348] systemd[621]: Reloading finished in 160 ms.1673machine # [ 61.542042] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1674machine # [ 61.553110] sudo[2831]: pam_unix(sudo:session): session closed for user usertest1675machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.26 seconds)1676machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1677machine # [ 61.597373] sudo[2841]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1678machine # [ 61.612547] sudo[2841]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1679machine # [ 61.627745] systemd[621]: Starting migrated.service...1680machine # [ 61.655513] systemd[621]: Finished migrated.service.1681machine # [ 61.660132] sudo[2841]: pam_unix(sudo:session): session closed for user usertest1682machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)1683machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1684machine # [ 61.705743] sudo[2849]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1685machine # [ 61.722248] sudo[2849]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1686machine # [ 61.735635] sudo[2849]: pam_unix(sudo:session): session closed for user usertest1687machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1688machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1689machine # [ 61.783816] sudo[2855]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1690machine # [ 61.800384] sudo[2855]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1691machine # [ 61.813624] sudo[2855]: pam_unix(sudo:session): session closed for user usertest1692machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1693machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1694machine # [ 61.859437] sudo[2861]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1695machine # [ 61.875377] sudo[2861]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1696machine # [ 61.897530] sudo[2861]: pam_unix(sudo:session): session closed for user usertest1697machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)1698machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11699machine # [ 62.468390] nixos[2870]: switching to system configuration /nix/store/5301ispnglfnj52fhv9jnps9pxzgxcni-nixos-system-machine-test1700machine # [ 62.472591] systemd[1]: Stopped target Local File Systems.1701machine # [ 62.475283] systemd[1]: Stopped target Remote File Systems.1702machine # [ 63.003758] systemd[1]: Reload requested from client PID 2870 ('.switch-to-conf') (unit backdoor.service)...1703machine # [ 63.006653] systemd[1]: Reloading...1704machine # [ 63.558408] systemd[1]: Reloading finished in 548 ms.1705machine # [ 63.743477] systemd[621]: Reexecution requested from client PID 2939 ('switch-to-confi')...1706machine # [ 63.747333] systemd[621]: Reexecuting.1707machine # [ 63.777944] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1708machine # [ 63.943096] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1709machine # [ 63.952393] systemd[621]: Reload requested from client PID 2939 ('switch-to-confi')...1710machine # [ 63.954663] systemd[621]: Reloading...1711machine # [ 64.130182] systemd[621]: Reloading finished in 172 ms.1712machine # [ 64.135992] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1713machine # [ 64.146535] systemd[621]: Stopped Run user-specific NixOS activation.1714machine # [ 64.149230] systemd[621]: Stopping Run user-specific NixOS activation...1715machine # [ 64.153440] systemd[621]: Starting Run user-specific NixOS activation...1716machine # [ 64.247835] systemd[621]: Reload requested from client PID 2951 ('systemctl') (unit nixos-activation.service)...1717machine # [ 64.251099] systemd[621]: Reloading...1718machine # [ 64.414116] systemd[621]: Reloading finished in 160 ms.1719machine # [ 64.420096] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1720machine # [ 64.435797] systemd[621]: Stopped migrated.service.1721machine # [ 64.450729] systemd[621]: Finished Run user-specific NixOS activation.1722machine # [ 64.454086] systemd[621]: Reload requested from client PID 2939 ('switch-to-confi')...1723machine # [ 64.457129] systemd[621]: Reloading...1724machine # [ 64.615933] systemd[621]: Reloading finished in 156 ms.1725machine # [ 64.621985] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1726machine # [ 64.635699] systemd[621]: Starting migrated.service...1727machine # [ 64.668640] systemd[621]: Finished migrated.service.1728machine # [ 64.673733] systemd[1]: Stopped target Reactivate sysinit units.1729machine # [ 64.675870] systemd[1]: Stopping Reactivate sysinit units...1730machine # [ 64.677845] systemd[1]: Reached target Reactivate sysinit units.1731machine # [ 64.681295] systemd[1]: Reached target Local File Systems.1732machine # [ 64.684752] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1733machine # [ 64.690099] systemd[1]: Starting Create SUID/SGID Wrappers...1734machine # [ 64.691705] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1735machine # [ 64.696197] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671736machine # [ 64.701226] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1737machine # [ 64.706215] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1738machine # [ 64.711285] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1739machine # [ 64.714131] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1740machine # [ 64.716469] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1741machine # [ 64.723793] systemd[1]: Reached target Remote File Systems.1742machine # [ 65.055783] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1743machine # [ 65.058598] systemd[1]: Finished Create SUID/SGID Wrappers.1744machine # [ 65.064974] systemd[1]: Starting linger-users.service...1745machine # [ 65.115127] systemd[1]: linger-users.service: Deactivated successfully.1746machine # [ 65.117670] systemd[1]: Finished linger-users.service.1747machine # [ 65.377712] nixos[2870]: finished switching to system configuration /nix/store/5301ispnglfnj52fhv9jnps9pxzgxcni-nixos-system-machine-test1748machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 3.49 seconds)1749machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1750machine # [ 65.455491] sudo[3021]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1751machine # [ 65.470573] sudo[3021]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1752machine # [ 65.481923] sudo[3021]: pam_unix(sudo:session): session closed for user usertest1753machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1754machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1755machine # [ 65.525821] sudo[3027]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1756machine # [ 65.539656] sudo[3027]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1757machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)1758machine # [ 65.565221] sudo[3027]: pam_unix(sudo:session): session closed for user usertest1759machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1760machine # [ 65.607985] sudo[3033]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1761machine # [ 65.625686] sudo[3033]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1762machine # [ 65.640382] sudo[3033]: pam_unix(sudo:session): session closed for user usertest1763machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1764machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&11765machine # [ 66.192827] nixos[3042]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1766machine # [ 66.198347] systemd[1]: Stopped target Remote File Systems.1767machine # [ 66.200150] systemd[1]: Stopped target Local File Systems.1768machine # [ 66.628775] systemd[1]: Reload requested from client PID 3042 ('.switch-to-conf') (unit backdoor.service)...1769machine # [ 66.631669] systemd[1]: Reloading...1770machine # [ 67.166637] systemd[1]: Reloading finished in 532 ms.1771machine # [ 67.325731] systemd[621]: Stopped migrated.service.1772machine # [ 67.328935] systemd[621]: Reexecution requested from client PID 3111 ('switch-to-confi')...1773machine # [ 67.331224] systemd[621]: Reexecuting.1774machine # [ 67.362233] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1775machine # [ 67.503442] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1776machine # [ 67.511780] systemd[621]: Reload requested from client PID 3111 ('switch-to-confi')...1777machine # [ 67.514294] systemd[621]: Reloading...1778machine # [ 67.692129] systemd[621]: Reloading finished in 175 ms.1779machine # [ 67.699088] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1780machine # [ 67.710830] systemd[621]: Stopped Run user-specific NixOS activation.1781machine # [ 67.712496] systemd[621]: Stopping Run user-specific NixOS activation...1782machine # [ 67.716365] systemd[621]: Starting Run user-specific NixOS activation...1783machine # [ 67.752709] systemd[621]: Finished Run user-specific NixOS activation.1784machine # [ 67.757575] systemd[1]: Stopped target Reactivate sysinit units.1785machine # [ 67.760309] systemd[1]: Stopping Reactivate sysinit units...1786machine # [ 67.762158] systemd[1]: Reached target Reactivate sysinit units.1787machine # [ 67.765294] systemd[1]: Reached target Remote File Systems.1788machine # [ 67.769334] systemd[1]: Starting linger-users.service...1789machine # [ 67.775601] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1790machine # [ 67.779095] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1791machine # [ 67.793581] systemd[1]: Reached target Local File Systems.1792machine # [ 67.799228] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1793machine # [ 67.804567] systemd[1]: Starting Create SUID/SGID Wrappers...1794machine # [ 67.808312] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1795machine # [ 67.810531] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671796machine # [ 67.814929] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1797machine # [ 67.818591] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1798machine # [ 67.822190] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1799machine # [ 67.875845] systemd[1]: linger-users.service: Deactivated successfully.1800machine # [ 67.880559] systemd[1]: Finished linger-users.service.1801machine # [ 68.177918] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1802machine # [ 68.181486] systemd[1]: Finished Create SUID/SGID Wrappers.1803machine # [ 68.438527] nixos[3042]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1804machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.80 seconds)1805machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1806machine # [ 68.493391] sudo[3179]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1807machine # [ 68.509802] sudo[3179]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1808machine # [ 68.523352] sudo[3179]: pam_unix(sudo:session): session closed for user usertest1809machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1810machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1811machine # [ 68.569819] sudo[3185]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1812machine # [ 68.584300] sudo[3185]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1813machine # [ 68.595642] sudo[3185]: pam_unix(sudo:session): session closed for user usertest1814machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)1815machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1816machine # [ 68.634989] sudo[3191]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1817machine # [ 68.651602] sudo[3191]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1818machine # [ 68.664882] sudo[3191]: pam_unix(sudo:session): session closed for user usertest1819machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)1820machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1821machine # [ 68.718636] sudo[3197]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1822machine # [ 68.734350] sudo[3197]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1823machine # [ 68.750716] sudo[3197]: pam_unix(sudo:session): session closed for user usertest1824machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.08 seconds)1825machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1826machine # [ 68.798645] sudo[3203]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1827machine # [ 68.813662] sudo[3203]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1828machine # [ 68.822769] systemd[621]: Reload requested from client PID 3208 ('systemctl')...1829machine # [ 68.825099] systemd[621]: Reloading...1830machine # [ 68.986294] systemd[621]: Reloading finished in 158 ms.1831machine # [ 68.992098] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1832machine # [ 69.003229] sudo[3203]: pam_unix(sudo:session): session closed for user usertest1833machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.25 seconds)1834machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1835machine # [ 69.059585] sudo[3213]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1836machine # [ 69.074425] sudo[3213]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1837machine # [ 69.086151] systemd[621]: Starting migrated.service...1838machine # [ 69.116403] systemd[621]: Finished migrated.service.1839machine # [ 69.120585] sudo[3213]: pam_unix(sudo:session): session closed for user usertest1840machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)1841machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1842machine # [ 69.167768] sudo[3221]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1843machine # [ 69.183542] sudo[3221]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 69.195336] sudo[3221]: pam_unix(sudo:session): session closed for user usertest1845machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1846machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1847machine # [ 69.239158] sudo[3227]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1848machine # [ 69.255896] sudo[3227]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1849machine # [ 69.273493] sudo[3227]: pam_unix(sudo:session): session closed for user usertest1850machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)1851machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1852machine # [ 69.333161] sudo[3233]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1853machine # [ 69.348490] sudo[3233]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1854machine # [ 69.368599] sudo[3233]: pam_unix(sudo:session): session closed for user usertest1855machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1856machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11857machine # [ 70.000443] nixos[3242]: switching to system configuration /nix/store/bnmn7fysynhv21jxhpv2sk3igshk6wgr-nixos-system-machine-test1858machine # [ 70.004849] systemd[1]: Stopped target Remote File Systems.1859machine # [ 70.008325] systemd[1]: Stopped target Local File Systems.1860machine # [ 70.561146] systemd[1]: Reload requested from client PID 3242 ('.switch-to-conf') (unit backdoor.service)...1861machine # [ 70.564126] systemd[1]: Reloading...1862machine # [ 71.143632] systemd[1]: Reloading finished in 576 ms.1863machine # [ 71.313694] systemd[621]: Reexecution requested from client PID 3311 ('switch-to-confi')...1864machine # [ 71.318241] systemd[621]: Reexecuting.1865machine # [ 71.345210] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1866machine # [ 71.511721] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1867machine # [ 71.519108] systemd[621]: Reload requested from client PID 3311 ('switch-to-confi')...1868machine # [ 71.522253] systemd[621]: Reloading...1869machine # [ 71.685108] systemd[621]: Reloading finished in 161 ms.1870machine # [ 71.690040] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1871machine # [ 71.700781] systemd[621]: Stopped Run user-specific NixOS activation.1872machine # [ 71.703989] systemd[621]: Stopping Run user-specific NixOS activation...1873machine # [ 71.707567] systemd[621]: Starting Run user-specific NixOS activation...1874machine # [ 71.764440] systemd[621]: Reload requested from client PID 3323 ('systemctl') (unit nixos-activation.service)...1875machine # [ 71.767598] systemd[621]: Reloading...1876machine # [ 71.941991] systemd[621]: Reloading finished in 171 ms.1877machine # [ 71.947095] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1878machine # [ 71.962435] systemd[621]: Finished Run user-specific NixOS activation.1879machine # [ 71.965748] systemd[621]: Reload requested from client PID 3311 ('switch-to-confi')...1880machine # [ 71.968207] systemd[621]: Reloading...1881machine # [ 72.137936] systemd[621]: Reloading finished in 167 ms.1882machine # [ 72.142989] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1883machine # [ 72.152520] systemd[621]: Stopped migrated.service.1884machine # [ 72.155145] systemd[621]: Stopping migrated.service...1885machine # [ 72.158514] systemd[621]: Starting migrated.service...1886machine # [ 72.194270] systemd[621]: Finished migrated.service.1887machine # [ 72.203830] systemd[1]: Stopped target Reactivate sysinit units.1888machine # [ 72.205656] systemd[1]: Stopping Reactivate sysinit units...1889machine # [ 72.208584] systemd[1]: Reached target Reactivate sysinit units.1890machine # [ 72.210294] systemd[1]: Reached target Local File Systems.1891machine # [ 72.216840] systemd[1]: Reached target Remote File Systems.1892machine # [ 72.221466] systemd[1]: Starting linger-users.service...1893machine # [ 72.225130] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1894machine # [ 72.229442] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1895machine # [ 72.239113] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1896machine # [ 72.247854] systemd[1]: Starting Create SUID/SGID Wrappers...1897machine # [ 72.249409] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1898machine # [ 72.251640] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671899machine # [ 72.255460] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1900machine # [ 72.263677] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1901machine # [ 72.266178] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 72.324114] systemd[1]: linger-users.service: Deactivated successfully.1903machine # [ 72.327109] systemd[1]: Finished linger-users.service.1904machine # [ 72.619079] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1905machine # [ 72.621176] systemd[1]: Finished Create SUID/SGID Wrappers.1906machine # [ 72.882609] nixos[3242]: finished switching to system configuration /nix/store/bnmn7fysynhv21jxhpv2sk3igshk6wgr-nixos-system-machine-test1907machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 3.52 seconds)1908machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1909machine # [ 72.959364] sudo[3392]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1910machine # [ 72.976422] sudo[3392]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1911machine # [ 72.989529] sudo[3392]: pam_unix(sudo:session): session closed for user usertest1912machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1913machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1914machine # [ 73.038433] sudo[3398]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1915machine # [ 73.054780] sudo[3398]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1916machine # [ 73.083418] sudo[3398]: pam_unix(sudo:session): session closed for user usertest1917machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)1918machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1919machine # [ 73.128403] sudo[3404]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1920machine # [ 73.144749] sudo[3404]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1921machine # [ 73.158492] sudo[3404]: pam_unix(sudo:session): session closed for user usertest1922machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1923machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&11924machine # [ 73.731773] nixos[3413]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1925machine # [ 73.735740] systemd[1]: Stopped target Remote File Systems.1926machine # [ 73.738844] systemd[1]: Stopped target Local File Systems.1927machine # [ 74.178730] systemd[1]: Reload requested from client PID 3413 ('.switch-to-conf') (unit backdoor.service)...1928machine # [ 74.181647] systemd[1]: Reloading...1929machine # [ 74.733129] systemd[1]: Reloading finished in 548 ms.1930machine # [ 74.875469] systemd[621]: Stopped migrated.service.1931machine # [ 74.879479] systemd[621]: Reexecution requested from client PID 3482 ('switch-to-confi')...1932machine # [ 74.881795] systemd[621]: Reexecuting.1933machine # [ 74.912755] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1934machine # [ 75.077369] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1935machine # [ 75.086876] systemd[621]: Reload requested from client PID 3482 ('switch-to-confi')...1936machine # [ 75.089504] systemd[621]: Reloading...1937machine # [ 75.255793] systemd[621]: Reloading finished in 163 ms.1938machine # [ 75.261065] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1939machine # [ 75.271331] systemd[621]: Stopped Run user-specific NixOS activation.1940machine # [ 75.273225] systemd[621]: Stopping Run user-specific NixOS activation...1941machine # [ 75.277861] systemd[621]: Starting Run user-specific NixOS activation...1942machine # [ 75.314754] systemd[621]: Finished Run user-specific NixOS activation.1943machine # [ 75.319382] systemd[1]: Stopped target Reactivate sysinit units.1944machine # [ 75.322243] systemd[1]: Stopping Reactivate sysinit units...1945machine # [ 75.324724] systemd[1]: Reached target Reactivate sysinit units.1946machine # [ 75.329403] systemd[1]: Reached target Remote File Systems.1947machine # [ 75.337853] systemd[1]: Starting linger-users.service...1948machine # [ 75.349046] systemd[1]: Reached target Local File Systems.1949machine # [ 75.350514] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1950machine # [ 75.358130] systemd[1]: Starting Create SUID/SGID Wrappers...1951machine # [ 75.360057] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1952machine # [ 75.363218] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671953machine # [ 75.367861] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1954machine # [ 75.373204] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1955machine # [ 75.375428] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1956machine # [ 75.378668] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1957machine # [ 75.382661] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1958machine # [ 75.436720] systemd[1]: linger-users.service: Deactivated successfully.1959machine # [ 75.439403] systemd[1]: Finished linger-users.service.1960machine # [ 75.731700] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1961machine # [ 75.734541] systemd[1]: Finished Create SUID/SGID Wrappers.1962machine # [ 75.993867] nixos[3413]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test1963machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.84 seconds)1964machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1965machine # [ 76.070413] sudo[3550]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1966machine # [ 76.085655] sudo[3550]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1967machine # [ 76.100702] sudo[3550]: pam_unix(sudo:session): session closed for user usertest1968machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)1969machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1970machine # [ 76.148498] sudo[3556]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1971machine # [ 76.164882] sudo[3556]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1972machine # [ 76.181227] sudo[3556]: pam_unix(sudo:session): session closed for user usertest1973machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.08 seconds)1974machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1975machine # [ 76.251829] sudo[3562]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1976machine # [ 76.271710] sudo[3562]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1977machine # [ 76.284349] sudo[3562]: pam_unix(sudo:session): session closed for user usertest1978machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.10 seconds)1979machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1980machine # [ 76.323483] sudo[3568]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1981machine # [ 76.339391] sudo[3568]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1982machine # [ 76.350608] sudo[3568]: pam_unix(sudo:session): session closed for user usertest1983machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)1984machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1985machine # [ 76.387694] sudo[3574]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1986machine # [ 76.401609] sudo[3574]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1987machine # [ 76.410443] systemd[621]: Reload requested from client PID 3579 ('systemctl')...1988machine # [ 76.413234] systemd[621]: Reloading...1989machine # [ 76.555919] systemd[621]: Reloading finished in 140 ms.1990machine # [ 76.561041] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1991machine # [ 76.571136] sudo[3574]: pam_unix(sudo:session): session closed for user usertest1992machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.22 seconds)1993machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1994machine # [ 76.610716] sudo[3584]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1995machine # [ 76.625719] sudo[3584]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1996machine # [ 76.640240] systemd[621]: Starting migrated.service...1997machine # [ 76.670719] systemd[621]: Finished migrated.service.1998machine # [ 76.675390] sudo[3584]: pam_unix(sudo:session): session closed for user usertest1999machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)2000machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2001machine # [ 76.735564] sudo[3592]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2002machine # [ 76.757541] sudo[3592]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2003machine # [ 76.771841] sudo[3592]: pam_unix(sudo:session): session closed for user usertest2004machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2005machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2006machine # [ 76.812412] sudo[3598]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2007machine # [ 76.826839] sudo[3598]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2008machine # [ 76.838548] sudo[3598]: pam_unix(sudo:session): session closed for user usertest2009machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2010machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2011machine # [ 76.877327] sudo[3604]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2012machine # [ 76.892775] sudo[3604]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2013machine # [ 76.912466] sudo[3604]: pam_unix(sudo:session): session closed for user usertest2014machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2015machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&12016machine # [ 77.499469] nixos[3613]: switching to system configuration /nix/store/p45scc859c6l862pxzd1d109383g22la-nixos-system-machine-test2017machine # [ 77.503535] systemd[1]: Stopped target Local File Systems.2018machine # [ 77.506553] systemd[1]: Stopped target Remote File Systems.2019machine # [ 77.967383] systemd[1]: Reload requested from client PID 3613 ('.switch-to-conf') (unit backdoor.service)...2020machine # [ 77.970203] systemd[1]: Reloading...2021machine # [ 78.573384] systemd[1]: Reloading finished in 600 ms.2022machine # [ 78.738986] systemd[621]: Reexecution requested from client PID 3682 ('switch-to-confi')...2023machine # [ 78.743308] systemd[621]: Reexecuting.2024machine # [ 78.771678] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2025machine # [ 78.923495] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2026machine # [ 78.932106] systemd[621]: Reload requested from client PID 3682 ('switch-to-confi')...2027machine # [ 78.934404] systemd[621]: Reloading...2028machine # [ 79.087205] systemd[621]: Reloading finished in 149 ms.2029machine # [ 79.093042] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2030machine # [ 79.104675] systemd[621]: Stopped Run user-specific NixOS activation.2031machine # [ 79.106375] systemd[621]: Stopping Run user-specific NixOS activation...2032machine # [ 79.110687] systemd[621]: Starting Run user-specific NixOS activation...2033machine # [ 79.165923] systemd[621]: Reload requested from client PID 3694 ('systemctl') (unit nixos-activation.service)...2034machine # [ 79.168948] systemd[621]: Reloading...2035machine # [ 79.334979] systemd[621]: Reloading finished in 163 ms.2036machine # [ 79.340159] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2037machine # [ 79.355862] systemd[621]: Finished Run user-specific NixOS activation.2038machine # [ 79.359226] systemd[621]: Reload requested from client PID 3682 ('switch-to-confi')...2039machine # [ 79.361751] systemd[621]: Reloading...2040machine # [ 79.528638] systemd[621]: Reloading finished in 164 ms.2041machine # [ 79.535055] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2042machine # [ 79.549159] systemd[621]: Reloading migrated.service...2043machine # [ 79.583443] systemd[621]: Reloaded migrated.service.2044machine # [ 79.592847] systemd[1]: Stopped target Reactivate sysinit units.2045machine # [ 79.594832] systemd[1]: Stopping Reactivate sysinit units...2046machine # [ 79.597381] systemd[1]: Reached target Reactivate sysinit units.2047machine # [ 79.602482] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2048machine # [ 79.606334] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2049machine # [ 79.610916] systemd[1]: Reached target Remote File Systems.2050machine # [ 79.617874] systemd[1]: Starting linger-users.service...2051machine # [ 79.629259] systemd[1]: Reached target Local File Systems.2052machine # [ 79.630775] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2053machine # [ 79.637766] systemd[1]: Starting Create SUID/SGID Wrappers...2054machine # [ 79.641376] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2055machine # [ 79.647243] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672056machine # [ 79.651173] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2057machine # [ 79.654335] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2058machine # [ 79.657273] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2059machine # [ 79.721297] systemd[1]: linger-users.service: Deactivated successfully.2060machine # [ 79.723261] systemd[1]: Finished linger-users.service.2061machine # [ 79.974833] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2062machine # [ 79.977589] systemd[1]: Finished Create SUID/SGID Wrappers.2063machine # [ 80.234155] nixos[3613]: finished switching to system configuration /nix/store/p45scc859c6l862pxzd1d109383g22la-nixos-system-machine-test2064machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.32 seconds)2065machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2066machine # [ 80.272466] sudo[3763]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2067machine # [ 80.286621] sudo[3763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2068machine # [ 80.297103] sudo[3763]: pam_unix(sudo:session): session closed for user usertest2069machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2070machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2071machine # [ 80.334375] sudo[3769]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2072machine # [ 80.348300] sudo[3769]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2073machine # [ 80.360108] sudo[3769]: pam_unix(sudo:session): session closed for user usertest2074machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2075machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12076machine # [ 80.848083] nixos[3778]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2077machine # [ 80.853525] systemd[1]: Stopped target Remote File Systems.2078machine # [ 80.855686] systemd[1]: Stopped target Local File Systems.2079machine # [ 81.294961] systemd[1]: Reload requested from client PID 3778 ('.switch-to-conf') (unit backdoor.service)...2080machine # [ 81.298335] systemd[1]: Reloading...2081machine # [ 81.862187] systemd[1]: Reloading finished in 560 ms.2082machine # [ 81.993965] systemd[621]: Stopped migrated.service.2083machine # [ 81.996284] systemd[621]: Reexecution requested from client PID 3847 ('switch-to-confi')...2084machine # [ 82.000284] systemd[621]: Reexecuting.2085machine # [ 82.029060] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2086machine # [ 82.174603] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2087machine # [ 82.182990] systemd[621]: Reload requested from client PID 3847 ('switch-to-confi')...2088machine # [ 82.185326] systemd[621]: Reloading...2089machine # [ 82.345192] systemd[621]: Reloading finished in 156 ms.2090machine # [ 82.352044] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2091machine # [ 82.363845] systemd[621]: Stopped Run user-specific NixOS activation.2092machine # [ 82.365532] systemd[621]: Stopping Run user-specific NixOS activation...2093machine # [ 82.369525] systemd[621]: Starting Run user-specific NixOS activation...2094machine # [ 82.411942] systemd[621]: Finished Run user-specific NixOS activation.2095machine # [ 82.416988] systemd[1]: Stopped target Reactivate sysinit units.2096machine # [ 82.419559] systemd[1]: Stopping Reactivate sysinit units...2097machine # [ 82.422220] systemd[1]: Reached target Reactivate sysinit units.2098machine # [ 82.426176] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2099machine # [ 82.429463] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2100machine # [ 82.436255] systemd[1]: Reached target Local File Systems.2101machine # [ 82.437756] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2102machine # [ 82.443983] systemd[1]: Starting Create SUID/SGID Wrappers...2103machine # [ 82.445695] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2104machine # [ 82.449582] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672105machine # [ 82.454185] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2106machine # [ 82.458230] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2107machine # [ 82.462186] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2108machine # [ 82.465945] systemd[1]: Reached target Remote File Systems.2109machine # [ 82.772386] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2110machine # [ 82.775422] systemd[1]: Finished Create SUID/SGID Wrappers.2111machine # [ 82.781411] systemd[1]: Starting linger-users.service...2112machine # [ 82.863866] systemd[1]: linger-users.service: Deactivated successfully.2113machine # [ 82.865851] systemd[1]: Finished linger-users.service.2114machine # [ 83.126474] nixos[3778]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2115machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.77 seconds)2116machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2117machine # [ 83.196529] sudo[3915]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2118machine # [ 83.212967] sudo[3915]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2119machine # [ 83.226879] sudo[3915]: pam_unix(sudo:session): session closed for user usertest2120machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2121machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2122machine # [ 83.283403] sudo[3921]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2123machine # [ 83.301789] sudo[3921]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2124machine # [ 83.324938] sudo[3921]: pam_unix(sudo:session): session closed for user usertest2125machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.10 seconds)2126machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2127machine # [ 83.369176] sudo[3927]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service2128machine # [ 83.384771] sudo[3927]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2129machine # [ 83.396813] sudo[3927]: pam_unix(sudo:session): session closed for user usertest2130machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)2131machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2132machine # [ 83.437674] sudo[3933]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2133machine # [ 83.454615] sudo[3933]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2134machine # [ 83.467934] sudo[3933]: pam_unix(sudo:session): session closed for user usertest2135machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)2136machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2137machine # [ 83.518809] sudo[3939]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2138machine # [ 83.533802] sudo[3939]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2139machine # [ 83.543482] systemd[621]: Reload requested from client PID 3944 ('systemctl')...2140machine # [ 83.547800] systemd[621]: Reloading...2141machine # [ 83.716546] systemd[621]: Reloading finished in 169 ms.2142machine # [ 83.722051] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2143machine # [ 83.733303] sudo[3939]: pam_unix(sudo:session): session closed for user usertest2144machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.27 seconds)2145machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2146machine # [ 83.773349] sudo[3949]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2147machine # [ 83.787720] sudo[3949]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2148machine # [ 83.802180] systemd[621]: Starting migrated.service...2149machine # [ 83.829460] systemd[621]: Finished migrated.service.2150machine # [ 83.833794] sudo[3949]: pam_unix(sudo:session): session closed for user usertest2151machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)2152machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2153machine # [ 83.879544] sudo[3957]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2154machine # [ 83.895892] sudo[3957]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2155machine # [ 83.907957] sudo[3957]: pam_unix(sudo:session): session closed for user usertest2156machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2157machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2158machine # [ 83.956250] sudo[3963]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2159machine # [ 83.972642] sudo[3963]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2160machine # [ 83.986623] sudo[3963]: pam_unix(sudo:session): session closed for user usertest2161machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2162machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2163machine # [ 84.039432] sudo[3969]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2164machine # [ 84.054893] sudo[3969]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2165machine # [ 84.074360] sudo[3969]: pam_unix(sudo:session): session closed for user usertest2166machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)2167machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12168machine # [ 84.585293] nixos[3978]: switching to system configuration /nix/store/9d0m2vm9j9pf4i2w70g6gcdbhlljpll6-nixos-system-machine-test2169machine # [ 84.589683] systemd[1]: Stopped target Remote File Systems.2170machine # [ 84.592375] systemd[1]: Stopped target Local File Systems.2171machine # [ 85.070649] systemd[1]: Reload requested from client PID 3978 ('.switch-to-conf') (unit backdoor.service)...2172machine # [ 85.073615] systemd[1]: Reloading...2173machine # [ 85.696667] systemd[1]: Reloading finished in 620 ms.2174machine # [ 85.862510] systemd[621]: Reexecution requested from client PID 4047 ('switch-to-confi')...2175machine # [ 85.866702] systemd[621]: Reexecuting.2176machine # [ 85.895813] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2177machine # [ 86.056062] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2178machine # [ 86.063176] systemd[621]: Reload requested from client PID 4047 ('switch-to-confi')...2179machine # [ 86.065938] systemd[621]: Reloading...2180machine # [ 86.225667] systemd[621]: Reloading finished in 158 ms.2181machine # [ 86.231994] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2182machine # [ 86.243611] systemd[621]: Stopped Run user-specific NixOS activation.2183machine # [ 86.245340] systemd[621]: Stopping Run user-specific NixOS activation...2184machine # [ 86.249281] systemd[621]: Starting Run user-specific NixOS activation...2185machine # [ 86.294264] systemd[621]: Reload requested from client PID 4059 ('systemctl') (unit nixos-activation.service)...2186machine # [ 86.297215] systemd[621]: Reloading...2187machine # [ 86.442146] systemd[621]: Reloading finished in 142 ms.2188machine # [ 86.447100] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 86.461732] systemd[621]: Finished Run user-specific NixOS activation.2190machine # [ 86.464969] systemd[621]: Reload requested from client PID 4047 ('switch-to-confi')...2191machine # [ 86.467573] systemd[621]: Reloading...2192machine # [ 86.623288] systemd[621]: Reloading finished in 153 ms.2193machine # [ 86.628988] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 86.643783] systemd[1]: Stopped target Reactivate sysinit units.2195machine # [ 86.646270] systemd[1]: Stopping Reactivate sysinit units...2196machine # [ 86.648192] systemd[1]: Reached target Reactivate sysinit units.2197machine # [ 86.651101] systemd[1]: Reached target Remote File Systems.2198machine # [ 86.656117] systemd[1]: Starting linger-users.service...2199machine # [ 86.672639] systemd[1]: Reached target Local File Systems.2200machine # [ 86.679691] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2201machine # [ 86.683549] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2202machine # [ 86.686662] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2203machine # [ 86.693091] systemd[1]: Starting Create SUID/SGID Wrappers...2204machine # [ 86.694620] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2205machine # [ 86.696985] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672206machine # [ 86.702437] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2207machine # [ 86.707153] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2208machine # [ 86.709737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2209machine # [ 86.755358] systemd[1]: linger-users.service: Deactivated successfully.2210machine # [ 86.757666] systemd[1]: Finished linger-users.service.2211machine # [ 87.010851] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2212machine # [ 87.013679] systemd[1]: Finished Create SUID/SGID Wrappers.2213machine # [ 87.274770] nixos[3978]: finished switching to system configuration /nix/store/9d0m2vm9j9pf4i2w70g6gcdbhlljpll6-nixos-system-machine-test2214machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.21 seconds)2215machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2216machine # [ 87.347811] sudo[4126]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2217machine # [ 87.364782] sudo[4126]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2218machine # [ 87.376371] sudo[4126]: pam_unix(sudo:session): session closed for user usertest2219machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2220machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2221machine # [ 87.423766] sudo[4132]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2222machine # [ 87.438649] sudo[4132]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2223machine # [ 87.450922] sudo[4132]: pam_unix(sudo:session): session closed for user usertest2224machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2225machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12226machine # [ 88.056092] nixos[4141]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2227machine # [ 88.060055] systemd[1]: Stopped target Remote File Systems.2228machine # [ 88.063384] systemd[1]: Stopped target Local File Systems.2229machine # [ 88.563104] systemd[1]: Reload requested from client PID 4141 ('.switch-to-conf') (unit backdoor.service)...2230machine # [ 88.566161] systemd[1]: Reloading...2231machine # [ 89.089350] systemd[1]: Reloading finished in 520 ms.2232machine # [ 89.234611] systemd[621]: Stopped migrated.service.2233machine # [ 89.238245] systemd[621]: Reexecution requested from client PID 4210 ('switch-to-confi')...2234machine # [ 89.241124] systemd[621]: Reexecuting.2235machine # [ 89.271095] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2236machine # [ 89.409762] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2237machine # [ 89.419148] systemd[621]: Reload requested from client PID 4210 ('switch-to-confi')...2238machine # [ 89.421906] systemd[621]: Reloading...2239machine # [ 89.571581] systemd[621]: Reloading finished in 147 ms.2240machine # [ 89.577997] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2241machine # [ 89.589390] systemd[621]: Stopped Run user-specific NixOS activation.2242machine # [ 89.591158] systemd[621]: Stopping Run user-specific NixOS activation...2243machine # [ 89.595223] systemd[621]: Starting Run user-specific NixOS activation...2244machine # [ 89.633777] systemd[621]: Finished Run user-specific NixOS activation.2245machine # [ 89.638741] systemd[1]: Stopped target Reactivate sysinit units.2246machine # [ 89.640501] systemd[1]: Stopping Reactivate sysinit units...2247machine # [ 89.642509] systemd[1]: Reached target Reactivate sysinit units.2248machine # [ 89.645634] systemd[1]: Reached target Local File Systems.2249machine # [ 89.655704] systemd[1]: Reached target Remote File Systems.2250machine # [ 89.663847] systemd[1]: Starting linger-users.service...2251machine # [ 89.671077] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2252machine # [ 89.673339] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2253machine # [ 89.680774] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2254machine # [ 89.686542] systemd[1]: Starting Create SUID/SGID Wrappers...2255machine # [ 89.688110] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2256machine # [ 89.690310] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672257machine # [ 89.693853] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2258machine # [ 89.698757] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2259machine # [ 89.701663] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2260machine # [ 89.762204] systemd[1]: linger-users.service: Deactivated successfully.2261machine # [ 89.765182] systemd[1]: Finished linger-users.service.2262machine # [ 90.055786] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2263machine # [ 90.058567] systemd[1]: Finished Create SUID/SGID Wrappers.2264machine # [ 90.318544] nixos[4141]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2265machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.88 seconds)2266machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2267machine # [ 90.400484] sudo[4278]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2268machine # [ 90.424628] sudo[4278]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2269machine # [ 90.438300] sudo[4278]: pam_unix(sudo:session): session closed for user usertest2270machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.11 seconds)2271machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2272machine # [ 90.502162] sudo[4284]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2273machine # [ 90.515955] sudo[4284]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2274machine # [ 90.527073] sudo[4284]: pam_unix(sudo:session): session closed for user usertest2275machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.08 seconds)2276machine: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service2277machine # [ 90.563783] sudo[4290]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2278machine # [ 90.579376] sudo[4290]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2279machine # [ 90.592423] sudo[4290]: pam_unix(sudo:session): session closed for user usertest2280machine: (finished: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.07 seconds)2281machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2282machine # [ 90.633473] sudo[4296]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2283machine # [ 90.649640] sudo[4296]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2284machine # [ 90.659251] systemd[621]: Reload requested from client PID 4301 ('systemctl')...2285machine # [ 90.663412] systemd[621]: Reloading...2286machine # [ 90.818153] systemd[621]: Reloading finished in 155 ms.2287machine # [ 90.823983] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2288machine # [ 90.833954] sudo[4296]: pam_unix(sudo:session): session closed for user usertest2289machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.24 seconds)2290machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2291machine # [ 90.883392] sudo[4306]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2292machine # [ 90.900257] sudo[4306]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2293machine # [ 90.917640] systemd[621]: Starting migrated.service...2294machine # [ 90.955661] systemd[621]: Finished migrated.service.2295machine # [ 90.960559] sudo[4306]: pam_unix(sudo:session): session closed for user usertest2296machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.13 seconds)2297machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2298machine # [ 91.014418] sudo[4314]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2299machine # [ 91.030638] sudo[4314]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2300machine # [ 91.042111] sudo[4314]: pam_unix(sudo:session): session closed for user usertest2301machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2302machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2303machine # [ 91.085896] sudo[4320]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2304machine # [ 91.101342] sudo[4320]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2305machine # [ 91.113690] sudo[4320]: pam_unix(sudo:session): session closed for user usertest2306machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2307machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2308machine # [ 91.157255] sudo[4326]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2309machine # [ 91.173715] sudo[4326]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2310machine # [ 91.222927] sudo[4326]: pam_unix(sudo:session): session closed for user usertest2311machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.11 seconds)2312machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12313machine # [ 91.803814] nixos[4335]: switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2314machine # [ 91.808072] systemd[1]: Stopped target Local File Systems.2315machine # [ 91.810918] systemd[1]: Stopped target Remote File Systems.2316machine # [ 92.249899] systemd[1]: Reload requested from client PID 4335 ('.switch-to-conf') (unit backdoor.service)...2317machine # [ 92.252832] systemd[1]: Reloading...2318machine # [ 92.823900] systemd[1]: Reloading finished in 568 ms.2319machine # [ 92.972378] systemd[621]: Reexecution requested from client PID 4404 ('switch-to-confi')...2320machine # [ 92.974714] systemd[621]: Reexecuting.2321machine # [ 93.005204] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2322machine # [ 93.192696] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2323machine # [ 93.201964] systemd[621]: Reload requested from client PID 4404 ('switch-to-confi')...2324machine # [ 93.205862] systemd[621]: Reloading...2325machine # [ 93.370920] systemd[621]: Reloading finished in 162 ms.2326machine # [ 93.377207] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2327machine # [ 93.388795] systemd[621]: Stopped Run user-specific NixOS activation.2328machine # [ 93.392041] systemd[621]: Stopping Run user-specific NixOS activation...2329machine # [ 93.394981] systemd[621]: Starting Run user-specific NixOS activation...2330machine # [ 93.435261] systemd[621]: Finished Run user-specific NixOS activation.2331machine # [ 93.438924] systemd[621]: Reload requested from client PID 4404 ('switch-to-confi')...2332machine # [ 93.442078] systemd[621]: Reloading...2333machine # [ 93.594087] systemd[621]: Reloading finished in 150 ms.2334machine # [ 93.601046] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2335machine # [ 93.612076] systemd[621]: Stopped migrated.service.2336machine # [ 93.614412] systemd[621]: Stopping migrated.service...2337machine # [ 93.618123] systemd[621]: Starting migrated.service...2338machine # [ 93.654182] systemd[621]: Finished migrated.service.2339machine # [ 93.663798] systemd[1]: Stopped target Reactivate sysinit units.2340machine # [ 93.666834] systemd[1]: Stopping Reactivate sysinit units...2341machine # [ 93.668746] systemd[1]: Reached target Reactivate sysinit units.2342machine # [ 93.671398] systemd[1]: Reached target Local File Systems.2343machine # [ 93.674252] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2344machine # [ 93.680508] systemd[1]: Starting Create SUID/SGID Wrappers...2345machine # [ 93.683546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2346machine # [ 93.685782] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672347machine # [ 93.689650] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2348machine # [ 93.693702] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2349machine # [ 93.699534] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2350machine # [ 93.702365] systemd[1]: Reached target Remote File Systems.2351machine # [ 93.717970] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2352machine # [ 93.721684] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2353machine # [ 94.038875] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2354machine # [ 94.041814] systemd[1]: Finished Create SUID/SGID Wrappers.2355machine # [ 94.048337] systemd[1]: Starting linger-users.service...2356machine # [ 94.103896] systemd[1]: linger-users.service: Deactivated successfully.2357machine # [ 94.107242] systemd[1]: Finished linger-users.service.2358machine # [ 94.365788] nixos[4335]: finished switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2359machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 3.15 seconds)2360machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2361machine # [ 94.441503] sudo[4478]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2362machine # [ 94.459777] sudo[4478]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2363machine # [ 94.472344] sudo[4478]: pam_unix(sudo:session): session closed for user usertest2364machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.10 seconds)2365machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2366machine # [ 94.521499] sudo[4484]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2367machine # [ 94.539133] sudo[4484]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2368machine # [ 94.570265] sudo[4484]: pam_unix(sudo:session): session closed for user usertest2369machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2370machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2371machine # [ 94.622268] sudo[4490]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2372machine # [ 94.639436] sudo[4490]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2373machine # [ 94.653378] sudo[4490]: pam_unix(sudo:session): session closed for user usertest2374machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2375machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12376machine # [ 95.275397] nixos[4499]: switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2377machine # [ 95.279669] systemd[1]: Stopped target Remote File Systems.2378machine # [ 95.282455] systemd[1]: Stopped target Local File Systems.2379machine # [ 95.737387] systemd[1]: Reload requested from client PID 4499 ('.switch-to-conf') (unit backdoor.service)...2380machine # [ 95.740230] systemd[1]: Reloading...2381machine # [ 96.324376] systemd[1]: Reloading finished in 580 ms.2382machine # [ 96.478136] systemd[621]: Reexecution requested from client PID 4568 ('switch-to-confi')...2383machine # [ 96.482302] systemd[621]: Reexecuting.2384machine # [ 96.513608] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2385machine # [ 96.667990] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2386machine # [ 96.678956] systemd[621]: Reload requested from client PID 4568 ('switch-to-confi')...2387machine # [ 96.681630] systemd[621]: Reloading...2388machine # [ 96.846861] systemd[621]: Reloading finished in 163 ms.2389machine # [ 96.854088] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2390machine # [ 96.864957] systemd[621]: Stopped Run user-specific NixOS activation.2391machine # [ 96.867731] systemd[621]: Stopping Run user-specific NixOS activation...2392machine # [ 96.871559] systemd[621]: Starting Run user-specific NixOS activation...2393machine # [ 96.921081] systemd[621]: Finished Run user-specific NixOS activation.2394machine # [ 96.925937] systemd[1]: Stopped target Reactivate sysinit units.2395machine # [ 96.928263] systemd[1]: Stopping Reactivate sysinit units...2396machine # [ 96.930204] systemd[1]: Reached target Reactivate sysinit units.2397machine # [ 96.939179] systemd[1]: Reached target Local File Systems.2398machine # [ 96.944189] systemd[1]: Reached target Remote File Systems.2399machine # [ 96.952988] systemd[1]: Starting linger-users.service...2400machine # [ 96.957087] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2401machine # [ 96.959409] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2402machine # [ 96.965192] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2403machine # [ 96.972669] systemd[1]: Starting Create SUID/SGID Wrappers...2404machine # [ 96.975567] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2405machine # [ 96.978928] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672406machine # [ 96.983221] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2407machine # [ 96.987057] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2408machine # [ 96.990621] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2409machine # [ 97.049292] systemd[1]: linger-users.service: Deactivated successfully.2410machine # [ 97.051505] systemd[1]: Finished linger-users.service.2411machine # [ 97.322973] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2412machine # [ 97.326891] systemd[1]: Finished Create SUID/SGID Wrappers.2413machine # [ 97.587738] nixos[4499]: finished switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2414machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.94 seconds)2415machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2416machine # [ 97.655544] sudo[4636]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2417machine # [ 97.671426] sudo[4636]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2418machine # [ 97.683217] sudo[4636]: pam_unix(sudo:session): session closed for user usertest2419machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.09 seconds)2420machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2421machine # [ 97.720695] sudo[4642]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2422machine # [ 97.735239] sudo[4642]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2423machine # [ 97.744599] systemd[621]: Reload requested from client PID 4647 ('systemctl')...2424machine # [ 97.746795] systemd[621]: Reloading...2425machine # [ 97.900063] systemd[621]: Reloading finished in 150 ms.2426machine # [ 97.904986] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2427machine # [ 97.914373] sudo[4642]: pam_unix(sudo:session): session closed for user usertest2428machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.23 seconds)2429machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12430machine # [ 98.517421] nixos[4655]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2431machine # [ 98.521570] systemd[1]: Stopped target Local File Systems.2432machine # [ 98.524331] systemd[1]: Stopped target Remote File Systems.2433machine # [ 98.960664] systemd[1]: Reload requested from client PID 4655 ('.switch-to-conf') (unit backdoor.service)...2434machine # [ 98.963578] systemd[1]: Reloading...2435machine # [ 101.298435] systemd[1]: Reloading finished in 2331 ms.2436machine # [ 101.457654] systemd[621]: Stopped migrated.service.2437machine # [ 101.459895] systemd[621]: Reexecution requested from client PID 4724 ('switch-to-confi')...2438machine # [ 101.464094] systemd[621]: Reexecuting.2439machine # [ 101.491698] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2440machine # [ 101.662854] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2441machine # [ 101.671803] systemd[621]: Reload requested from client PID 4724 ('switch-to-confi')...2442machine # [ 101.674188] systemd[621]: Reloading...2443machine # [ 101.843773] systemd[621]: Reloading finished in 166 ms.2444machine # [ 101.848992] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2445machine # [ 101.859638] systemd[621]: Stopped Run user-specific NixOS activation.2446machine # [ 101.861483] systemd[621]: Stopping Run user-specific NixOS activation...2447machine # [ 101.866270] systemd[621]: Starting Run user-specific NixOS activation...2448machine # [ 101.907350] systemd[621]: Finished Run user-specific NixOS activation.2449machine # [ 101.912120] systemd[1]: Stopped target Reactivate sysinit units.2450machine # [ 101.914566] systemd[1]: Stopping Reactivate sysinit units...2451machine # [ 101.916349] systemd[1]: Reached target Reactivate sysinit units.2452machine # [ 101.922520] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2453machine # [ 101.925597] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2454machine # [ 101.931732] systemd[1]: Reached target Local File Systems.2455machine # [ 101.935849] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2456machine # [ 101.941099] systemd[1]: Starting Create SUID/SGID Wrappers...2457machine # [ 101.942672] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2458machine # [ 101.947253] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672459machine # [ 101.953430] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2460machine # [ 101.956291] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2461machine # [ 101.959284] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2462machine # [ 101.966380] systemd[1]: Reached target Remote File Systems.2463machine # [ 102.304641] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2464machine # [ 102.307312] systemd[1]: Finished Create SUID/SGID Wrappers.2465machine # [ 102.313522] systemd[1]: Starting linger-users.service...2466machine # [ 102.362825] systemd[1]: linger-users.service: Deactivated successfully.2467machine # [ 102.365491] systemd[1]: Finished linger-users.service.2468machine # [ 102.621462] nixos[4655]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2469machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 4.71 seconds)2470machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2471machine # [ 102.666483] sudo[4792]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2472machine # [ 102.681240] sudo[4792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2473machine # [ 102.692783] sudo[4792]: pam_unix(sudo:session): session closed for user usertest2474machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)2475machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2476machine # [ 102.731894] sudo[4798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service2477machine # [ 102.747642] sudo[4798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2478machine # [ 102.759495] sudo[4798]: pam_unix(sudo:session): session closed for user usertest2479machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)2480machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2481machine # [ 102.799572] sudo[4804]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2482machine # [ 102.814616] sudo[4804]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2483machine # [ 102.826199] sudo[4804]: pam_unix(sudo:session): session closed for user usertest2484machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)2485machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2486machine # [ 102.865121] sudo[4810]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2487machine # [ 102.879658] sudo[4810]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2488machine # [ 102.888971] systemd[621]: Reload requested from client PID 4815 ('systemctl')...2489machine # [ 102.893179] systemd[621]: Reloading...2490machine # [ 103.049837] systemd[621]: Reloading finished in 156 ms.2491machine # [ 103.055982] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2492machine # [ 103.065950] sudo[4810]: pam_unix(sudo:session): session closed for user usertest2493machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.24 seconds)2494machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2495machine # [ 103.116859] sudo[4820]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2496machine # [ 103.131765] sudo[4820]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2497machine # [ 103.148357] systemd[621]: Starting migrated.service...2498machine # [ 103.178323] systemd[621]: Finished migrated.service.2499machine # [ 103.182779] sudo[4820]: pam_unix(sudo:session): session closed for user usertest2500machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.12 seconds)2501machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2502machine # [ 103.229358] sudo[4828]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2503machine # [ 103.245902] sudo[4828]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2504machine # [ 103.257860] sudo[4828]: pam_unix(sudo:session): session closed for user usertest2505machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2506machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2507machine # [ 103.305192] sudo[4834]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2508machine # [ 103.321356] sudo[4834]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2509machine # [ 103.334799] sudo[4834]: pam_unix(sudo:session): session closed for user usertest2510machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2511machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2512machine # [ 103.382456] sudo[4840]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2513machine # [ 103.398295] sudo[4840]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2514machine # [ 103.417880] sudo[4840]: pam_unix(sudo:session): session closed for user usertest2515machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.08 seconds)2516machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12517machine # [ 103.980933] nixos[4849]: switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2518machine # [ 103.984980] systemd[1]: Stopped target Remote File Systems.2519machine # [ 103.987928] systemd[1]: Stopped target Local File Systems.2520machine # [ 104.415523] systemd[1]: Reload requested from client PID 4849 ('.switch-to-conf') (unit backdoor.service)...2521machine # [ 104.418432] systemd[1]: Reloading...2522machine # [ 104.939123] systemd[1]: Reloading finished in 518 ms.2523machine # [ 105.073714] systemd[621]: Reexecution requested from client PID 4918 ('switch-to-confi')...2524machine # [ 105.076292] systemd[621]: Reexecuting.2525machine # [ 105.099251] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2526machine # [ 105.227806] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2527machine # [ 105.234653] systemd[621]: Reload requested from client PID 4918 ('switch-to-confi')...2528machine # [ 105.237732] systemd[621]: Reloading...2529machine # [ 105.363322] systemd[621]: Reloading finished in 122 ms.2530machine # [ 105.367043] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2531machine # [ 105.378432] systemd[621]: Stopped Run user-specific NixOS activation.2532machine # [ 105.380144] systemd[621]: Stopping Run user-specific NixOS activation...2533machine # [ 105.383659] systemd[621]: Starting Run user-specific NixOS activation...2534machine # [ 105.414424] systemd[621]: Finished Run user-specific NixOS activation.2535machine # [ 105.417196] systemd[621]: Reload requested from client PID 4918 ('switch-to-confi')...2536machine # [ 105.420133] systemd[621]: Reloading...2537machine # [ 105.548855] systemd[621]: Reloading finished in 126 ms.2538machine # [ 105.555053] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2539machine # [ 105.569351] systemd[1]: Stopped target Reactivate sysinit units.2540machine # [ 105.572970] systemd[1]: Stopping Reactivate sysinit units...2541machine # [ 105.574599] systemd[1]: Reached target Reactivate sysinit units.2542machine # [ 105.576924] systemd[1]: Reached target Remote File Systems.2543machine # [ 105.582099] systemd[1]: Reached target Local File Systems.2544machine # [ 105.585418] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2545machine # [ 105.590307] systemd[1]: Starting Create SUID/SGID Wrappers...2546machine # [ 105.593603] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2547machine # [ 105.595799] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672548machine # [ 105.601409] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2549machine # [ 105.606220] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2550machine # [ 105.609199] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2551machine # [ 105.617723] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2552machine # [ 105.620101] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2553machine # [ 105.892688] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2554machine # [ 105.896337] systemd[1]: Finished Create SUID/SGID Wrappers.2555machine # [ 105.901587] systemd[1]: Starting linger-users.service...2556machine # [ 105.952359] systemd[1]: linger-users.service: Deactivated successfully.2557machine # [ 105.955092] systemd[1]: Finished linger-users.service.2558machine # [ 106.215499] nixos[4849]: finished switching to system configuration /nix/store/ivyqf056h7flhvcwlprdv294bf0prly9-nixos-system-machine-test2559machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.81 seconds)2560machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2561machine # [ 106.300720] sudo[4990]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2562machine # [ 106.317939] sudo[4990]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2563machine # [ 106.340330] sudo[4990]: pam_unix(sudo:session): session closed for user usertest2564machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.12 seconds)2565machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2566machine # [ 106.390794] sudo[4996]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2567machine # [ 106.407459] sudo[4996]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2568machine # [ 106.421221] sudo[4996]: pam_unix(sudo:session): session closed for user usertest2569machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2570machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2571machine # [ 106.473811] sudo[5002]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2572machine # [ 106.490620] sudo[5002]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2573machine # [ 106.504124] sudo[5002]: pam_unix(sudo:session): session closed for user usertest2574machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.08 seconds)2575machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2576machine # [ 106.554518] sudo[5008]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2577machine # [ 106.570725] sudo[5008]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2578machine # [ 106.580736] systemd[621]: Reload requested from client PID 5013 ('systemctl')...2579machine # [ 106.584108] systemd[621]: Reloading...2580machine # [ 106.763070] systemd[621]: Reloading finished in 177 ms.2581machine # [ 106.768990] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2582machine # [ 106.779260] sudo[5008]: pam_unix(sudo:session): session closed for user usertest2583machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.28 seconds)2584machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2585machine # [ 106.828889] sudo[5018]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2586machine # [ 106.846079] sudo[5018]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2587machine # [ 106.856824] systemd[621]: Stopped migrated.service.2588machine # [ 106.864415] sudo[5018]: pam_unix(sudo:session): session closed for user usertest2589machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.08 seconds)2590machine: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12591machine # [ 107.521869] nixos[5027]: switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2592machine # [ 107.525830] systemd[1]: Stopped target Local File Systems.2593machine # [ 107.528623] systemd[1]: Stopped target Remote File Systems.2594machine # [ 108.005934] systemd[1]: Reload requested from client PID 5027 ('.switch-to-conf') (unit backdoor.service)...2595machine # [ 108.008988] systemd[1]: Reloading...2596machine # [ 108.665942] systemd[1]: Reloading finished in 654 ms.2597machine # [ 108.813947] systemd[621]: Reexecution requested from client PID 5096 ('switch-to-confi')...2598machine # [ 108.818192] systemd[621]: Reexecuting.2599machine # [ 108.851687] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2600machine # [ 109.039114] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2601machine # [ 109.046968] systemd[621]: Reload requested from client PID 5096 ('switch-to-confi')...2602machine # [ 109.049866] systemd[621]: Reloading...2603machine # [ 109.226912] systemd[621]: Reloading finished in 175 ms.2604machine # [ 109.233047] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2605machine # [ 109.244106] systemd[621]: Stopped Run user-specific NixOS activation.2606machine # [ 109.247262] systemd[621]: Stopping Run user-specific NixOS activation...2607machine # [ 109.251199] systemd[621]: Starting Run user-specific NixOS activation...2608machine # [ 109.295568] systemd[621]: Finished Run user-specific NixOS activation.2609machine # [ 109.300600] systemd[1]: Stopped target Reactivate sysinit units.2610machine # [ 109.303064] systemd[1]: Stopping Reactivate sysinit units...2611machine # [ 109.305355] systemd[1]: Reached target Reactivate sysinit units.2612machine # [ 109.313861] systemd[1]: Reached target Remote File Systems.2613machine # [ 109.317807] systemd[1]: Starting linger-users.service...2614machine # [ 109.324745] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2615machine # [ 109.327245] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2616machine # [ 109.336168] systemd[1]: Reached target Local File Systems.2617machine # [ 109.338292] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2618machine # [ 109.342515] systemd[1]: Starting Create SUID/SGID Wrappers...2619machine # [ 109.346381] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2620machine # [ 109.350546] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672621machine # [ 109.358927] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2622machine # [ 109.362780] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2623machine # [ 109.366322] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2624machine # [ 109.420836] systemd[1]: linger-users.service: Deactivated successfully.2625machine # [ 109.423864] systemd[1]: Finished linger-users.service.2626machine # [ 109.688477] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2627machine # [ 109.691495] systemd[1]: Finished Create SUID/SGID Wrappers.2628machine # [ 109.951728] nixos[5027]: finished switching to system configuration /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test2629machine: (finished: must succeed: /nix/store/62zvy8v1nbn2xhqynbpd88hj4s6rb0nc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.10 seconds)2630(finished: subtest: user services, in 81.22 seconds)2631(finished: run the VM test script, in 111.16 seconds)2632test script finished in 111.33s2633cleanup2634kill QemuMachine (pid 45)2635machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2636(finished: cleanup, in 0.14 seconds)2637additionally exposed symbols:2638 machine, other,2639 vlan1,2640 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