vm-test-run-switch-test-user
checks.x86_64-linux.switchTest-user
· build #21
· 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.BnEEUD0la1', 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: 34a6b496-c3f9-4684-ae1e-e673c615f02617machine # 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)... ok[ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Aug 28 06:22:54 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/init regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-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.000000] kvm-clock: using sched offset of 470585301 cycles62machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns63machine # [ 0.000005] tsc: Detected 2400.010 MHz processor64machine # [ 0.000809] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000836] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000839] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002742] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002753] Using GB pages for direct mapping69machine # [ 0.002803] RAMDISK: [mem 0x3e429000-0x3ffcffff]70machine # [ 0.002813] ACPI: Early table checksum verification disabled71machine # [ 0.002815] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002819] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002822] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002829] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002831] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002832] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002834] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002836] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002837] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002839] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002840] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002840] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002841] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002841] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002841] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002842] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003069] No NUMA configuration found88machine # [ 0.003069] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003072] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.005299] Zone ranges:91machine # [ 0.005300] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.005301] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.005303] Normal empty94machine # [ 0.005303] Device empty95machine # [ 0.005304] Movable zone start for each node96machine # [ 0.005305] Early memory node ranges97machine # [ 0.005305] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.005306] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.005307] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.005327] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.005574] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.022179] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.022637] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.022647] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.022674] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.022677] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.022678] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.022679] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.022680] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.022681] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.022683] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.022685] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.022687] TSC deadline timer available114machine # [ 0.022691] CPU topo: Max. logical packages: 1115machine # [ 0.022692] CPU topo: Max. logical dies: 1116machine # [ 0.022692] CPU topo: Max. dies per package: 1117machine # [ 0.022695] CPU topo: Max. threads per core: 1118machine # [ 0.022696] CPU topo: Num. cores per package: 1119machine # [ 0.022696] CPU topo: Num. threads per package: 1120machine # [ 0.022697] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.022712] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.022742] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.022744] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.022745] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.022746] Booting paravirtualized kernel on KVM126machine # [ 0.022749] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.027179] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.029259] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.029299] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.029301] 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/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/init regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.029390] Unknown kernel command line parameters "regInfo=/nix/store/gaaawqkmab3c0q4xif2dqiwb4az1kjp6-closure-info/registration", will be passed to user space.132machine # [ 0.029403] random: crng init done133machine # [ 0.029404] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.029425] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.029437] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.029466] Fallback order for Node 0: 0137machine # [ 0.029469] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.029470] Policy zone: DMA32139machine # [ 0.031801] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.034120] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.036362] allocated 2097152 bytes of page_ext142machine # [ 0.045443] ftrace: allocating 48733 entries in 192 pages143machine # [ 0.045445] ftrace: allocated 192 pages with 2 groups144machine # [ 0.046240] Dynamic Preempt: lazy145machine # [ 0.046409] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.046410] rcu: RCU event tracing is enabled.147machine # [ 0.046411] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.046412] Trampoline variant of Tasks RCU enabled.149machine # [ 0.046412] Rude variant of Tasks RCU enabled.150machine # [ 0.046413] Tracing variant of Tasks RCU enabled.151machine # [ 0.046413] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.046414] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.046434] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.046436] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.046436] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.051074] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.051560] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.051567] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.051670] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.056059] Console: colour VGA+ 80x25161machine # [ 0.056062] printk: legacy console [tty0] enabled162machine # [ 0.085235] printk: legacy console [ttyS0] enabled163machine # [ 0.194092] ACPI: Core revision 20250807164machine # [ 0.195219] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.197262] APIC: Switch to symmetric I/O mode setup166machine # [ 0.198565] x2apic enabled167machine # [ 0.199509] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.201827] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.202914] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns170machine # [ 0.204669] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)171machine # [ 0.205752] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.207755] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.208666] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.209669] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.210666] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.211666] Transient Scheduler Attacks: Vulnerable: No microcode177machine # [ 0.212665] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.213666] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.214665] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.216675] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.217667] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.218666] active return thunk: srso_alias_return_thunk183machine # [ 0.220370] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.221666] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.222666] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.223667] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.224666] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.225666] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.226666] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.227667] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256191machine # [ 0.229457] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64192machine # [ 0.230453] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512193machine # [ 0.231445] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024194machine # [ 0.232473] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8195machine # [ 0.233457] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.196machine # [ 0.268599] Freeing SMP alternatives memory: 44K197machine # [ 0.269252] pid_max: default: 32768 minimum: 301198machine # [ 0.269753] LSM: initializing lsm=capability,landlock,yama,bpf,ima199machine # [ 0.270775] landlock: Up and running.200machine # [ 0.272137] Yama: becoming mindful.201machine # [ 0.272889] LSM support for eBPF active202machine # [ 0.273811] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)203machine # [ 0.275692] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204machine # [ 0.277868] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)205machine # [ 0.279206] Performance Events: Fam17h+ core perfctr, AMD PMU driver.206machine # [ 0.279674] ... version: 2207machine # [ 0.280429] ... bit width: 48208machine # [ 0.280669] ... generic counters: 6209machine # [ 0.281434] ... generic bitmap: 000000000000003f210machine # [ 0.281668] ... fixed-purpose counters: 0211machine # [ 0.282383] ... fixed-purpose bitmap: 0000000000000000212machine # [ 0.282668] ... value mask: 0000ffffffffffff213machine # [ 0.283629] ... max period: 00007fffffffffff214machine # [ 0.284412] ... global_ctrl mask: 000000000000003f215machine # [ 0.284765] signal: max sigframe size: 3376216machine # [ 0.285639] rcu: Hierarchical SRCU implementation.217machine # [ 0.286325] rcu: Max phase no-delay instances is 400.218machine # [ 0.291123] smp: Bringing up secondary CPUs ...219machine # [ 0.291682] smp: Brought up 1 node, 1 CPU220machine # [ 0.292451] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)221machine # [ 0.293709] Memory: 941792K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 98840K reserved, 0K cma-reserved)222machine # [ 0.294885] devtmpfs: initialized223machine # [ 0.295656] x86/mm: Memory block size: 128MB224machine # [ 0.297156] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)225machine # [ 0.297699] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).226machine # [ 0.298778] pinctrl core: initialized pinctrl subsystem227machine # [ 0.299948] PM: RTC time: 02:38:38, date: 2026-09-04228machine # [ 0.303808] NET: Registered PF_NETLINK/PF_ROUTE protocol family229machine # [ 0.305058] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations230machine # [ 0.305686] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations231machine # [ 0.306804] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations232machine # [ 0.307680] audit: initializing netlink subsys (disabled)233machine # [ 0.308844] thermal_sys: Registered thermal governor 'fair_share'234machine # [ 0.308846] thermal_sys: Registered thermal governor 'bang_bang'235machine # [ 0.309671] audit: type=2000 audit(1788489519.153:1): state=initialized audit_enabled=0 res=1236machine # [ 0.311669] thermal_sys: Registered thermal governor 'step_wise'237machine # [ 0.311670] thermal_sys: Registered thermal governor 'user_space'238machine # [ 0.312668] thermal_sys: Registered thermal governor 'power_allocator'239machine # [ 0.313686] cpuidle: using governor menu240machine # [ 0.315941] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5241machine # [ 0.316898] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]242machine # [ 0.317671] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry243machine # [ 0.318681] PCI: Using configuration type 1 for base access244machine # [ 0.319813] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.245machine # [ 0.324893] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages246machine # [ 0.325669] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page247machine # [ 0.330669] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages248machine # [ 0.331668] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page249machine # [ 0.342051] ACPI: Added _OSI(Module Device)250machine # [ 0.342670] ACPI: Added _OSI(Processor Device)251machine # [ 0.343450] ACPI: Added _OSI(Processor Aggregator Device)252machine # [ 0.352065] ACPI: 1 ACPI AML tables successfully acquired and loaded253machine # [ 0.356082] ACPI: Interpreter enabled254machine # [ 0.356683] ACPI: PM: (supports S0 S3 S4 S5)255machine # [ 0.357436] ACPI: Using IOAPIC for interrupt routing256machine # [ 0.359757] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug257machine # [ 0.360671] PCI: Using E820 reservations for host bridge windows258machine # [ 0.363818] ACPI: Enabled 2 GPEs in block 00 to 3F259machine # [ 0.371422] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])260machine # [ 0.371675] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]261machine # [ 0.372748] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]262machine # [ 0.373790] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]263machine # [ 0.375025] PCI host bridge to bus 0000:00264machine # [ 0.375673] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]265machine # [ 0.376668] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]266machine # [ 0.377668] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]267machine # [ 0.378668] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]268machine # [ 0.379668] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]269machine # [ 0.380668] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]270machine # [ 0.381669] pci_bus 0000:00: root bus resource [bus 00-ff]271machine # [ 0.382724] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint272machine # [ 0.384112] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint273machine # [ 0.386706] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]274machine # [ 0.387681] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]275machine # [ 0.388668] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]276machine # [ 0.389989] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]277machine # [ 0.391526] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint278machine # [ 0.393676] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]279machine # [ 0.394571] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]280machine # [ 0.395452] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]281machine # [ 0.396565] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]282machine # [ 0.398025] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint283machine # [ 0.400651] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]284machine # [ 0.401348] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]285machine # [ 0.401690] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]286machine # [ 0.403230] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287machine # [ 0.405441] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]288machine # [ 0.405675] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]289machine # [ 0.406679] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]290machine # [ 0.408227] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.409675] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]292machine # [ 0.410571] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]293machine # [ 0.411463] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]294machine # [ 0.413140] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.414872] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]296machine # [ 0.415675] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]297machine # [ 0.416684] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]298machine # [ 0.418250] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint299machine # [ 0.419690] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]300machine # [ 0.420571] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]301machine # [ 0.421444] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]302machine # [ 0.423192] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint303machine # [ 0.425676] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]304machine # [ 0.426567] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]305machine # [ 0.427453] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]306machine # [ 0.428559] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]307machine # [ 0.430039] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint308machine # [ 0.431680] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]309machine # [ 0.432690] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]310machine # [ 0.434215] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint311machine # [ 0.435692] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]312machine # [ 0.436582] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]313machine # [ 0.437474] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]314machine # [ 0.439118] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint315machine # [ 0.440892] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]316machine # [ 0.441675] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]317machine # [ 0.442614] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]318machine # [ 0.444049] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint319machine # [ 0.445201] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]320machine # [ 0.445883] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint321machine # [ 0.447223] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]322machine # [ 0.447859] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.451173] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]324machine # [ 0.451927] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint325machine # [ 0.453552] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]326machine # [ 0.454653] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint327machine # [ 0.455937] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO328machine # [ 0.456919] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint329machine # [ 0.459157] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]330machine # [ 0.459675] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]331machine # [ 0.460932] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint332machine # [ 0.462301] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]333machine # [ 0.465628] ACPI: PCI: Interrupt link LNKA configured for IRQ 10334machine # [ 0.466498] ACPI: PCI: Interrupt link LNKB configured for IRQ 10335machine # [ 0.467761] ACPI: PCI: Interrupt link LNKC configured for IRQ 11336machine # [ 0.468757] ACPI: PCI: Interrupt link LNKD configured for IRQ 11337machine # [ 0.469768] ACPI: PCI: Interrupt link LNKE configured for IRQ 10338machine # [ 0.470764] ACPI: PCI: Interrupt link LNKF configured for IRQ 10339machine # [ 0.471771] ACPI: PCI: Interrupt link LNKG configured for IRQ 11340machine # [ 0.472765] ACPI: PCI: Interrupt link LNKH configured for IRQ 11341machine # [ 0.473707] ACPI: PCI: Interrupt link GSIA configured for IRQ 16342machine # [ 0.474675] ACPI: PCI: Interrupt link GSIB configured for IRQ 17343machine # [ 0.475625] ACPI: PCI: Interrupt link GSIC configured for IRQ 18344machine # [ 0.476400] ACPI: PCI: Interrupt link GSID configured for IRQ 19345machine # [ 0.476684] ACPI: PCI: Interrupt link GSIE configured for IRQ 20346machine # [ 0.477687] ACPI: PCI: Interrupt link GSIF configured for IRQ 21347machine # [ 0.478645] ACPI: PCI: Interrupt link GSIG configured for IRQ 22348machine # [ 0.479436] ACPI: PCI: Interrupt link GSIH configured for IRQ 23349machine # [ 0.480740] iommu: Default domain type: Translated350machine # [ 0.481503] iommu: DMA domain TLB invalidation policy: lazy mode351machine # [ 0.482652] ACPI: bus type USB registered352machine # [ 0.483197] usbcore: registered new interface driver usbfs353machine # [ 0.483686] usbcore: registered new interface driver hub354machine # [ 0.484544] usbcore: registered new device driver usb355machine # [ 0.486268] NetLabel: Initializing356machine # [ 0.486688] NetLabel: domain hash size = 128357machine # [ 0.487397] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO358machine # [ 0.487745] NetLabel: unlabeled traffic allowed by default359machine # [ 0.488631] PCI: Using ACPI for IRQ routing360machine # [ 0.533940] pci 0000:00:01.0: vgaarb: setting as boot VGA device361machine # [ 0.534664] pci 0000:00:01.0: vgaarb: bridge control possible362machine # [ 0.534664] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none363machine # [ 0.534673] vgaarb: loaded364machine # [ 0.535410] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0365machine # [ 0.535668] hpet0: 3 comparators, 64-bit 100.000000 MHz counter366machine # [ 0.539759] clocksource: Switched to clocksource kvm-clock367machine # [ 0.543145] VFS: Disk quotas dquot_6.6.0368machine # [ 0.543828] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)369machine # [ 0.545177] pnp: PnP ACPI init370machine # [ 0.545947] ACPI: IRQ 4 override to edge(!), high(!)371machine # [ 0.546913] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved372machine # [ 0.548453] pnp: PnP ACPI: found 6 devices373machine # [ 0.555974] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns374machine # [ 0.557440] clocksource: Switched to clocksource acpi_pm375machine # [ 0.558499] NET: Registered PF_INET protocol family376machine # [ 0.559590] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)377machine # [ 0.576600] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)378machine # [ 0.578235] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)379machine # [ 0.579595] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)380machine # [ 0.580950] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)381machine # [ 0.582276] TCP: Hash tables configured (established 8192 bind 8192)382machine # [ 0.583455] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)383machine # [ 0.584802] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)384machine # [ 0.585970] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)385machine # [ 0.587286] NET: Registered PF_UNIX/PF_LOCAL protocol family386machine # [ 0.588316] NET: Registered PF_XDP protocol family387machine # [ 0.589178] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]388machine # [ 0.590218] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]389machine # [ 0.591287] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]390machine # [ 0.592461] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]391machine # [ 0.593626] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]392machine # [ 0.594812] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]393machine # [ 0.596638] ACPI: \_SB_.GSIA: Enabled at IRQ 16394machine # [ 0.598979] ACPI: \_SB_.GSIB: Enabled at IRQ 17395machine # [ 0.601009] ACPI: \_SB_.GSIC: Enabled at IRQ 18396machine # [ 0.603019] ACPI: \_SB_.GSID: Enabled at IRQ 19397machine # [ 0.604857] PCI: CLS 0 bytes, default 64398machine # [ 0.605808] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns399machine # [ 0.607664] Trying to unpack rootfs image as initramfs...400machine # [ 0.653554] Initialise system trusted keyrings401machine # [ 0.654582] workingset: timestamp_bits=40 max_order=18 bucket_order=0402machine # [ 0.678433] Key type asymmetric registered403machine # [ 0.679156] Asymmetric key parser 'x509' registered404machine # [ 0.679966] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)405machine # [ 0.683281] io scheduler mq-deadline registered406machine # [ 0.684014] io scheduler kyber registered407machine # [ 0.687660] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled408machine # [ 0.688906] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A409machine # [ 0.692908] Linux agpgart interface v0.103410machine # [ 0.693678] ACPI: bus type drm_connector registered411machine # [ 0.697605] usbcore: registered new interface driver usbserial_generic412machine # [ 0.698651] usbserial: USB Serial support registered for generic413machine # [ 0.702133] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.414machine # [ 0.702133] Please enable it if your BIOS has the CPPC option.415machine # [ 0.704316] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled416machine # [ 0.708255] drop_monitor: Initializing network drop monitor service417machine # [ 0.709421] NET: Registered PF_INET6 protocol family418machine # [ 0.712570] Segment Routing with IPv6419machine # [ 0.715133] In-situ OAM (IOAM) with IPv6420machine # [ 0.716206] IPI shorthand broadcast: enabled421machine # [ 0.724670] sched_clock: Marking stable (577014299, 147098042)->(797080379, -72968038)422machine # [ 0.730232] registered taskstats version 1423machine # [ 0.731175] Loading compiled-in X.509 certificates424machine # [ 0.747586] Demotion targets for Node 0: null425machine # [ 0.750168] Key type .fscrypt registered426machine # [ 0.750816] Key type fscrypt-provisioning registered427machine # [ 0.751729] ima: No TPM chip found, activating TPM-bypass!428machine # [ 0.754131] ima: Allocated hash algorithm: sha1429machine # [ 0.754905] ima: No architecture policies found430machine # [ 0.759130] PM: Magic number: 10:109:611431machine # [ 0.760691] RAS: Correctable Errors collector initialized.432machine # [ 0.768793] clk: Disabling unused clocks433machine # [ 0.771136] PM: genpd: Disabling unused power domains434machine # [ 0.928367] Freeing initrd memory: 28316K435machine # [ 0.931386] Freeing unused decrypted memory: 2028K436machine # [ 0.934020] Freeing unused kernel image (initmem) memory: 3644K437machine # [ 0.935048] Write protecting the kernel read-only data: 32768k438machine # [ 0.936851] Freeing unused kernel image (text/rodata gap) memory: 1216K439machine # [ 0.938320] Freeing unused kernel image (rodata/data gap) memory: 756K440machine # [ 0.990172] x86/mm: Checked W+X mappings: passed, no W+X pages found.441machine # [ 0.991329] Run /init as init process442machine # [ 1.002184] systemd[1]: Inserted module 'autofs4'443machine # [ 1.018624] fuse: init (API version 7.45)444machine # [ 1.024375] ACPI: \_SB_.GSIG: Enabled at IRQ 22445machine # [ 1.026720] ACPI: \_SB_.GSIH: Enabled at IRQ 23446machine # [ 1.030017] ACPI: \_SB_.GSIE: Enabled at IRQ 20447machine # [ 1.032703] ACPI: \_SB_.GSIF: Enabled at IRQ 21448machine # [ 1.062322] systemd[1]: Successfully made /usr/ read-only.449machine # [ 1.398947] systemd[1]: systemd 262-175b52e 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)450machine # [ 1.411152] systemd[1]: Detected virtualization kvm.451machine # [ 1.413277] systemd[1]: Detected architecture x86-64.452machine # [ 1.415444] systemd[1]: Running in initrd.453machine # [ 1.418012] systemd[1]: Initializing machine ID from random generator.454machine # [ 1.420963] systemd[1]: Hostname set to <machine>.455machine # [ 1.512492] systemd[1]: bpf-restrict-fs: LSM BPF program attached456machine # [ 1.550650] systemd[1]: Queued start job for default target Initrd Default Target.457machine # [ 1.554486] systemd[1]: Created slice Slice /system/modprobe.458machine # [ 1.555697] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459machine # [ 1.557155] systemd[1]: Expecting device /dev/disk/by-label/nixos...460machine # [ 1.558279] systemd[1]: Reached target Path Units.461machine # [ 1.559150] systemd[1]: Reached target Slice Units.462machine # [ 1.560019] systemd[1]: Reached target Swaps.463machine # [ 1.560921] systemd[1]: Reached target Timer Units.464machine # [ 1.561962] systemd[1]: Listening on D-Bus System Message Bus Socket.465machine # [ 1.563250] systemd[1]: Listening on Journal Socket (/dev/log).466machine # [ 1.564457] systemd[1]: Listening on Journal Sockets.467machine # [ 1.565471] systemd[1]: Listening on udev Kernel Socket.468machine # [ 1.566457] systemd[1]: Reached target Socket Units.469machine # [ 1.569195] systemd[1]: Starting Create List of Static Device Nodes...470machine # [ 1.572003] systemd[1]: Starting Load Kernel Module 9pnet_virtio...471machine # [ 1.581203] systemd[1]: Starting Load Kernel Module configfs...472machine # [ 1.593696] systemd[1]: Starting Journal Service...473machine # [ 1.604234] systemd[1]: Starting Load Kernel Modules...474machine # [ 1.611205] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os475machine # [ 1.631457] netfs: FS-Cache loaded476machine # [ 1.636264] 9pnet: Installing 9P2000 support477machine # [ 1.646206] systemd[1]: Starting Coldplug All udev Devices...478machine # [ 1.663314] systemd[1]: Finished Create List of Static Device Nodes.479machine # [ 1.668961] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.480machine # [ 1.679553] systemd[1]: Finished Load Kernel Module 9pnet_virtio.481machine # [ 1.684657] systemd[1]: modprobe@configfs.service: Deactivated successfully.482machine # [ 1.689169] systemd-journald[67]: Collecting audit messages is disabled.483machine # [ 1.691490] systemd[1]: Finished Load Kernel Module configfs.484machine # [ 1.697589] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config485machine # [ 1.703419] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.486machine # [ 1.709193] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...487machine # [ 1.711153] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev488machine # [ 1.740660] systemd[1]: Finished Load Kernel Modules.489machine # [ 1.747321] systemd[1]: Starting Apply Kernel Variables...490machine # [ 1.759068] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491machine # [ 1.769328] systemd[1]: Starting Create Static Device Nodes in /dev...492machine # [ 1.782361] systemd[1]: Finished Apply Kernel Variables.493machine # [ 1.800704] systemd[1]: Finished Create Static Device Nodes in /dev.494machine # [ 1.655211] systemd-modules-load[68]: Inserted module 'dm_mod'495machine # [ 1.657380] systemd-modules-load[68]: Inserted module 'virtio_balloon'496machine # [ 1.805580] systemd[1]: Started Journal Service.497machine # [ 1.659390] systemd-modules-load[68]: Inserted module 'virtio_gpu'498machine # [ 1.669108] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 1.671079] systemd[1]: Reached target Local File Systems.500machine # [ 1.674063] systemd[1]: Starting Create System Files and Directories...501machine # [ 1.679080] systemd[1]: Starting Rule-based Manager for Device Events and Files...502machine # [ 1.704207] systemd[1]: Finished Create System Files and Directories.503machine # [ 1.727363] systemd-udevd[83]: Using default interface naming scheme 'v261'.504machine # [ 1.753118] systemd[1]: Started Rule-based Manager for Device Events and Files.505machine # [ 1.797099] systemd[1]: Finished Coldplug All udev Devices.506machine # [ 1.797886] systemd[1]: Reached target System Initialization.507machine # [ 1.798744] systemd[1]: Reached target Basic System.508machine # [ 2.123448] virtio_blk virtio5: 1/0/0 default/read/poll queues509machine # [ 2.128205] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12510machine # [ 2.145793] serio: i8042 KBD port at 0x60,0x64 irq 1511machine # [ 2.151186] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)512machine # [ 2.156276] serio: i8042 AUX port at 0x60,0x64 irq 12513machine # [ 2.170282] ehci-pci 0000:00:1d.7: EHCI Host Controller514machine # [ 2.171196] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1515machine # [ 2.173065] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000516machine # [ 2.181147] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00517machine # [ 2.184030] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18518machine # [ 2.187121] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1519machine # [ 2.188818] usb usb1: Product: EHCI Host Controller520machine # [ 2.190113] usb usb1: Manufacturer: Linux 6.18.48 ehci_hcd521machine # [ 2.190835] usb usb1: SerialNumber: 0000:00:1d.7522machine # [ 2.193313] hub 1-0:1.0: USB hub found523machine # [ 2.194966] hub 1-0:1.0: 6 ports detected524machine # [ 2.199993] uhci_hcd 0000:00:1d.0: UHCI Host Controller525machine # [ 2.200793] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2526machine # [ 2.214270] uhci_hcd 0000:00:1d.0: detected 2 ports527machine # [ 2.215064] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200528machine # [ 2.228788] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18529machine # [ 2.229963] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.243851] usb usb2: Product: UHCI Host Controller531machine # [ 2.248244] SCSI subsystem initialized532machine # [ 2.254687] usb usb2: Manufacturer: Linux 6.18.48 uhci_hcd533machine # [ 2.113407] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line.534machine # [ 2.263124] usb usb2: SerialNumber: 0000:00:1d.0535machine # [ 2.119072] systemd[1]: Starting Virtual Console Setup...536machine # [ 2.267853] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0537machine # [ 2.129827] (udev-worker)[93]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.538machine # [ 2.281843] hub 2-0:1.0: USB hub found539machine # [ 2.287421] hub 2-0:1.0: 2 ports detected540machine # [ 2.143169] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.541machine # [ 2.160974] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.542machine # [ 2.162669] systemd[1]: Finished Virtual Console Setup.543machine # [ 2.324499] uhci_hcd 0000:00:1d.1: UHCI Host Controller544machine # [ 2.325241] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3545machine # [ 2.332867] uhci_hcd 0000:00:1d.1: detected 2 ports546machine # [ 2.336239] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220547machine # [ 2.340809] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18548machine # [ 2.341966] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1549machine # [ 2.202076] systemd[1]: Found device /dev/disk/by-label/nixos.550machine # [ 2.202961] systemd[1]: Reached target Initrd Root Device.551machine # [ 2.350851] usb usb3: Product: UHCI Host Controller552machine # [ 2.205107] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...553machine # [ 2.353339] usb usb3: Manufacturer: Linux 6.18.48 uhci_hcd554machine # [ 2.354070] usb usb3: SerialNumber: 0000:00:1d.1555machine # [ 2.360535] hub 3-0:1.0: USB hub found556machine # [ 2.361535] hub 3-0:1.0: 2 ports detected557machine # [ 2.370153] uhci_hcd 0000:00:1d.2: UHCI Host Controller558machine # [ 2.370867] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4559machine # [ 2.373139] uhci_hcd 0000:00:1d.2: detected 2 ports560machine # [ 2.373958] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240561machine # [ 2.383006] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18562machine # [ 2.384886] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1563machine # [ 2.387153] usb usb4: Product: UHCI Host Controller564machine # [ 2.387883] usb usb4: Manufacturer: Linux 6.18.48 uhci_hcd565machine # [ 2.390737] usb usb4: SerialNumber: 0000:00:1d.2566machine # [ 2.396676] hub 4-0:1.0: USB hub found567machine # [ 2.398302] hub 4-0:1.0: 2 ports detected568machine # [ 2.252161] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks569machine # [ 2.258990] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.570machine # [ 2.409240] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode571machine # [ 2.418279] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)572machine # [ 2.423629] ahci 0000:00:1f.2: flags: 64bit ncq only573machine # [ 2.431131] usb 1-1: new high-speed USB device number 2 using ehci-pci574machine # [ 2.437738] scsi host0: ahci575machine # [ 2.438629] scsi host1: ahci576machine # [ 2.442802] scsi host2: ahci577machine # [ 2.444832] scsi host3: ahci578machine # [ 2.448076] scsi host4: ahci579machine # [ 2.450128] scsi host5: ahci580machine # [ 2.450711] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1581machine # [ 2.455130] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1582machine # [ 2.458338] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1583machine # [ 2.459635] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1584machine # [ 2.460816] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1585machine # [ 2.461970] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1586machine # [ 2.562498] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00587machine # [ 2.565417] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10588machine # [ 2.568839] usb 1-1: Product: QEMU USB Tablet589machine # [ 2.570785] usb 1-1: Manufacturer: QEMU590machine # [ 2.572813] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1591machine # [ 2.606830] hid: raw HID events driver (C) Jiri Kosina592machine # [ 2.509958] systemd[1]: Mounting /sysroot...593machine # [ 2.774758] ata4: SATA link down (SStatus 0 SControl 300)594machine # [ 2.777064] ata5: SATA link down (SStatus 0 SControl 300)595machine # [ 2.779642] ata6: SATA link down (SStatus 0 SControl 300)596machine # [ 2.782000] ata2: SATA link down (SStatus 0 SControl 300)597machine # [ 2.784543] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)598machine # [ 2.787030] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100599machine # [ 2.789408] ata3.00: applying bridge limits600machine # [ 2.791427] ata1: SATA link down (SStatus 0 SControl 300)601machine # [ 2.793579] ata3.00: configured for UDMA/100602machine # [ 2.796007] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5603machine # [ 2.867943] usbcore: registered new interface driver usbhid604machine # [ 2.874106] usbhid: USB HID core driver605machine # [ 2.891501] 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/input2606machine # [ 2.893257] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0607machine # [ 2.903425] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray608machine # [ 2.912967] EXT4-fs (vda): mounted filesystem 34a6b496-c3f9-4684-ae1e-e673c615f026 r/w with ordered data mode. Quota mode: none.609machine # [ 2.772272] systemd[1]: Mounted /sysroot.610machine # [ 2.772917] systemd[1]: Reached target Initrd Root File System.611machine # [ 2.920862] cdrom: Uniform CD-ROM driver Revision: 3.20612machine # [ 2.776358] systemd[1]: Starting Mountpoints Configured in the Real Root...613machine # [ 2.795504] systemd-sysroot-fstab-check[139]: /sysroot should be mounted in the initrd, will request daemon-reload.614machine # [ 2.798809] systemd[1]: Reload requested from client PID 139 ('systemd-sysroot') (unit initrd-parse-etc.service)...615machine # [ 2.801158] systemd[1]: Reloading...616machine # [ 2.880043] systemd[1]: Reloading finished in 79 ms.617machine # [ 2.890642] systemd-sysroot-fstab-check[139]: Requesting initrd-fs.target/start/replace...618machine # [ 2.892967] systemd-sysroot-fstab-check[139]: Requesting swap.target/start/replace...619machine # [ 2.898499] systemd[1]: initrd-parse-etc.service: Deactivated successfully.620machine # [ 2.899910] systemd[1]: Finished Mountpoints Configured in the Real Root.621machine # [ 2.901175] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.622machine # [ 2.903542] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio623machine # [ 3.512185] systemd[1]: Mounting /sysroot/nix/.ro-store...624machine # [ 3.517322] systemd[1]: Mounting /sysroot/nix/.rw-store...625machine # [ 3.526335] systemd[1]: Mounting /sysroot/run...626machine # [ 3.535222] systemd[1]: Mounting /sysroot/tmp/shared...627machine # [ 3.547698] systemd[1]: Mounting /sysroot/tmp/xchg...628machine # [ 3.734758] 9p: Installing v9fs 9p2000 file system support629machine # [ 3.590219] systemd[1]: Mounted /sysroot/nix/.rw-store.630machine # [ 3.597954] systemd[1]: Starting rw-sysroot-nix-store.service...631machine # [ 3.603448] systemd[1]: Mounted /sysroot/nix/.ro-store.632machine # [ 3.604247] systemd[1]: Mounted /sysroot/run.633machine # [ 3.606882] systemd[1]: Mounted /sysroot/tmp/shared.634machine # [ 3.608160] systemd[1]: Mounted /sysroot/tmp/xchg.635machine # [ 3.617289] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.636machine # [ 3.619654] systemd[1]: Finished rw-sysroot-nix-store.service.637machine # [ 3.620714] systemd[1]: Mounting /sysroot/nix/store...638machine # [ 3.641495] systemd[1]: Mounted /sysroot/nix/store.639machine # [ 3.643072] systemd[1]: Reached target Initrd File Systems.640machine # [ 3.644740] systemd[1]: Starting Find NixOS closure...641machine # [ 3.648354] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...642machine # [ 3.665939] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.643machine # [ 3.674133] systemd[1]: Finished Find NixOS closure.644machine # [ 3.675444] systemd[1]: Reached target Initrd Default Target.645machine # [ 3.677476] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...646machine # [ 3.691319] systemd[1]: Stopped target Initrd Default Target.647machine # [ 3.692566] systemd[1]: Stopped target Basic System.648machine # [ 3.693533] systemd[1]: Stopped target Initrd Root Device.649machine # [ 3.695064] systemd[1]: Stopped target Path Units.650machine # [ 3.695869] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.651machine # [ 3.697052] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.652machine # [ 3.698087] systemd[1]: Stopped target Slice Units.653machine # [ 3.699507] systemd[1]: Stopped target Socket Units.654machine # [ 3.700599] systemd[1]: Stopped target System Initialization.655machine # [ 3.701722] systemd[1]: Stopped target Swaps.656machine # [ 3.702607] systemd[1]: Stopped target Timer Units.657machine # [ 3.703665] systemd[1]: dbus.socket: Deactivated successfully.658machine # [ 3.705195] systemd[1]: Closed D-Bus System Message Bus Socket.659machine # [ 3.705991] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.660machine # [ 3.707078] systemd[1]: Stopped Find NixOS closure.661machine # [ 3.708108] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio662machine # [ 3.710142] systemd[1]: Starting rw-sysroot-nix-store.service...663machine # [ 3.711105] systemd[1]: systemd-sysctl.service: Deactivated successfully.664machine # [ 3.712193] systemd[1]: Stopped Apply Kernel Variables.665machine # [ 3.713498] systemd[1]: systemd-modules-load.service: Deactivated successfully.666machine # [ 3.715180] systemd[1]: Stopped Load Kernel Modules.667machine # [ 3.716119] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.668machine # [ 3.717364] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.669machine # [ 3.718610] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.670machine # [ 3.720081] systemd[1]: Stopped Create System Files and Directories.671machine # [ 3.721251] systemd[1]: Stopped target Local File Systems.672machine # [ 3.722416] systemd[1]: Stopped target Preparation for Local File Systems.673machine # [ 3.724522] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.674machine # [ 3.725514] systemd[1]: Stopped Coldplug All udev Devices.675machine # [ 3.727451] systemd[1]: Stopping Rule-based Manager for Device Events and Files...676machine # [ 3.728466] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.677machine # [ 3.729438] systemd[1]: Stopped Virtual Console Setup.678machine # [ 3.736832] systemd[1]: initrd-cleanup.service: Deactivated successfully.679machine # [ 3.741212] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.680machine # [ 3.742109] systemd[1]: systemd-udevd.service: Deactivated successfully.681machine # [ 3.744151] systemd[1]: Stopped Rule-based Manager for Device Events and Files.682machine # [ 3.751092] systemd[1]: Starting Cleanup udev Database...683machine # [ 3.751938] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.684machine # [ 3.754150] systemd[1]: Stopped Create Static Device Nodes in /dev.685machine # [ 3.755162] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.686machine # [ 3.757169] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.687machine # [ 3.758166] systemd[1]: kmod-static-nodes.service: Deactivated successfully.688machine # [ 3.759149] systemd[1]: Stopped Create List of Static Device Nodes.689machine # [ 3.759967] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.690machine # [ 3.762130] systemd[1]: Finished rw-sysroot-nix-store.service.691machine # [ 3.776892] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.692machine # [ 3.778616] systemd[1]: Finished Cleanup udev Database.693machine # [ 3.779900] systemd[1]: Reached target Switch Root.694machine # [ 3.781766] systemd[1]: Starting NixOS Activation...695machine # [ 3.896690] initrd-nixos-activation-start[192]: booting system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test696machine # [ 3.944460] initrd-nixos-activation-start[192]: running activation script...697machine # [ 4.328921] initrd-nixos-activation-start[215]: setting up /etc...698machine # [ 4.577590] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.699machine # [ 4.579183] systemd[1]: Finished NixOS Activation.700machine # [ 4.581160] systemd[1]: Starting Switch Root...701machine # [ 4.593717] systemd[1]: Switching root.702machine # [ 4.872402] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).703machine # [ 5.017403] NET: Registered PF_VSOCK protocol family704machine # [ 5.398799] systemd[1]: systemd 262-175b52e 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)705machine # [ 5.408638] systemd[1]: Detected virtualization kvm.706machine # [ 5.410558] systemd[1]: Detected architecture x86-64.707machine # [ 5.412531] systemd[1]: Detected first boot.708machine # [ 5.419791] systemd[1]: Initializing machine ID from random generator.709machine # [ 5.540611] systemd[1]: bpf-restrict-fs: LSM BPF program attached710machine # [ 5.643721] systemd[1]: Applying preset policy.711machine # [ 6.000888] systemd[1]: Populated /etc with preset unit settings.712machine # [ 6.404519] systemd[1]: initrd-switch-root.service: Deactivated successfully.713machine # [ 6.406001] systemd[1]: Stopped initrd-switch-root.service.714machine # [ 6.408492] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.715machine # [ 6.410683] systemd[1]: Created slice Slice /system/getty.716machine # [ 6.412005] systemd[1]: Created slice User and Session Slice.717machine # [ 6.413147] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.718machine # [ 6.414334] systemd[1]: Started Forward Password Requests to Wall Directory Watch.719machine # [ 6.415557] systemd[1]: Expecting device /dev/hvc0...720machine # [ 6.416297] systemd[1]: Expecting device /dev/ttyS0...721machine # [ 6.417000] systemd[1]: Reached target Local Encrypted Volumes.722machine # [ 6.417833] systemd[1]: Stopped target initrd-fs.target.723machine # [ 6.418589] systemd[1]: Stopped target initrd-root-fs.target.724machine # [ 6.419393] systemd[1]: Stopped target initrd-switch-root.target.725machine # [ 6.420222] systemd[1]: Reached target Virtual Machines and Containers.726machine # [ 6.421129] systemd[1]: Reached target Path Units.727machine # [ 6.421806] systemd[1]: Reached target Remote File Systems.728machine # [ 6.422610] systemd[1]: Reached target Slice Units.729machine # [ 6.423342] systemd[1]: Reached target Swaps.730machine # [ 6.437381] systemd[1]: Listening on Query the User Interactively for a Password.731machine # [ 6.440786] systemd[1]: Listening on Process Core Dump Socket.732machine # [ 6.443170] systemd[1]: Listening on Credential Encryption/Decryption.733machine # [ 6.445468] systemd[1]: Listening on Factory Reset Management.734machine # [ 6.446381] systemd[1]: Listening on Hostname Service Socket.735machine # [ 6.449597] systemd[1]: Starting Journal Log Access Socket...736machine # [ 6.451480] systemd[1]: Listening on Journal Audit Socket.737machine # [ 6.454015] systemd[1]: Listening on Console Output Muting Service Socket.738machine # [ 6.455208] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.739machine # [ 6.456866] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os740machine # [ 6.458144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki741machine # [ 6.466482] systemd[1]: Listening on Disk Repartitioning Service Socket.742machine # [ 6.467626] systemd[1]: Listening on udev Varlink Socket.743machine # [ 6.471631] systemd[1]: Mounting Huge Pages File System...744machine # [ 6.474749] systemd[1]: Mounting POSIX Message Queue File System...745machine # [ 6.480001] systemd[1]: Mounting Kernel Debug File System...746machine # [ 6.486648] systemd[1]: Mounting Kernel Trace File System...747machine # [ 6.493422] systemd[1]: Starting Create List of Static Device Nodes...748machine # [ 6.495317] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio749machine # [ 6.501960] systemd[1]: Starting Load Kernel Module configfs...750machine # [ 6.506634] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm751machine # [ 6.512065] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore752machine # [ 6.514318] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse753machine # [ 6.520751] systemd[1]: Mounting FUSE Control File System...754machine # [ 6.522354] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67755machine # [ 6.563359] systemd[1]: Starting Journal Service...756machine # [ 6.576727] systemd[1]: Starting Load Kernel Modules...757machine # [ 6.600948] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...758machine # [ 6.615507] systemd[1]: Starting Remount Root and Kernel File Systems...759machine # [ 6.618533] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os760machine # [ 6.633907] systemd[1]: Starting Coldplug All udev Devices...761machine # [ 6.655558] systemd[1]: Listening on Journal Log Access Socket.762machine # [ 6.660427] systemd[1]: Mounted Huge Pages File System.763machine # [ 6.664480] systemd[1]: Mounted POSIX Message Queue File System.764machine # [ 6.668659] systemd[1]: Mounted Kernel Debug File System.765machine # [ 6.674055] systemd-journald[286]: Collecting audit messages is enabled.766machine # [ 6.676038] systemd[1]: Mounted Kernel Trace File System.767machine # [ 6.683414] loop: module loaded768machine # [ 6.684290] systemd[1]: Finished Create List of Static Device Nodes.769machine # [ 6.689142] EXT4-fs (vda): re-mounted 34a6b496-c3f9-4684-ae1e-e673c615f026.770machine # [ 6.690780] systemd[1]: modprobe@configfs.service: Deactivated successfully.771machine # [ 6.696367] systemd[1]: Finished Load Kernel Module configfs.772machine # [ 6.702788] systemd[1]: Mounted FUSE Control File System.773machine # [ 6.559487] systemd[1]: Queued start job for default target Multi-User System.774machine # [ 6.708161] systemd[1]: Started Journal Service.775machine # [ 6.563296] systemd[1]: systemd-journald.service: Deactivated successfully.776machine # [ 6.565263] systemd-modules-load[287]: Inserted module 'loop'777machine # [ 6.567164] systemd[1]: Finished Load Kernel Modules.778machine # [ 6.571131] systemd[1]: Finished Remount Root and Kernel File Systems.779machine # [ 6.588082] systemd[1]: Listening on Disk Image Download Service Socket.780machine # [ 6.595395] systemd[1]: Mounting Kernel Configuration File System...781machine # [ 6.601583] systemd[1]: Starting Firewall...782machine # [ 6.613545] systemd-oomd[288]: No swap; memory pressure usage will be degraded783machine # [ 6.618507] systemd[1]: Starting Flush Journal to Persistent Storage...784machine # [ 6.619945] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore785machine # [ 6.629710] systemd[1]: Starting Load/Save OS Random Seed...786machine # [ 6.642865] systemd[1]: Starting Apply Kernel Variables...787machine # [ 6.657869] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...788machine # [ 6.659317] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os789machine # [ 6.660562] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.790machine # [ 6.847813] systemd-journald[286]: Received client request to flush runtime journal.791machine # [ 6.827356] systemd[1]: Finished Load/Save OS Random Seed.792machine # [ 6.828347] systemd[1]: Mounted Kernel Configuration File System.793machine # [ 6.831064] systemd[1]: Reached target First Boot Complete.794machine # [ 6.832435] systemd[1]: Finished Apply Kernel Variables.795machine # [ 6.834705] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.796machine # [ 6.835721] systemd[1]: Starting Create Static Device Nodes in /dev...797machine # [ 6.839322] systemd[1]: Finished Flush Journal to Persistent Storage.798machine # [ 6.889460] systemd[1]: Finished Create Static Device Nodes in /dev.799machine # [ 6.891502] systemd[1]: Reached target Preparation for Local File Systems.800machine # [ 6.895642] systemd[1]: Starting Rule-based Manager for Device Events and Files...801machine # [ 6.976282] systemd[1]: Finished Coldplug All udev Devices.802machine # [ 6.978448] systemd-udevd[325]: Using default interface naming scheme 'v261'.803machine # [ 7.075312] systemd[1]: Started Rule-based Manager for Device Events and Files.804machine # [ 7.193963] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse805machine # [ 7.263399] systemd[1]: Mounting /run/wrappers...806machine # [ 7.275212] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.807machine # [ 7.304778] systemd[1]: Mounted /run/wrappers.808machine # [ 7.307268] systemd[1]: Reached target Local File Systems.809machine # [ 7.309760] systemd[1]: Listening on Boot Loader Control Service Socket.810machine # [ 7.316367] systemd[1]: Starting register-nix-paths.service...811machine # [ 7.319582] systemd[1]: Starting Create SUID/SGID Wrappers...812machine # [ 7.321132] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.813machine # [ 7.330301] systemd[1]: Starting Save Transient machine-id to Disk...814machine # [ 7.351517] systemd[1]: Starting Create System Files and Directories...815machine # [ 7.354302] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.816machine # [ 7.382529] (udev-worker)[360]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.817machine # [ 7.401097] (udev-worker)[359]: Network interface NamePolicy= disabled on kernel command line.818machine # [ 7.402479] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line.819machine # [ 7.433964] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.820machine # [ 7.439388] systemd[1]: Finished Save Transient machine-id to Disk.821machine # [ 7.495331] systemd[1]: Finished Create System Files and Directories.822machine # [ 7.507444] systemd[1]: Starting Rebuild Journal Catalog...823machine # [ 7.514176] systemd[1]: Starting Record System Boot/Shutdown in UTMP...824machine # [ 7.595281] systemd[1]: Finished Record System Boot/Shutdown in UTMP.825machine # [ 7.644934] systemd[1]: Finished Rebuild Journal Catalog.826machine # [ 7.653093] systemd[1]: Starting Update is Completed...827machine # [ 7.668862] systemd[1]: Condition check resulted in Virtio network device being skipped.828machine # [ 7.670104] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore829machine # [ 7.672369] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.830machine # [ 7.674801] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67831machine # [ 7.678266] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore832machine # [ 7.679900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os833machine # [ 7.682439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os834machine # [ 7.733486] systemd[1]: Finished Update is Completed.835machine # [ 8.061258] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3836machine # [ 8.066762] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console837machine # [ 7.931086] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.838machine # [ 7.934366] systemd[1]: Finished Create SUID/SGID Wrappers.839machine # [ 8.098008] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4840machine # [ 7.980143] systemd[1]: Finished Firewall.841machine # [ 8.121960] ACPI: button: Power Button [PWRF]842machine # [ 8.145027] mousedev: PS/2 mouse device common for all mice843machine # [ 8.183000] rtc_cmos 00:04: RTC can wake from S4844machine # [ 8.203602] rtc_cmos 00:04: registered as rtc0845machine # [ 8.203670] rtc_cmos 00:04: setting system clock to 2026-09-04T02:38:46 UTC (1788489526)846machine # [ 8.203747] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs847machine # [ 8.205600] systemd-journald[286]: Time jumped backwards, rotating.848machine # [ 8.252211] Console: switching to colour dummy device 80x25849machine # [ 8.291514] parport_pc 00:02: reported by Plug and Play ACPI850machine # [ 8.291641] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]851machine # [ 8.306645] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized852machine # [ 8.255655] systemd[1]: Finished register-nix-paths.service.853machine # [ 8.257858] systemd[1]: Reached target System Initialization.854machine # [ 8.262231] systemd[1]: Started Discard unused filesystem blocks once a week.855machine # [ 8.263533] systemd[1]: Started Daily Cleanup of Temporary Directories.856machine # [ 8.264754] systemd[1]: Reached target Timer Units.857machine # [ 8.265969] systemd[1]: Listening on D-Bus System Message Bus Socket.858machine # [ 8.416251] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt859machine # [ 8.417156] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD860machine # [ 8.271428] systemd[1]: Listening on Nix Daemon Socket.861machine # [ 8.272761] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.862machine # [ 8.275305] systemd[1]: Reached target Socket Units.863machine # [ 8.277190] systemd[1]: Reached target Basic System.864machine # [ 8.425823] [drm] Found bochs VGA, ID 0xb0c5.865machine # [ 8.426483] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.866machine # [ 8.280950] systemd[1]: Started backdoor.service.867machine # [ 8.285783] systemd[1]: Starting Import lastlog data into lastlog2 database...868machine # [ 8.297457] systemd[1]: Starting Name Service Cache Daemon (nsncd)...869machine # [ 8.309824] systemd[1]: Starting Post-Boot Actions...870machine # [ 8.469125] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic871machine # [ 8.324838] systemd[1]: Started Reset console on configuration changes.872machine # [ 8.341794] systemd[1]: Starting resolvconf update...873machine # [ 8.510527] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0874machine # connecting to host...875machine # [ 8.404852] systemd[1]: Starting D-Bus System Message Bus...876machine # [ 8.423243] nsncd[502]: Sep 04 02:38:46.862 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"877machine # [ 8.425601] systemd[1]: Started Name Service Cache Daemon (nsncd).878machine # [ 8.433149] systemd[1]: Finished Post-Boot Actions.879machine: Guest shell says: b'Spawning backdoor root shell...\n'880machine # [ 8.451149] systemd[1]: Reached target Host and Network Name Lookups.881machine: connected to guest root shell882machine # [ 8.454061] systemd[1]: Reached target User and Group Name Lookups.883machine: (connecting took 9.12 seconds)884machine: (finished: waiting for the VM to finish booting, in 9.33 seconds)885machine # [ 8.616357] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6886machine # [ 8.471673] systemd[1]: Starting User Login Management...887machine # [ 8.620664] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5888machine # [ 8.480428] systemd[1]: Finished Import lastlog data into lastlog2 database.889machine # [ 8.621781] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'...890machine # [ 8.849899] Console: switching to colour frame buffer device 160x50891machine # [ 8.922118] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device892machine # [ 8.668852] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync'893machine # [ 8.779119] dbus-broker-launch[512]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"894machine # [ 8.781929] systemd[1]: Stopped target Host and Network Name Lookups.895machine # [ 8.786077] systemd[1]: Stopping Host and Network Name Lookups...896machine # [ 8.790832] systemd[1]: Stopped target User and Group Name Lookups.897machine # [ 8.792677] systemd[1]: Stopping User and Group Name Lookups...898machine # [ 8.796177] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...899machine # [ 8.797756] systemd[1]: Starting Virtual Console Setup...900machine # [ 8.800473] systemd[1]: nscd.service: Deactivated successfully.901machine # [ 8.802443] systemd[1]: Stopped Name Service Cache Daemon (nsncd).902machine # [ 8.805083] systemd[1]: Started D-Bus System Message Bus.903machine # [ 8.806911] systemd[1]: Starting Name Service Cache Daemon (nsncd)...904machine # [ 8.808785] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.905machine # [ 8.814963] dbus-broker-launch[512]: Ready906machine # [ 8.815981] systemd[1]: Stopped Virtual Console Setup.907machine # [ 8.817200] systemd-logind[528]: New seat seat0.908machine # [ 8.819451] systemd[1]: Starting Virtual Console Setup...909machine # [ 8.821210] systemd[1]: Started User Login Management.910machine # [ 8.823099] systemd-logind[528]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)911machine # [ 8.827093] systemd-logind[528]: Watching system buttons on /dev/input/event2 (Power Button)912machine # [ 8.843911] systemd[1]: Starting linger-users.service...913machine # [ 8.858728] systemd-logind[528]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)914machine # [ 8.860762] nsncd[587]: Sep 04 02:38:47.301 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"915machine # [ 8.865944] systemd[1]: Started Name Service Cache Daemon (nsncd).916machine # [ 8.869571] systemd[1]: Reached target Host and Network Name Lookups.917machine # [ 8.870901] systemd[1]: Reached target User and Group Name Lookups.918machine # [ 8.895161] systemd[1]: Finished resolvconf update.919machine # [ 8.898255] systemd[1]: Reached target Preparation for Network.920machine # [ 8.908595] systemd[1]: Starting DHCP Client...921machine # [ 8.918725] systemd[1]: Starting Address configuration of eth1...922machine # [ 8.928499] systemd[1]: Starting Extra networking commands....923machine # [ 9.136640] ppdev: user-space parallel port driver924machine # [ 9.005657] systemd[1]: linger-users.service: Deactivated successfully.925machine # [ 9.007915] systemd[1]: Finished linger-users.service.926machine # [ 9.029312] systemd[1]: Created slice Slice /user/1001.927machine # [ 9.036112] systemd[1]: Starting User Runtime Directory /run/user/1001...928machine # [ 9.185339] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)929machine # [ 9.224589] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)930machine # [ 9.115235] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.931machine # [ 9.116303] systemd[1]: Stopped Virtual Console Setup.932machine # [ 9.123228] systemd[1]: Starting Virtual Console Setup...933machine # [ 9.168821] systemd[1]: Finished User Runtime Directory /run/user/1001.934machine # [ 9.173187] systemd[1]: Starting User Manager for UID 1001...935machine # [ 9.191943] network-addresses-eth1-start[617]: adding address 192.168.1.1/24... done936machine # [ 9.230189] network-addresses-eth1-start[617]: adding address 2001:db8:1::1/64... done937machine # [ 9.276198] systemd[1]: Finished Address configuration of eth1.938machine # [ 9.279906] (systemd)[636]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)939machine # [ 9.308358] systemd-logind[528]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.940machine # [ 9.367327] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy941machine # [ 9.576292] kvm_amd: TSC scaling supported942machine # [ 9.576907] kvm_amd: Nested Virtualization enabled943machine # [ 9.580383] kvm_amd: Nested Paging enabled944machine # [ 9.582130] kvm_amd: LBR virtualization supported945machine # [ 9.582957] kvm_amd: Virtual VMLOAD VMSAVE supported946machine # [ 9.585129] kvm_amd: Virtual GIF supported947machine # [ 9.585863] kvm_amd: Virtual NMI enabled948machine # [ 9.462345] systemd[1]: Finished Extra networking commands..949machine # [ 9.463756] systemd[1]: Reached target Network.950machine # [ 9.468303] systemd[1]: Starting Permit User Sessions...951machine # [ 9.492682] dhcpcd[641]: dhcpcd-10.3.2 starting952machine # [ 9.508358] dhcpcd[700]: dev: loaded udev953machine # [ 9.669400] EDAC MC: Ver: 3.0.0954machine # [ 9.537581] systemd[1]: Finished Permit User Sessions.955machine # [ 9.542704] systemd[1]: Started Getty on tty1.956machine # [ 9.546148] systemd[1]: Reached target Login Prompts.957machine # [ 9.698866] 8021q: 802.1Q VLAN Support v1.8958machine # [ 9.701943] 8021q: adding VLAN 0 to HW filter on device eth1959machine # [ 9.596118] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.960machine # [ 9.816029] cfg80211: Loading compiled-in X.509 certificates for regulatory database961machine # [ 9.704657] systemd[636]: Queued start job for default target Main User Target.962machine # [ 9.707833] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy963machine # [ 9.858788] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'964machine # [ 9.859989] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'965machine # [ 9.717591] systemd[636]: Created slice User Application Slice.966machine # [ 9.719160] systemd[636]: Started Daily Cleanup of User's Temporary Directories.967machine # [ 9.867555] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2968machine # [ 9.868758] cfg80211: failed to load regulatory.db969machine # [ 9.723413] systemd[636]: Reached target Paths.970machine # [ 9.724966] systemd[636]: Reached target Timers.971machine # [ 9.730630] systemd[636]: Starting D-Bus User Message Bus Socket...972machine # [ 9.735704] systemd[636]: Starting Create User Files and Directories...973machine # [ 9.787454] systemd[636]: Finished Create User Files and Directories.974machine # [ 9.968788] 8021q: adding VLAN 0 to HW filter on device eth0975machine # [ 9.826667] dhcpcd[700]: eth0: waiting for carrier976machine # [ 9.828672] dhcpcd[700]: eth0: carrier acquired977machine # [ 9.833813] systemd[636]: Listening on D-Bus User Message Bus Socket.978machine # [ 9.838891] systemd[636]: Reached target Sockets.979machine # [ 9.841155] systemd[636]: Reached target Basic System.980machine # [ 9.843282] systemd[1]: Started User Manager for UID 1001.981machine # [ 9.846233] systemd[636]: Starting Run user-specific NixOS activation...982machine # [ 9.860318] dhcpcd[700]: DUID 00:01:00:01:32:2c:eb:b8:52:54:00:12:34:56983machine # [ 9.862069] dhcpcd[700]: eth0: IAID 00:12:34:56984machine # [ 9.863431] dhcpcd[700]: eth0: adding address fe80::5054:ff:fe12:3456985machine # [ 9.894660] systemd[636]: Finished Run user-specific NixOS activation.986machine # [ 9.896457] systemd[636]: Reached target Main User Target.987machine # [ 9.898323] systemd[636]: Startup finished in 549ms.988machine # [ 9.983992] systemd-vconsole-setup[632]: Configuration of first virtual console was skipped, ignoring remaining ones.989machine # [ 9.989304] systemd[1]: Finished Virtual Console Setup.990machine # [ 10.636446] dhcpcd[700]: eth0: soliciting a DHCP lease991machine # [ 10.806869] NET: Registered PF_PACKET protocol family992machine # [ 10.666308] dhcpcd[700]: eth0: offered 10.0.2.15 from 10.0.2.2993machine # [ 10.669358] dhcpcd[700]: eth0: probing address 10.0.2.15/24994machine # [ 12.176610] dhcpcd[700]: eth0: soliciting an IPv6 router995machine # [ 12.179578] dhcpcd[700]: eth0: Router Advertisement from fe80::2996machine # [ 12.181733] dhcpcd[700]: eth0: adding address fec0::5054:ff:fe12:3456/64997machine # [ 12.183989] dhcpcd[700]: eth0: adding route to fec0::/64998machine # [ 12.185930] dhcpcd[700]: eth0: adding default route via fe80::2999machine # [ 16.477805] dhcpcd[700]: eth0: leased 10.0.2.15 for 86400 seconds1000machine # [ 16.481385] dhcpcd[700]: eth0: adding route to 10.0.2.0/241001machine # [ 16.483414] dhcpcd[700]: eth0: adding default route via 10.0.2.21002machine # [ 16.597517] systemd[1]: Started DHCP Client.1003machine # [ 16.599478] systemd[1]: Reached target Multi-User System.1004machine # [ 16.600432] systemd[1]: Startup finished in 847ms (kernel) + 3.963s (initrd) + 11.788s (userspace) = 16.599s.1005machine: (finished: waiting for unit multi-user.target, in 18.25 seconds)1006machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test1007machine # Checking switch inhibitors... done1008machine # [ 17.863938] nixos[858]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1009machine # [ 17.866177] systemd[1]: Stopped target Remote File Systems.1010machine # [ 17.867801] systemd[1]: Stopped target Local File Systems.1011machine # activating the configuration...1012machine # setting up /etc...1013machine # [ 18.210477] systemd[1]: Reload requested from client PID 858 ('.switch-to-conf') (unit backdoor.service)...1014machine # [ 18.212187] systemd[1]: Reloading...1015machine # [ 18.680272] systemd[1]: Reloading finished in 465 ms.1016machine # reloading user units for usertest...1017machine # [ 18.712901] systemd[636]: Created slice User Core Session Slice.1018machine # [ 18.715982] systemd[636]: Starting D-Bus User Message Bus...1019machine # [ 18.765479] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.import1.service'1020machine # [ 18.768106] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1021machine # [ 18.770874] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1022machine # [ 18.773851] dbus-broker-launch[928]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1023machine # [ 18.777036] dbus-broker-launch[928]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1024machine # [ 18.778587] dbus-broker-launch[928]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1025machine # [ 18.780224] systemd[636]: Started D-Bus User Message Bus.1026machine # [ 18.790118] dbus-broker-launch[928]: Ready1027machine # [ 18.871746] systemd[636]: Reexecution requested from client PID 927 ('switch-to-confi')...1028machine # [ 18.873734] systemd[636]: Reexecuting.1029machine # [ 18.903192] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1030machine # [ 19.042392] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1031machine # [ 19.047752] systemd[636]: Reload requested from client PID 927 ('switch-to-confi')...1032machine # [ 19.048941] systemd[636]: Reloading...1033machine # [ 19.185974] systemd[636]: Reloading finished in 135 ms.1034machine # [ 19.191097] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1035machine # restarting the following user units: nixos-activation.service1036machine # [ 19.199400] systemd[636]: Stopped Run user-specific NixOS activation.1037machine # [ 19.200424] systemd[636]: Stopping Run user-specific NixOS activation...1038machine # [ 19.203603] systemd[636]: Starting Run user-specific NixOS activation...1039machine # [ 19.240751] systemd[636]: Finished Run user-specific NixOS activation.1040machine # restarting sysinit-reactivation.target1041machine # [ 19.252780] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1042machine # [ 19.373643] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1043machine # [ 19.374825] systemd[1]: Reached target Reactivate sysinit units.1044machine # [ 19.379103] systemd[1]: Reached target Local File Systems.1045machine # [ 19.380309] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1046machine # [ 19.385075] systemd[1]: Starting Create SUID/SGID Wrappers...1047machine # [ 19.386056] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1048machine # [ 19.387667] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671049machine # [ 19.392393] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1050machine # [ 19.394455] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1051machine # [ 19.397350] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1052machine # [ 19.398492] systemd[1]: Reached target Remote File Systems.1053machine # [ 19.406901] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 19.408662] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1055machine # [ 19.693865] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1056machine # [ 19.696210] systemd[1]: Finished Create SUID/SGID Wrappers.1057machine # [ 19.700449] systemd[1]: Starting linger-users.service...1058machine # [ 19.751060] systemd[1]: linger-users.service: Deactivated successfully.1059machine # [ 19.753549] systemd[1]: Finished linger-users.service.1060machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1061machine # [ 20.008978] nixos[858]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1062machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test, in 2.63 seconds)1063machine: must succeed: rm /etc/NIXOS1064machine: (finished: must succeed: rm /etc/NIXOS, in 0.02 seconds)1065machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test1066machine # this should succeed (config: /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test, action: test)1067machine # Checking switch inhibitors... done1068machine # [ 20.617225] nixos[1006]: switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test1069machine # [ 20.620316] systemd[1]: Stopped target Local File Systems.1070machine # [ 20.622094] systemd[1]: Stopped target Remote File Systems.1071machine # activating the configuration...1072machine # removing user ‘usertest’1073machine # setting up /etc...1074machine # [ 21.032070] systemd[1]: Reload requested from client PID 1006 ('.switch-to-conf') (unit backdoor.service)...1075machine # [ 21.033942] systemd[1]: Reloading...1076machine # [ 21.504409] systemd[1]: Reloading finished in 468 ms.1077machine # reloading user units for usertest...1078machine # [ 21.620476] systemd[636]: Reexecution requested from client PID 1077 ('switch-to-confi')...1079machine # [ 21.621799] systemd[636]: Reexecuting.1080machine # [ 21.647785] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1081machine # [ 21.781526] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1082machine # [ 21.788063] systemd[636]: Reload requested from client PID 1077 ('switch-to-confi')...1083machine # [ 21.789444] systemd[636]: Reloading...1084machine # [ 21.912099] systemd[636]: Reloading finished in 121 ms.1085machine # [ 21.917855] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1086machine # restarting the following user units: nixos-activation.service1087machine # [ 21.925305] systemd[636]: Stopped Run user-specific NixOS activation.1088machine # [ 21.926156] systemd[636]: Stopping Run user-specific NixOS activation...1089machine # [ 21.928905] systemd[636]: Starting Run user-specific NixOS activation...1090machine # [ 21.960410] systemd[636]: Finished Run user-specific NixOS activation.1091machine # restarting sysinit-reactivation.target1092machine # [ 21.964563] systemd[1]: Stopped target Reactivate sysinit units.1093machine # [ 21.965703] systemd[1]: Stopping Reactivate sysinit units...1094machine # reloading the following units: dbus-broker.service[ 21.967323] systemd[1]: Reached target Reactivate sysinit units.1095machine # 1096machine # [ 21.969658] dbus-broker-launch[512]: Caught SIGHUP, trigger reload.1097machine # [ 21.971190] systemd[1]: Reloading D-Bus System Message Bus...1098machine # [ 21.987174] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'...1099machine # [ 21.989190] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync'1100machine # [ 21.990189] dbus-broker-launch[512]: Invalid user-name in /nix/store/0n1xh0s1wqya4gyrk665anqq5xkl5bll-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1101machine # [ 22.009823] systemd[1]: Reloaded D-Bus System Message Bus.1102machine # restarting the following units: network-addresses-eth1.service1103machine # [ 22.015456] systemd[1]: Stopping Address configuration of eth1...1104machine # [ 22.050448] network-addresses-eth1-pre-stop[1089]: deleting address 192.168.1.1/24... done1105machine # [ 22.059196] network-addresses-eth1-pre-stop[1089]: deleting address 2001:db8:1::1/64... done1106machine # [ 22.066619] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1107machine # [ 22.068491] systemd[1]: Stopped Address configuration of eth1.1108machine # [ 22.072068] systemd[1]: Starting Address configuration of eth1...1109machine # [ 22.122469] network-addresses-eth1-start[1094]: adding address 192.168.1.2/24... done1110machine # [ 22.132230] network-addresses-eth1-start[1094]: adding address 2001:db8:1::2/64... done1111machine # [ 22.143660] systemd[1]: Finished Address configuration of eth1.1112machine # [ 22.147345] systemd[1]: Reached target Remote File Systems.1113machine # [ 22.152166] systemd[1]: Starting linger-users.service...1114machine # [ 22.154837] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1115machine # [ 22.157513] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1116machine # [ 22.160636] systemd[1]: Reached target Local File Systems.1117machine # [ 22.165623] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1118machine # [ 22.169425] systemd[1]: Starting Create SUID/SGID Wrappers...1119machine # [ 22.171093] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1120machine # [ 22.172420] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671121machine # [ 22.174383] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1122machine # [ 22.175834] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1123machine # [ 22.177965] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 22.214568] linger-users-start[1108]: id: ‘usertest’: no such user1125machine # [ 22.216187] linger-users-start[1105]: Removing linger for missing user usertest1126machine # [ 22.223710] systemd[1]: linger-users.service: Deactivated successfully.1127machine # [ 22.225423] systemd[1]: Finished linger-users.service.1128machine # [ 22.492800] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1129machine # [ 22.494496] systemd[1]: Finished Create SUID/SGID Wrappers.1130machine # [ 22.750726] nixos[1006]: finished switching to system configuration /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test1131machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/kr3nhdyb6zpfdwjzyy7syixwz9556avk-nixos-system-other-test/bin/switch-to-configuration test, in 2.72 seconds)1132machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&11133machine # [ 23.245319] nixos[1173]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1134machine # [ 23.248594] systemd[1]: Stopped target Remote File Systems.1135machine # [ 23.249971] systemd[1]: Stopped target Local File Systems.1136machine # [ 23.632644] systemd[1]: Reload requested from client PID 1173 ('.switch-to-conf') (unit backdoor.service)...1137machine # [ 23.634324] systemd[1]: Reloading...1138machine # [ 24.135062] systemd[1]: Reloading finished in 498 ms.1139machine # [ 24.228141] systemd[636]: Reexecution requested from client PID 1242 ('switch-to-confi')...1140machine # [ 24.229986] systemd[636]: Reexecuting.1141machine # [ 24.256446] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1142machine # [ 24.388120] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1143machine # [ 24.394247] systemd[636]: Reload requested from client PID 1242 ('switch-to-confi')...1144machine # [ 24.395509] systemd[636]: Reloading...1145machine # [ 24.533369] systemd[636]: Reloading finished in 135 ms.1146machine # [ 24.538877] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1147machine # [ 24.545789] systemd[636]: Stopped Run user-specific NixOS activation.1148machine # [ 24.546826] systemd[636]: Stopping Run user-specific NixOS activation...1149machine # [ 24.549825] systemd[636]: Starting Run user-specific NixOS activation...1150machine # [ 24.577702] systemd[636]: Finished Run user-specific NixOS activation.1151machine # [ 24.581639] systemd[1]: Stopped target Reactivate sysinit units.1152machine # [ 24.582771] systemd[1]: Stopping Reactivate sysinit units...1153machine # [ 24.584148] systemd[1]: Reached target Reactivate sysinit units.1154machine # [ 24.586278] dbus-broker-launch[512]: Caught SIGHUP, trigger reload.1155machine # [ 24.587560] systemd[1]: Reloading D-Bus System Message Bus...1156machine # [ 24.595171] dbus-broker-launch[512]: Looking up NSS user entry for 'systemd-timesync'...1157machine # [ 24.597050] dbus-broker-launch[512]: NSS returned no entry for 'systemd-timesync'1158machine # [ 24.597962] dbus-broker-launch[512]: Invalid user-name in /nix/store/nxbpk401an3y90294yk3vj386qpkvmng-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1159machine # [ 24.611764] systemd[1]: Reloaded D-Bus System Message Bus.1160machine # [ 24.616899] systemd[1]: Stopping Address configuration of eth1...1161machine # [ 24.647761] network-addresses-eth1-pre-stop[1254]: deleting address 192.168.1.2/24... done1162machine # [ 24.656636] network-addresses-eth1-pre-stop[1254]: deleting address 2001:db8:1::2/64... done1163machine # [ 24.663499] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1164machine # [ 24.665489] systemd[1]: Stopped Address configuration of eth1.1165machine # [ 24.669522] systemd[1]: Starting Address configuration of eth1...1166machine # [ 24.730773] network-addresses-eth1-start[1259]: adding address 192.168.1.1/24... done1167machine # [ 24.740584] network-addresses-eth1-start[1259]: adding address 2001:db8:1::1/64... done1168machine # [ 24.750807] systemd[1]: Finished Address configuration of eth1.1169machine # [ 24.753527] systemd[1]: Reached target Local File Systems.1170machine # [ 24.755843] systemd[1]: Reached target Remote File Systems.1171machine # [ 24.759402] systemd[1]: Starting linger-users.service...1172machine # [ 24.770113] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1173machine # [ 24.772105] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1174machine # [ 24.775716] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1175machine # [ 24.780914] systemd[1]: Starting Create SUID/SGID Wrappers...1176machine # [ 24.782120] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1177machine # [ 24.783993] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671178machine # [ 24.787223] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1179machine # [ 24.790093] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1180machine # [ 24.791297] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 24.819171] systemd[1]: linger-users.service: Deactivated successfully.1182machine # [ 24.820821] systemd[1]: Finished linger-users.service.1183machine # [ 25.041366] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1184machine # [ 25.043084] systemd[1]: Finished Create SUID/SGID Wrappers.1185machine # [ 25.298271] nixos[1173]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1186machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.55 seconds)1187subtest: user services1188machine: waiting for unit user@1001.service1189machine: (finished: waiting for unit user@1001.service, in 0.13 seconds)1190machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11191machine # [ 25.929053] nixos[1332]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1192machine # [ 25.933233] systemd[1]: Stopped target Remote File Systems.1193machine # [ 25.936112] systemd[1]: Stopped target Local File Systems.1194machine # [ 26.418947] systemd[1]: Reload requested from client PID 1332 ('.switch-to-conf') (unit backdoor.service)...1195machine # [ 26.420823] systemd[1]: Reloading...1196machine # [ 26.852459] systemd[1]: Reloading finished in 429 ms.1197machine # [ 26.981291] systemd[636]: Reexecution requested from client PID 1402 ('switch-to-confi')...1198machine # [ 26.982689] systemd[636]: Reexecuting.1199machine # [ 27.009930] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 27.191465] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 27.195740] systemd[636]: Reload requested from client PID 1402 ('switch-to-confi')...1202machine # [ 27.197273] systemd[636]: Reloading...1203machine # [ 27.342722] systemd[636]: Reloading finished in 143 ms.1204machine # [ 27.347929] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 27.356363] systemd[636]: Starting usertest.service...1206machine # [ 27.386617] systemd[636]: Finished usertest.service.1207machine # [ 27.389347] systemd[636]: Stopped Run user-specific NixOS activation.1208machine # [ 27.391116] systemd[636]: Stopping Run user-specific NixOS activation...1209machine # [ 27.393636] systemd[636]: Starting Run user-specific NixOS activation...1210machine # [ 27.432641] systemd[636]: Finished Run user-specific NixOS activation.1211machine # [ 27.437285] systemd[1]: Stopped target Reactivate sysinit units.1212machine # [ 27.438212] systemd[1]: Stopping Reactivate sysinit units...1213machine # [ 27.438937] systemd[1]: Reached target Reactivate sysinit units.1214machine # [ 27.441796] systemd[1]: Reached target Local File Systems.1215machine # [ 27.444067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1216machine # [ 27.448573] systemd[1]: Starting Create SUID/SGID Wrappers...1217machine # [ 27.449406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1218machine # [ 27.450576] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671219machine # [ 27.453932] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1220machine # [ 27.456851] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1221machine # [ 27.458871] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1222machine # [ 27.463602] systemd[1]: Reached target Remote File Systems.1223machine # [ 27.472059] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1224machine # [ 27.473617] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1225machine # [ 27.796466] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1226machine # [ 27.798516] systemd[1]: Finished Create SUID/SGID Wrappers.1227machine # [ 27.803411] systemd[1]: Starting linger-users.service...1228machine # [ 27.861656] systemd[1]: linger-users.service: Deactivated successfully.1229machine # [ 27.864362] systemd[1]: Finished linger-users.service.1230machine # [ 28.122543] nixos[1332]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1231machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.70 seconds)1232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1233machine # [ 28.231163] sudo[1472]: 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.service1234machine # [ 28.247470] sudo[1472]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1235machine # [ 28.262422] sudo[1472]: pam_unix(sudo:session): session closed for user usertest1236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.13 seconds)1237machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11238machine # [ 29.105373] nixos[1481]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1239machine # [ 29.110428] systemd[1]: Stopped target Remote File Systems.1240machine # [ 29.111871] systemd[1]: Stopped target Local File Systems.1241machine # [ 29.641737] systemd[1]: Reload requested from client PID 1481 ('.switch-to-conf') (unit backdoor.service)...1242machine # [ 29.644084] systemd[1]: Reloading...1243machine # [ 30.145400] systemd[1]: Reloading finished in 498 ms.1244machine # [ 30.269243] systemd[636]: Reexecution requested from client PID 1551 ('switch-to-confi')...1245machine # [ 30.271229] systemd[636]: Reexecuting.1246machine # [ 30.301593] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1247machine # [ 30.459513] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1248machine # [ 30.465182] systemd[636]: Reload requested from client PID 1551 ('switch-to-confi')...1249machine # [ 30.467280] systemd[636]: Reloading...1250machine # [ 30.606488] systemd[636]: Reloading finished in 137 ms.1251machine # [ 30.611865] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1252machine # [ 30.618769] systemd[636]: Stopped Run user-specific NixOS activation.1253machine # [ 30.619796] systemd[636]: Stopping Run user-specific NixOS activation...1254machine # [ 30.623116] systemd[636]: Starting Run user-specific NixOS activation...1255machine # [ 30.656680] systemd[636]: Finished Run user-specific NixOS activation.1256machine # [ 30.660184] systemd[1]: Stopped target Reactivate sysinit units.1257machine # [ 30.661867] systemd[1]: Stopping Reactivate sysinit units...1258machine # [ 30.662808] systemd[1]: Reached target Reactivate sysinit units.1259machine # [ 30.664710] systemd[1]: Reached target Local File Systems.1260machine # [ 30.671121] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1261machine # [ 30.674808] systemd[1]: Starting Create SUID/SGID Wrappers...1262machine # [ 30.675662] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1263machine # [ 30.677393] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671264machine # [ 30.680199] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1265machine # [ 30.682486] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 30.684564] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1267machine # [ 30.688442] systemd[1]: Reached target Remote File Systems.1268machine # [ 30.694260] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 30.695838] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1270machine # [ 30.943727] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1271machine # [ 30.945164] systemd[1]: Finished Create SUID/SGID Wrappers.1272machine # [ 30.952225] systemd[1]: Starting linger-users.service...1273machine # [ 31.056169] systemd[1]: linger-users.service: Deactivated successfully.1274machine # [ 31.057723] systemd[1]: Finished linger-users.service.1275machine # [ 31.314486] nixos[1481]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1276machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 3.05 seconds)1277machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11278machine # [ 31.801712] nixos[1622]: switching to system configuration /nix/store/23jp4pz6198dxbfzqii2qwyx1a4sba12-nixos-system-machine-test1279machine # [ 31.804422] systemd[1]: Stopped target Local File Systems.1280machine # [ 31.806318] systemd[1]: Stopped target Remote File Systems.1281machine # [ 32.274905] systemd[1]: Reload requested from client PID 1622 ('.switch-to-conf') (unit backdoor.service)...1282machine # [ 32.277106] systemd[1]: Reloading...1283machine # [ 32.755261] systemd[1]: Reloading finished in 476 ms.1284machine # [ 32.935528] systemd[636]: Stopped usertest.service.1285machine # [ 32.937064] systemd[636]: Reexecution requested from client PID 1692 ('switch-to-confi')...1286machine # [ 32.939681] systemd[636]: Reexecuting.1287machine # [ 32.965066] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1288machine # [ 33.092199] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1289machine # [ 33.098219] systemd[636]: Reload requested from client PID 1692 ('switch-to-confi')...1290machine # [ 33.099664] systemd[636]: Reloading...1291machine # [ 33.225616] systemd[636]: Reloading finished in 123 ms.1292machine # [ 33.230921] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1293machine # [ 33.238944] systemd[636]: Starting usertest.service...1294machine # [ 33.281215] systemd[636]: Finished usertest.service.1295machine # [ 33.284067] systemd[636]: Stopped Run user-specific NixOS activation.1296machine # [ 33.285200] systemd[636]: Stopping Run user-specific NixOS activation...1297machine # [ 33.290744] systemd[636]: Starting Run user-specific NixOS activation...1298machine # [ 33.342351] systemd[636]: Finished Run user-specific NixOS activation.1299machine # [ 33.346768] systemd[1]: Stopped target Reactivate sysinit units.1300machine # [ 33.349179] systemd[1]: Stopping Reactivate sysinit units...1301machine # [ 33.350308] systemd[1]: Reached target Reactivate sysinit units.1302machine # [ 33.356067] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1303machine # [ 33.357971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1304machine # [ 33.363152] systemd[1]: Reached target Remote File Systems.1305machine # [ 33.368864] systemd[1]: Starting linger-users.service...1306machine # [ 33.370544] systemd[1]: Reached target Local File Systems.1307machine # [ 33.378077] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1308machine # [ 33.384487] systemd[1]: Starting Create SUID/SGID Wrappers...1309machine # [ 33.385665] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1310machine # [ 33.387533] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671311machine # [ 33.390807] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1312machine # [ 33.393517] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1313machine # [ 33.395223] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 33.441902] systemd[1]: linger-users.service: Deactivated successfully.1315machine # [ 33.443600] systemd[1]: Finished linger-users.service.1316machine # [ 33.683482] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1317machine # [ 33.686980] systemd[1]: Finished Create SUID/SGID Wrappers.1318machine # [ 33.941456] nixos[1622]: finished switching to system configuration /nix/store/23jp4pz6198dxbfzqii2qwyx1a4sba12-nixos-system-machine-test1319machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 2.62 seconds)1320machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1321machine # [ 33.977850] sudo[1762]: 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.service1322machine # [ 33.989366] sudo[1762]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1323machine # [ 33.999191] sudo[1762]: pam_unix(sudo:session): session closed for user usertest1324machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1325machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11326machine # [ 34.473807] nixos[1771]: switching to system configuration /nix/store/d9j7pw22w68mmylrhq0iwj3k1wa7f51l-nixos-system-machine-test1327machine # [ 34.476246] systemd[1]: Stopped target Local File Systems.1328machine # [ 34.478327] systemd[1]: Stopped target Remote File Systems.1329machine # [ 34.924849] systemd[1]: Reload requested from client PID 1771 ('.switch-to-conf') (unit backdoor.service)...1330machine # [ 34.926567] systemd[1]: Reloading...1331machine # [ 35.444998] systemd[1]: Reloading finished in 515 ms.1332machine # [ 35.583598] systemd[636]: Reexecution requested from client PID 1840 ('switch-to-confi')...1333machine # [ 35.585154] systemd[636]: Reexecuting.1334machine # [ 35.612930] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1335machine # [ 35.774618] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1336machine # [ 35.781639] systemd[636]: Reload requested from client PID 1840 ('switch-to-confi')...1337machine # [ 35.783339] systemd[636]: Reloading...1338machine # [ 35.931263] systemd[636]: Reloading finished in 145 ms.1339machine # [ 35.936099] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1340machine # [ 35.941137] systemd[636]: Stopped usertest.service.1341machine # [ 35.941994] systemd[636]: Stopping usertest.service...1342machine # [ 35.945186] systemd[636]: Starting usertest.service...1343machine # [ 35.973216] systemd[636]: Finished usertest.service.1344machine # [ 35.980088] systemd[636]: Stopped Run user-specific NixOS activation.1345machine # [ 35.981028] systemd[636]: Stopping Run user-specific NixOS activation...1346machine # [ 35.983701] systemd[636]: Starting Run user-specific NixOS activation...1347machine # [ 36.013862] systemd[636]: Finished Run user-specific NixOS activation.1348machine # [ 36.017290] systemd[1]: Stopped target Reactivate sysinit units.1349machine # [ 36.018905] systemd[1]: Stopping Reactivate sysinit units...1350machine # [ 36.020362] systemd[1]: Reached target Reactivate sysinit units.1351machine # [ 36.021428] systemd[1]: Reached target Remote File Systems.1352machine # [ 36.023390] systemd[1]: Reached target Local File Systems.1353machine # [ 36.027218] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1354machine # [ 36.031889] systemd[1]: Starting Create SUID/SGID Wrappers...1355machine # [ 36.033115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1356machine # [ 36.034292] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671357machine # [ 36.036202] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1358machine # [ 36.037611] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1359machine # [ 36.038762] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1360machine # [ 36.050098] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1361machine # [ 36.051592] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1362machine # [ 36.373719] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1363machine # [ 36.375342] systemd[1]: Finished Create SUID/SGID Wrappers.1364machine # [ 36.380114] systemd[1]: Starting linger-users.service...1365machine # [ 36.424873] systemd[1]: linger-users.service: Deactivated successfully.1366machine # [ 36.426390] systemd[1]: Finished linger-users.service.1367machine # [ 36.681527] nixos[1771]: finished switching to system configuration /nix/store/d9j7pw22w68mmylrhq0iwj3k1wa7f51l-nixos-system-machine-test1368machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.68 seconds)1369machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1370machine # [ 36.721419] sudo[1911]: 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.service1371machine # [ 36.733901] sudo[1911]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1372machine # [ 36.744519] sudo[1911]: pam_unix(sudo:session): session closed for user usertest1373machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1374machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11375machine # [ 37.252111] nixos[1920]: switching to system configuration /nix/store/7bmfpyz7aw9pbw93ny767qaxd38c13v1-nixos-system-machine-test1376machine # [ 37.255662] systemd[1]: Stopped target Local File Systems.1377machine # [ 37.256616] systemd[1]: Stopped target Remote File Systems.1378machine # [ 37.732415] systemd[1]: Reload requested from client PID 1920 ('.switch-to-conf') (unit backdoor.service)...1379machine # [ 37.734143] systemd[1]: Reloading...1380machine # [ 38.229464] systemd[1]: Reloading finished in 493 ms.1381machine # [ 38.354501] systemd[636]: Reexecution requested from client PID 1989 ('switch-to-confi')...1382machine # [ 38.356480] systemd[636]: Reexecuting.1383machine # [ 38.382264] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1384machine # [ 38.564463] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1385machine # [ 38.575341] systemd[636]: Reload requested from client PID 1989 ('switch-to-confi')...1386machine # [ 38.577450] systemd[636]: Reloading...1387machine # [ 38.730279] systemd[636]: Reloading finished in 151 ms.1388machine # [ 38.735841] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1389machine # [ 38.741877] systemd[636]: Reloading usertest.service...1390machine # [ 38.768850] systemd[636]: Reloaded usertest.service.1391machine # [ 38.773832] systemd[636]: Stopped Run user-specific NixOS activation.1392machine # [ 38.775312] systemd[636]: Stopping Run user-specific NixOS activation...1393machine # [ 38.777805] systemd[636]: Starting Run user-specific NixOS activation...1394machine # [ 38.814424] systemd[636]: Finished Run user-specific NixOS activation.1395machine # [ 38.818487] systemd[1]: Stopped target Reactivate sysinit units.1396machine # [ 38.819707] systemd[1]: Stopping Reactivate sysinit units...1397machine # [ 38.821148] systemd[1]: Reached target Reactivate sysinit units.1398machine # [ 38.822663] systemd[1]: Reached target Local File Systems.1399machine # [ 38.824474] systemd[1]: Reached target Remote File Systems.1400machine # [ 38.826956] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1401machine # [ 38.832195] systemd[1]: Starting Create SUID/SGID Wrappers...1402machine # [ 38.833171] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1403machine # [ 38.835261] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671404machine # [ 38.838694] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1405machine # [ 38.840480] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1406machine # [ 38.842736] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1407machine # [ 38.850377] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1408machine # [ 38.851722] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1409machine # [ 39.108311] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1410machine # [ 39.109676] systemd[1]: Finished Create SUID/SGID Wrappers.1411machine # [ 39.114435] systemd[1]: Starting linger-users.service...1412machine # [ 39.156604] systemd[1]: linger-users.service: Deactivated successfully.1413machine # [ 39.158254] systemd[1]: Finished linger-users.service.1414machine # [ 39.412764] nixos[1920]: finished switching to system configuration /nix/store/7bmfpyz7aw9pbw93ny767qaxd38c13v1-nixos-system-machine-test1415machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.67 seconds)1416machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1417machine # [ 39.450571] sudo[2060]: 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.service1418machine # [ 39.461294] sudo[2060]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1419machine # [ 39.471359] sudo[2060]: pam_unix(sudo:session): session closed for user usertest1420machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1421machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11422machine # [ 39.969703] nixos[2069]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1423machine # [ 39.972600] systemd[1]: Stopped target Remote File Systems.1424machine # [ 39.974140] systemd[1]: Stopped target Local File Systems.1425machine # [ 40.321589] systemd[1]: Reload requested from client PID 2069 ('.switch-to-conf') (unit backdoor.service)...1426machine # [ 40.323355] systemd[1]: Reloading...1427machine # [ 40.778858] systemd[1]: Reloading finished in 453 ms.1428machine # [ 40.888967] systemd[636]: Stopped usertest.service.1429machine # [ 40.891772] systemd[636]: Reexecution requested from client PID 2138 ('switch-to-confi')...1430machine # [ 40.892982] systemd[636]: Reexecuting.1431machine # [ 40.921356] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1432machine # [ 41.042164] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1433machine # [ 41.047266] systemd[636]: Reload requested from client PID 2138 ('switch-to-confi')...1434machine # [ 41.048739] systemd[636]: Reloading...1435machine # [ 41.165786] systemd[636]: Reloading finished in 115 ms.1436machine # [ 41.169847] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 41.177243] systemd[636]: Starting usertest.service...1438machine # [ 41.199169] systemd[636]: Finished usertest.service.1439machine # [ 41.202104] systemd[636]: Stopped Run user-specific NixOS activation.1440machine # [ 41.202960] systemd[636]: Stopping Run user-specific NixOS activation...1441machine # [ 41.205491] systemd[636]: Starting Run user-specific NixOS activation...1442machine # [ 41.235771] systemd[636]: Finished Run user-specific NixOS activation.1443machine # [ 41.239379] systemd[1]: Stopped target Reactivate sysinit units.1444machine # [ 41.240748] systemd[1]: Stopping Reactivate sysinit units...1445machine # [ 41.242170] systemd[1]: Reached target Reactivate sysinit units.1446machine # [ 41.246420] systemd[1]: Reached target Local File Systems.1447machine # [ 41.248420] systemd[1]: Reached target Remote File Systems.1448machine # [ 41.249186] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1449machine # [ 41.254163] systemd[1]: Starting Create SUID/SGID Wrappers...1450machine # [ 41.254937] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1451machine # [ 41.256092] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671452machine # [ 41.258045] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1453machine # [ 41.259476] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1454machine # [ 41.260598] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1455machine # [ 41.265593] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1456machine # [ 41.266807] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1457machine # [ 41.521137] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1458machine # [ 41.522649] systemd[1]: Finished Create SUID/SGID Wrappers.1459machine # [ 41.527353] systemd[1]: Starting linger-users.service...1460machine # [ 41.568176] systemd[1]: linger-users.service: Deactivated successfully.1461machine # [ 41.570238] systemd[1]: Finished linger-users.service.1462machine # [ 41.824097] nixos[2069]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1463machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.36 seconds)1464machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1465machine # [ 41.872936] sudo[2209]: 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.service1466machine # [ 41.884578] sudo[2209]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1467machine # [ 41.894219] sudo[2209]: pam_unix(sudo:session): session closed for user usertest1468machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1469machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11470machine # [ 42.344950] nixos[2218]: switching to system configuration /nix/store/0yh0vsslkc427cwbfgzrlb8hkg2s3la8-nixos-system-machine-test1471machine # [ 42.347357] systemd[1]: Stopped target Remote File Systems.1472machine # [ 42.349044] systemd[1]: Stopped target Local File Systems.1473machine # [ 42.753395] systemd[1]: Reload requested from client PID 2218 ('.switch-to-conf') (unit backdoor.service)...1474machine # [ 42.754949] systemd[1]: Reloading...1475machine # [ 43.196552] systemd[1]: Reloading finished in 439 ms.1476machine # [ 43.304335] systemd[636]: Reexecution requested from client PID 2287 ('switch-to-confi')...1477machine # [ 43.306645] systemd[636]: Reexecuting.1478machine # [ 43.332277] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1479machine # [ 43.484884] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1480machine # [ 43.491065] systemd[636]: Reload requested from client PID 2287 ('switch-to-confi')...1481machine # [ 43.492944] systemd[636]: Reloading...1482machine # [ 43.630162] systemd[636]: Reloading finished in 135 ms.1483machine # [ 43.635852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 43.641966] systemd[636]: Reloading usertest.service...1485machine # [ 43.669354] systemd[636]: Reloaded usertest.service.1486machine # [ 43.675068] systemd[636]: Stopped Run user-specific NixOS activation.1487machine # [ 43.675994] systemd[636]: Stopping Run user-specific NixOS activation...1488machine # [ 43.678638] systemd[636]: Starting Run user-specific NixOS activation...1489machine # [ 43.719897] systemd[636]: Finished Run user-specific NixOS activation.1490machine # [ 43.724597] systemd[1]: Stopped target Reactivate sysinit units.1491machine # [ 43.726193] systemd[1]: Stopping Reactivate sysinit units...1492machine # [ 43.727570] systemd[1]: Reached target Reactivate sysinit units.1493machine # [ 43.731901] systemd[1]: Reached target Remote File Systems.1494machine # [ 43.736452] systemd[1]: Starting linger-users.service...1495machine # [ 43.747764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1496machine # [ 43.749644] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1497machine # [ 43.754131] systemd[1]: Reached target Local File Systems.1498machine # [ 43.756159] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1499machine # [ 43.762229] systemd[1]: Starting Create SUID/SGID Wrappers...1500machine # [ 43.763683] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1501machine # [ 43.764893] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671502machine # [ 43.766891] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1503machine # [ 43.768334] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1504machine # [ 43.769532] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1505machine # [ 43.815666] systemd[1]: linger-users.service: Deactivated successfully.1506machine # [ 43.817647] systemd[1]: Finished linger-users.service.1507machine # [ 44.052903] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1508machine # [ 44.054607] systemd[1]: Finished Create SUID/SGID Wrappers.1509machine # [ 44.312432] nixos[2218]: finished switching to system configuration /nix/store/0yh0vsslkc427cwbfgzrlb8hkg2s3la8-nixos-system-machine-test1510machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.42 seconds)1511machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1512machine # [ 44.364623] sudo[2357]: 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.service1513machine # [ 44.379776] sudo[2357]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1514machine # [ 44.387534] sudo[2357]: pam_unix(sudo:session): session closed for user usertest1515machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1516machine: must fail: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11517machine # [ 44.811152] nixos[2366]: switching to system configuration /nix/store/d4721yb2gj1119q7m6vvlbg6yk2vm17q-nixos-system-machine-test1518machine # [ 44.813545] systemd[1]: Stopped target Remote File Systems.1519machine # [ 44.815311] systemd[1]: Stopped target Local File Systems.1520machine # [ 45.202910] systemd[1]: Reload requested from client PID 2366 ('.switch-to-conf') (unit backdoor.service)...1521machine # [ 45.204711] systemd[1]: Reloading...1522machine # [ 45.635898] systemd[1]: Reloading finished in 429 ms.1523machine # [ 45.759382] systemd[636]: Stopped usertest.service.1524machine # [ 45.762549] systemd[636]: Reexecution requested from client PID 2435 ('switch-to-confi')...1525machine # [ 45.763902] systemd[636]: Reexecuting.1526machine # [ 45.789560] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1527machine # [ 45.941584] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1528machine # [ 45.947060] systemd[636]: Reload requested from client PID 2435 ('switch-to-confi')...1529machine # [ 45.948768] systemd[636]: Reloading...1530machine # [ 46.075039] systemd[636]: Reloading finished in 124 ms.1531machine # [ 46.080855] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1532machine # [ 46.087902] systemd[636]: Starting usertest.service...1533machine # [ 46.110980] systemd[636]: usertest.service: Main process exited, code=exited, status=1/FAILURE1534machine # [ 46.112330] systemd[636]: usertest.service: Failed with result 'exit-code'.1535machine # [ 46.114154] systemd[636]: Failed to start usertest.service.1536machine # [ 46.116868] systemd[636]: Stopped Run user-specific NixOS activation.1537machine # [ 46.117940] systemd[636]: Stopping Run user-specific NixOS activation...1538machine # [ 46.120819] systemd[636]: Starting Run user-specific NixOS activation...1539machine # [ 46.147581] systemd[636]: Finished Run user-specific NixOS activation.1540machine # [ 46.151396] systemd[1]: Stopped target Reactivate sysinit units.1541machine # [ 46.152936] systemd[1]: Stopping Reactivate sysinit units...1542machine # [ 46.154115] systemd[1]: Reached target Reactivate sysinit units.1543machine # [ 46.155465] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1544machine # [ 46.157259] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1545machine # [ 46.160236] systemd[1]: Reached target Remote File Systems.1546machine # [ 46.163369] systemd[1]: Starting linger-users.service...1547machine # [ 46.173414] systemd[1]: Reached target Local File Systems.1548machine # [ 46.174396] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1549machine # [ 46.179412] systemd[1]: Starting Create SUID/SGID Wrappers...1550machine # [ 46.180424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1551machine # [ 46.181739] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671552machine # [ 46.185681] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1553machine # [ 46.187746] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1554machine # [ 46.190853] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 46.241694] systemd[1]: linger-users.service: Deactivated successfully.1556machine # [ 46.243952] systemd[1]: Finished linger-users.service.1557machine # [ 46.501620] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1558machine # [ 46.503315] systemd[1]: Finished Create SUID/SGID Wrappers.1559machine # [ 46.758162] nixos[2366]: switching to system configuration /nix/store/d4721yb2gj1119q7m6vvlbg6yk2vm17q-nixos-system-machine-test failed (status 4)1560machine: (finished: must fail: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 2.37 seconds)1561machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11562machine # [ 47.287943] nixos[2508]: switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1563machine # [ 47.290287] systemd[1]: Stopped target Local File Systems.1564machine # [ 47.292061] systemd[1]: Stopped target Remote File Systems.1565machine # [ 47.667925] systemd[1]: Reload requested from client PID 2508 ('.switch-to-conf') (unit backdoor.service)...1566machine # [ 47.669668] systemd[1]: Reloading...1567machine # [ 48.137760] systemd[1]: Reloading finished in 466 ms.1568machine # [ 48.274366] systemd[636]: Reexecution requested from client PID 2577 ('switch-to-confi')...1569machine # [ 48.276454] systemd[636]: Reexecuting.1570machine # [ 48.306796] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1571machine # [ 48.510458] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1572machine # [ 48.516905] systemd[636]: Reload requested from client PID 2577 ('switch-to-confi')...1573machine # [ 48.518286] systemd[636]: Reloading...1574machine # [ 48.690443] systemd[636]: Reloading finished in 169 ms.1575machine # [ 48.695048] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 48.705385] systemd[636]: Starting usertest.service...1577machine # [ 48.739087] systemd[636]: Finished usertest.service.1578machine # [ 48.742450] systemd[636]: Stopped Run user-specific NixOS activation.1579machine # [ 48.743864] systemd[636]: Stopping Run user-specific NixOS activation...1580machine # [ 48.747314] systemd[636]: Starting Run user-specific NixOS activation...1581machine # [ 48.791137] systemd[636]: Finished Run user-specific NixOS activation.1582machine # [ 48.795854] systemd[1]: Stopped target Reactivate sysinit units.1583machine # [ 48.798364] systemd[1]: Stopping Reactivate sysinit units...1584machine # [ 48.800531] systemd[1]: Reached target Reactivate sysinit units.1585machine # [ 48.803216] systemd[1]: Reached target Remote File Systems.1586machine # [ 48.807819] systemd[1]: Starting linger-users.service...1587machine # [ 48.814994] systemd[1]: Reached target Local File Systems.1588machine # [ 48.818955] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1589machine # [ 48.825434] systemd[1]: Starting Create SUID/SGID Wrappers...1590machine # [ 48.826721] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1591machine # [ 48.828614] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671592machine # [ 48.832761] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1593machine # [ 48.835489] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1594machine # [ 48.838979] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1595machine # [ 48.845742] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1596machine # [ 48.847454] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1597machine # [ 48.902121] systemd[1]: linger-users.service: Deactivated successfully.1598machine # [ 48.904407] systemd[1]: Finished linger-users.service.1599machine # [ 49.215874] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1600machine # [ 49.218138] systemd[1]: Finished Create SUID/SGID Wrappers.1601machine # [ 49.473664] nixos[2508]: finished switching to system configuration /nix/store/apczps80kc9hdhrs5i9r3inl5qbj044w-nixos-system-machine-test1602machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.72 seconds)1603machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1604machine # [ 49.524594] sudo[2647]: 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.service1605machine # [ 49.535480] sudo[2647]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1606machine # [ 49.544585] sudo[2647]: pam_unix(sudo:session): session closed for user usertest1607machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1608machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&11609machine # [ 50.132321] nixos[2656]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1610machine # [ 50.136883] systemd[1]: Stopped target Local File Systems.1611machine # [ 50.138632] systemd[1]: Stopped target Remote File Systems.1612machine # [ 50.658907] systemd[1]: Reload requested from client PID 2656 ('.switch-to-conf') (unit backdoor.service)...1613machine # [ 50.661389] systemd[1]: Reloading...1614machine # [ 51.197894] systemd[1]: Reloading finished in 533 ms.1615machine # [ 51.341047] systemd[636]: Stopped usertest.service.1616machine # [ 51.342782] systemd[636]: Reexecution requested from client PID 2725 ('switch-to-confi')...1617machine # [ 51.345630] systemd[636]: Reexecuting.1618machine # [ 51.372852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1619machine # [ 51.496063] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1620machine # [ 51.500300] systemd[636]: Reload requested from client PID 2725 ('switch-to-confi')...1621machine # [ 51.502067] systemd[636]: Reloading...1622machine # [ 51.666113] systemd[636]: Reloading finished in 162 ms.1623machine # [ 51.671057] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1624machine # [ 51.678678] systemd[636]: Stopped Run user-specific NixOS activation.1625machine # [ 51.679762] systemd[636]: Stopping Run user-specific NixOS activation...1626machine # [ 51.683095] systemd[636]: Starting Run user-specific NixOS activation...1627machine # [ 51.729093] systemd[636]: Finished Run user-specific NixOS activation.1628machine # [ 51.732486] systemd[1]: Stopped target Reactivate sysinit units.1629machine # [ 51.733980] systemd[1]: Stopping Reactivate sysinit units...1630machine # [ 51.734955] systemd[1]: Reached target Reactivate sysinit units.1631machine # [ 51.738909] systemd[1]: Reached target Local File Systems.1632machine # [ 51.742087] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1633machine # [ 51.747277] systemd[1]: Starting Create SUID/SGID Wrappers...1634machine # [ 51.748246] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1635machine # [ 51.750287] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671636machine # [ 51.753134] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1637machine # [ 51.755509] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1638machine # [ 51.759052] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1639machine # [ 51.760238] systemd[1]: Reached target Remote File Systems.1640machine # [ 51.764656] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1641machine # [ 51.767070] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1642machine # [ 52.081728] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1643machine # [ 52.083895] systemd[1]: Finished Create SUID/SGID Wrappers.1644machine # [ 52.090282] systemd[1]: Starting linger-users.service...1645machine # [ 52.140917] systemd[1]: linger-users.service: Deactivated successfully.1646machine # [ 52.142548] systemd[1]: Finished linger-users.service.1647machine # [ 52.397426] nixos[2656]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1648machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.86 seconds)1649machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1650machine # [ 52.442473] sudo[2793]: 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.service1651machine # [ 52.453430] sudo[2793]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1652machine # [ 52.465182] sudo[2793]: pam_unix(sudo:session): session closed for user usertest1653machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1654machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1655machine # [ 52.510186] sudo[2799]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1656machine # [ 52.520377] sudo[2799]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1657machine # [ 52.529745] sudo[2799]: pam_unix(sudo:session): session closed for user usertest1658machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1659machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1660machine # [ 52.561928] sudo[2805]: 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.service1661machine # [ 52.571953] sudo[2805]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1662machine # [ 52.582211] sudo[2805]: pam_unix(sudo:session): session closed for user usertest1663machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1664machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1665machine # [ 52.615619] sudo[2811]: 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.service1666machine # [ 52.627357] sudo[2811]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1667machine # [ 52.638577] sudo[2811]: pam_unix(sudo:session): session closed for user usertest1668machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)1669machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1670machine # [ 52.670360] sudo[2817]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1671machine # [ 52.680875] sudo[2817]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1672machine # [ 52.689355] systemd[636]: Reload requested from client PID 2822 ('systemctl')...1673machine # [ 52.691582] systemd[636]: Reloading...1674machine # [ 52.849146] systemd[636]: Reloading finished in 157 ms.1675machine # [ 52.853911] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1676machine # [ 52.862370] sudo[2817]: pam_unix(sudo:session): session closed for user usertest1677machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.22 seconds)1678machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1679machine # [ 52.907802] sudo[2827]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1680machine # [ 52.918423] sudo[2827]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1681machine # [ 52.932159] systemd[636]: Starting migrated.service...1682machine # [ 52.964704] systemd[636]: Finished migrated.service.1683machine # [ 52.968272] sudo[2827]: pam_unix(sudo:session): session closed for user usertest1684machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)1685machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1686machine # [ 53.008320] sudo[2835]: 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.service1687machine # [ 53.019630] sudo[2835]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1688machine # [ 53.030701] sudo[2835]: pam_unix(sudo:session): session closed for user usertest1689machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1690machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1691machine # [ 53.070535] sudo[2841]: 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-owner1692machine # [ 53.081502] sudo[2841]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1693machine # [ 53.092090] sudo[2841]: pam_unix(sudo:session): session closed for user usertest1694machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1695machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1696machine # [ 53.126352] sudo[2847]: 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.service1697machine # [ 53.136417] sudo[2847]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1698machine # [ 53.153291] sudo[2847]: pam_unix(sudo:session): session closed for user usertest1699machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1700machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11701machine # [ 53.646539] nixos[2856]: switching to system configuration /nix/store/igmiy27rz2fpm0yi5rk2i9a3qrnh0als-nixos-system-machine-test1702machine # [ 53.649237] systemd[1]: Stopped target Local File Systems.1703machine # [ 53.650947] systemd[1]: Stopped target Remote File Systems.1704machine # [ 54.094717] systemd[1]: Reload requested from client PID 2856 ('.switch-to-conf') (unit backdoor.service)...1705machine # [ 54.096674] systemd[1]: Reloading...1706machine # [ 54.526800] systemd[1]: Reloading finished in 427 ms.1707machine # [ 54.653351] systemd[636]: Reexecution requested from client PID 2925 ('switch-to-confi')...1708machine # [ 54.654661] systemd[636]: Reexecuting.1709machine # [ 54.678894] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1710machine # [ 54.830492] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1711machine # [ 54.837648] systemd[636]: Reload requested from client PID 2925 ('switch-to-confi')...1712machine # [ 54.839619] systemd[636]: Reloading...1713machine # [ 54.966616] systemd[636]: Reloading finished in 124 ms.1714machine # [ 54.970876] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1715machine # [ 54.977358] systemd[636]: Stopped Run user-specific NixOS activation.1716machine # [ 54.979069] systemd[636]: Stopping Run user-specific NixOS activation...1717machine # [ 54.982227] systemd[636]: Starting Run user-specific NixOS activation...1718machine # [ 55.044839] systemd[636]: Reload requested from client PID 2937 ('systemctl') (unit nixos-activation.service)...1719machine # [ 55.046648] systemd[636]: Reloading...1720machine # [ 55.174740] systemd[636]: Reloading finished in 126 ms.1721machine # [ 55.179084] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1722machine # [ 55.193482] systemd[636]: Stopped migrated.service.1723machine # [ 55.207229] systemd[636]: Finished Run user-specific NixOS activation.1724machine # [ 55.209620] systemd[636]: Reload requested from client PID 2925 ('switch-to-confi')...1725machine # [ 55.211058] systemd[636]: Reloading...1726machine # [ 55.358147] systemd[636]: Reloading finished in 144 ms.1727machine # [ 55.362371] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1728machine # [ 55.374774] systemd[636]: Starting migrated.service...1729machine # [ 55.401636] systemd[636]: Finished migrated.service.1730machine # [ 55.405483] systemd[1]: Stopped target Reactivate sysinit units.1731machine # [ 55.407241] systemd[1]: Stopping Reactivate sysinit units...1732machine # [ 55.407980] systemd[1]: Reached target Reactivate sysinit units.1733machine # [ 55.410919] systemd[1]: Reached target Remote File Systems.1734machine # [ 55.414629] systemd[1]: Starting linger-users.service...1735machine # [ 55.418070] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1736machine # [ 55.420240] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1737machine # [ 55.423971] systemd[1]: Reached target Local File Systems.1738machine # [ 55.431098] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1739machine # [ 55.436353] systemd[1]: Starting Create SUID/SGID Wrappers...1740machine # [ 55.438551] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1741machine # [ 55.440728] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671742machine # [ 55.443584] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1743machine # [ 55.447200] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1744machine # [ 55.448737] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1745machine # [ 55.515277] systemd[1]: linger-users.service: Deactivated successfully.1746machine # [ 55.518152] systemd[1]: Finished linger-users.service.1747machine # [ 55.751529] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1748machine # [ 55.753977] systemd[1]: Finished Create SUID/SGID Wrappers.1749machine # [ 56.011732] nixos[2856]: finished switching to system configuration /nix/store/igmiy27rz2fpm0yi5rk2i9a3qrnh0als-nixos-system-machine-test1750machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.87 seconds)1751machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1752machine # [ 56.065403] sudo[3007]: 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.service1753machine # [ 56.076552] sudo[3007]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1754machine # [ 56.085472] sudo[3007]: pam_unix(sudo:session): session closed for user usertest1755machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1756machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1757machine # [ 56.122160] sudo[3013]: 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.service1758machine # [ 56.132718] sudo[3013]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1759machine # [ 56.153940] sudo[3013]: pam_unix(sudo:session): session closed for user usertest1760machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1761machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1762machine # [ 56.192870] sudo[3019]: 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-owner1763machine # [ 56.204584] sudo[3019]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1764machine # [ 56.218217] sudo[3019]: pam_unix(sudo:session): session closed for user usertest1765machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1766machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&11767machine # [ 56.670624] nixos[3028]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1768machine # [ 56.673045] systemd[1]: Stopped target Remote File Systems.1769machine # [ 56.674780] systemd[1]: Stopped target Local File Systems.1770machine # [ 57.014520] systemd[1]: Reload requested from client PID 3028 ('.switch-to-conf') (unit backdoor.service)...1771machine # [ 57.016202] systemd[1]: Reloading...1772machine # [ 57.496158] systemd[1]: Reloading finished in 477 ms.1773machine # [ 57.615913] systemd[636]: Stopped migrated.service.1774machine # [ 57.617266] systemd[636]: Reexecution requested from client PID 3097 ('switch-to-confi')...1775machine # [ 57.619822] systemd[636]: Reexecuting.1776machine # [ 57.642298] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1777machine # [ 57.774938] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1778machine # [ 57.781242] systemd[636]: Reload requested from client PID 3097 ('switch-to-confi')...1779machine # [ 57.783232] systemd[636]: Reloading...1780machine # [ 57.911472] systemd[636]: Reloading finished in 126 ms.1781machine # [ 57.915852] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1782machine # [ 57.923068] systemd[636]: Stopped Run user-specific NixOS activation.1783machine # [ 57.923941] systemd[636]: Stopping Run user-specific NixOS activation...1784machine # [ 57.926278] systemd[636]: Starting Run user-specific NixOS activation...1785machine # [ 57.953475] systemd[636]: Finished Run user-specific NixOS activation.1786machine # [ 57.956763] systemd[1]: Stopped target Reactivate sysinit units.1787machine # [ 57.958359] systemd[1]: Stopping Reactivate sysinit units...1788machine # [ 57.959778] systemd[1]: Reached target Reactivate sysinit units.1789machine # [ 57.961557] systemd[1]: Reached target Remote File Systems.1790machine # [ 57.965904] systemd[1]: Starting linger-users.service...1791machine # [ 57.972525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1792machine # [ 57.974199] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1793machine # [ 57.979337] systemd[1]: Reached target Local File Systems.1794machine # [ 57.980154] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1795machine # [ 57.985875] systemd[1]: Starting Create SUID/SGID Wrappers...1796machine # [ 57.986792] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1797machine # [ 57.988698] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671798machine # [ 57.991467] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1799machine # [ 57.994158] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1800machine # [ 57.997546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1801machine # [ 58.039368] systemd[1]: linger-users.service: Deactivated successfully.1802machine # [ 58.040902] systemd[1]: Finished linger-users.service.1803machine # [ 58.244917] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1804machine # [ 58.247676] systemd[1]: Finished Create SUID/SGID Wrappers.1805machine # [ 58.502534] nixos[3028]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1806machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.29 seconds)1807machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1808machine # [ 58.550664] sudo[3165]: 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.service1809machine # [ 58.562526] sudo[3165]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1810machine # [ 58.574948] sudo[3165]: pam_unix(sudo:session): session closed for user usertest1811machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1812machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1813machine # [ 58.623507] sudo[3171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1814machine # [ 58.635041] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1815machine # [ 58.644700] sudo[3171]: pam_unix(sudo:session): session closed for user usertest1816machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)1817machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1818machine # [ 58.685487] sudo[3177]: 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.service1819machine # [ 58.695761] sudo[3177]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1820machine # [ 58.704466] sudo[3177]: pam_unix(sudo:session): session closed for user usertest1821machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)1822machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1823machine # [ 58.737865] sudo[3183]: 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.service1824machine # [ 58.747810] sudo[3183]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1825machine # [ 58.756418] sudo[3183]: pam_unix(sudo:session): session closed for user usertest1826machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1827machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1828machine # [ 58.790890] sudo[3189]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1829machine # [ 58.801731] sudo[3189]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1830machine # [ 58.810399] systemd[636]: Reload requested from client PID 3194 ('systemctl')...1831machine # [ 58.813463] systemd[636]: Reloading...1832machine # [ 58.947940] systemd[636]: Reloading finished in 135 ms.1833machine # [ 58.953961] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1834machine # [ 58.962230] sudo[3189]: pam_unix(sudo:session): session closed for user usertest1835machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)1836machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1837machine # [ 58.999839] sudo[3199]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1838machine # [ 59.011219] sudo[3199]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 59.026906] systemd[636]: Starting migrated.service...1840machine # [ 59.056504] systemd[636]: Finished migrated.service.1841machine # [ 59.060704] sudo[3199]: pam_unix(sudo:session): session closed for user usertest1842machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)1843machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1844machine # [ 59.092657] sudo[3207]: 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.service1845machine # [ 59.101860] sudo[3207]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1846machine # [ 59.110810] sudo[3207]: pam_unix(sudo:session): session closed for user usertest1847machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1848machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1849machine # [ 59.144590] sudo[3213]: 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-owner1850machine # [ 59.154649] sudo[3213]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1851machine # [ 59.163506] sudo[3213]: pam_unix(sudo:session): session closed for user usertest1852machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)1853machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1854machine # [ 59.197426] sudo[3219]: 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.service1855machine # [ 59.207512] sudo[3219]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1856machine # [ 59.223090] sudo[3219]: pam_unix(sudo:session): session closed for user usertest1857machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1858machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11859machine # [ 59.688303] nixos[3228]: switching to system configuration /nix/store/6zvhh5mp0g7bqkraj9fmm8khqkz3x1lq-nixos-system-machine-test1860machine # [ 59.691668] systemd[1]: Stopped target Local File Systems.1861machine # [ 59.692891] systemd[1]: Stopped target Remote File Systems.1862machine # [ 60.117598] systemd[1]: Reload requested from client PID 3228 ('.switch-to-conf') (unit backdoor.service)...1863machine # [ 60.119377] systemd[1]: Reloading...1864machine # [ 60.547951] systemd[1]: Reloading finished in 426 ms.1865machine # [ 60.678364] systemd[636]: Reexecution requested from client PID 3297 ('switch-to-confi')...1866machine # [ 60.680909] systemd[636]: Reexecuting.1867machine # [ 60.708612] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1868machine # [ 60.851311] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1869machine # [ 60.856816] systemd[636]: Reload requested from client PID 3297 ('switch-to-confi')...1870machine # [ 60.858529] systemd[636]: Reloading...1871machine # [ 60.981955] systemd[636]: Reloading finished in 122 ms.1872machine # [ 60.986870] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1873machine # [ 60.994146] systemd[636]: Stopped Run user-specific NixOS activation.1874machine # [ 60.995211] systemd[636]: Stopping Run user-specific NixOS activation...1875machine # [ 60.998725] systemd[636]: Starting Run user-specific NixOS activation...1876machine # [ 61.034934] systemd[636]: Reload requested from client PID 3309 ('systemctl') (unit nixos-activation.service)...1877machine # [ 61.036807] systemd[636]: Reloading...1878machine # [ 61.148145] systemd[636]: Reloading finished in 110 ms.1879machine # [ 61.154071] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1880machine # [ 61.167558] systemd[636]: Finished Run user-specific NixOS activation.1881machine # [ 61.169865] systemd[636]: Reload requested from client PID 3297 ('switch-to-confi')...1882machine # [ 61.171692] systemd[636]: Reloading...1883machine # [ 61.318061] systemd[636]: Reloading finished in 144 ms.1884machine # [ 61.322918] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1885machine # [ 61.331559] systemd[636]: Stopped migrated.service.1886machine # [ 61.332932] systemd[636]: Stopping migrated.service...1887machine # [ 61.337100] systemd[636]: Starting migrated.service...1888machine # [ 61.362112] systemd[636]: Finished migrated.service.1889machine # [ 61.371114] systemd[1]: Stopped target Reactivate sysinit units.1890machine # [ 61.373156] systemd[1]: Stopping Reactivate sysinit units...1891machine # [ 61.375175] systemd[1]: Reached target Reactivate sysinit units.1892machine # [ 61.380090] systemd[1]: Reached target Remote File Systems.1893machine # [ 61.385481] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1894machine # [ 61.387867] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1895machine # [ 61.390446] systemd[1]: Reached target Local File Systems.1896machine # [ 61.394282] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1897machine # [ 61.401292] systemd[1]: Starting Create SUID/SGID Wrappers...1898machine # [ 61.403178] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1899machine # [ 61.405202] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671900machine # [ 61.407782] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1901machine # [ 61.409710] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 61.414396] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1903machine # [ 61.658164] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1904machine # [ 61.659354] systemd[1]: Finished Create SUID/SGID Wrappers.1905machine # [ 61.664424] systemd[1]: Starting linger-users.service...1906machine # [ 61.709837] systemd[1]: linger-users.service: Deactivated successfully.1907machine # [ 61.711195] systemd[1]: Finished linger-users.service.1908machine # [ 61.968648] nixos[3228]: finished switching to system configuration /nix/store/6zvhh5mp0g7bqkraj9fmm8khqkz3x1lq-nixos-system-machine-test1909machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.75 seconds)1910machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1911machine # [ 62.033099] sudo[3378]: 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.service1912machine # [ 62.048626] sudo[3378]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1913machine # [ 62.058267] sudo[3378]: pam_unix(sudo:session): session closed for user usertest1914machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1915machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1916machine # [ 62.089711] sudo[3384]: 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.service1917machine # [ 62.102956] sudo[3384]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1918machine # [ 62.123695] sudo[3384]: pam_unix(sudo:session): session closed for user usertest1919machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1920machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1921machine # [ 62.163601] sudo[3390]: 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-owner1922machine # [ 62.175725] sudo[3390]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1923machine # [ 62.186918] sudo[3390]: pam_unix(sudo:session): session closed for user usertest1924machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1925machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&11926machine # [ 62.650733] nixos[3399]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1927machine # [ 62.653147] systemd[1]: Stopped target Remote File Systems.1928machine # [ 62.655059] systemd[1]: Stopped target Local File Systems.1929machine # [ 63.028404] systemd[1]: Reload requested from client PID 3399 ('.switch-to-conf') (unit backdoor.service)...1930machine # [ 63.030451] systemd[1]: Reloading...1931machine # [ 64.354116] systemd[1]: Reloading finished in 1320 ms.1932machine # [ 64.473286] systemd[636]: Stopped migrated.service.1933machine # [ 64.474467] systemd[636]: Reexecution requested from client PID 3468 ('switch-to-confi')...1934machine # [ 64.477047] systemd[636]: Reexecuting.1935machine # [ 64.505339] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1936machine # [ 64.640334] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1937machine # [ 64.645727] systemd[636]: Reload requested from client PID 3468 ('switch-to-confi')...1938machine # [ 64.647413] systemd[636]: Reloading...1939machine # [ 64.780034] systemd[636]: Reloading finished in 130 ms.1940machine # [ 64.784849] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1941machine # [ 64.791277] systemd[636]: Stopped Run user-specific NixOS activation.1942machine # [ 64.793065] systemd[636]: Stopping Run user-specific NixOS activation...1943machine # [ 64.795494] systemd[636]: Starting Run user-specific NixOS activation...1944machine # [ 64.832388] systemd[636]: Finished Run user-specific NixOS activation.1945machine # [ 64.836419] systemd[1]: Stopped target Reactivate sysinit units.1946machine # [ 64.838041] systemd[1]: Stopping Reactivate sysinit units...1947machine # [ 64.839636] systemd[1]: Reached target Reactivate sysinit units.1948machine # [ 64.842476] systemd[1]: Reached target Local File Systems.1949machine # [ 64.845169] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1950machine # [ 64.849308] systemd[1]: Starting Create SUID/SGID Wrappers...1951machine # [ 64.851631] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1952machine # [ 64.853182] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671953machine # [ 64.857588] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1954machine # [ 64.860155] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1955machine # [ 64.862151] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1956machine # [ 64.865141] systemd[1]: Reached target Remote File Systems.1957machine # [ 64.868634] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1958machine # [ 64.870546] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1959machine # [ 65.125891] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1960machine # [ 65.127089] systemd[1]: Finished Create SUID/SGID Wrappers.1961machine # [ 65.131369] systemd[1]: Starting linger-users.service...1962machine # [ 65.174823] systemd[1]: linger-users.service: Deactivated successfully.1963machine # [ 65.176843] systemd[1]: Finished linger-users.service.1964machine # [ 65.433942] nixos[3399]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test1965machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.25 seconds)1966machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1967machine # [ 65.496554] sudo[3536]: 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.service1968machine # [ 65.512119] sudo[3536]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1969machine # [ 65.523169] sudo[3536]: pam_unix(sudo:session): session closed for user usertest1970machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1971machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1972machine # [ 65.561258] sudo[3542]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1973machine # [ 65.570443] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1974machine # [ 65.579868] sudo[3542]: pam_unix(sudo:session): session closed for user usertest1975machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1976machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1977machine # [ 65.615378] sudo[3548]: 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.service1978machine # [ 65.625970] sudo[3548]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1979machine # [ 65.635852] sudo[3548]: pam_unix(sudo:session): session closed for user usertest1980machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)1981machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1982machine # [ 65.681593] sudo[3554]: 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.service1983machine # [ 65.694732] sudo[3554]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1984machine # [ 65.706156] sudo[3554]: pam_unix(sudo:session): session closed for user usertest1985machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.07 seconds)1986machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1987machine # [ 65.745597] sudo[3560]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1988machine # [ 65.756864] sudo[3560]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1989machine # [ 65.765214] systemd[636]: Reload requested from client PID 3565 ('systemctl')...1990machine # [ 65.767299] systemd[636]: Reloading...1991machine # [ 65.892882] systemd[636]: Reloading finished in 124 ms.1992machine # [ 65.896836] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1993machine # [ 65.904415] sudo[3560]: pam_unix(sudo:session): session closed for user usertest1994machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)1995machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1996machine # [ 65.945713] sudo[3570]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1997machine # [ 65.957528] sudo[3570]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1998machine # [ 65.969553] systemd[636]: Starting migrated.service...1999machine # [ 65.999269] systemd[636]: Finished migrated.service.2000machine # [ 66.002537] sudo[3570]: pam_unix(sudo:session): session closed for user usertest2001machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)2002machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2003machine # [ 66.042668] sudo[3578]: 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.service2004machine # [ 66.055465] sudo[3578]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2005machine # [ 66.065056] sudo[3578]: pam_unix(sudo:session): session closed for user usertest2006machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2007machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2008machine # [ 66.103986] sudo[3584]: 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-owner2009machine # [ 66.114942] sudo[3584]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2010machine # [ 66.124202] sudo[3584]: pam_unix(sudo:session): session closed for user usertest2011machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2012machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2013machine # [ 66.158750] sudo[3590]: 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.service2014machine # [ 66.170492] sudo[3590]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2015machine # [ 66.187624] sudo[3590]: pam_unix(sudo:session): session closed for user usertest2016machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2017machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&12018machine # [ 66.626958] nixos[3599]: switching to system configuration /nix/store/iqz1rm5y1g94lri4xpdd7arz7bxisbzi-nixos-system-machine-test2019machine # [ 66.630559] systemd[1]: Stopped target Local File Systems.2020machine # [ 66.631466] systemd[1]: Stopped target Remote File Systems.2021machine # [ 67.057771] systemd[1]: Reload requested from client PID 3599 ('.switch-to-conf') (unit backdoor.service)...2022machine # [ 67.059600] systemd[1]: Reloading...2023machine # [ 67.729536] systemd[1]: Reloading finished in 667 ms.2024machine # [ 67.852450] systemd[636]: Reexecution requested from client PID 3668 ('switch-to-confi')...2025machine # [ 67.855092] systemd[636]: Reexecuting.2026machine # [ 67.881464] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2027machine # [ 68.024214] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2028machine # [ 68.030158] systemd[636]: Reload requested from client PID 3668 ('switch-to-confi')...2029machine # [ 68.031689] systemd[636]: Reloading...2030machine # [ 68.177167] systemd[636]: Reloading finished in 144 ms.2031machine # [ 68.182106] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2032machine # [ 68.191069] systemd[636]: Stopped Run user-specific NixOS activation.2033machine # [ 68.192036] systemd[636]: Stopping Run user-specific NixOS activation...2034machine # [ 68.194667] systemd[636]: Starting Run user-specific NixOS activation...2035machine # [ 68.243291] systemd[636]: Reload requested from client PID 3680 ('systemctl') (unit nixos-activation.service)...2036machine # [ 68.245173] systemd[636]: Reloading...2037machine # [ 68.377265] systemd[636]: Reloading finished in 130 ms.2038machine # [ 68.382073] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2039machine # [ 68.395296] systemd[636]: Finished Run user-specific NixOS activation.2040machine # [ 68.397941] systemd[636]: Reload requested from client PID 3668 ('switch-to-confi')...2041machine # [ 68.399340] systemd[636]: Reloading...2042machine # [ 68.536406] systemd[636]: Reloading finished in 135 ms.2043machine # [ 68.541917] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 68.550948] systemd[636]: Reloading migrated.service...2045machine # [ 68.574177] systemd[636]: Reloaded migrated.service.2046machine # [ 68.580299] systemd[1]: Stopped target Reactivate sysinit units.2047machine # [ 68.581852] systemd[1]: Stopping Reactivate sysinit units...2048machine # [ 68.583380] systemd[1]: Reached target Reactivate sysinit units.2049machine # [ 68.586678] systemd[1]: Reached target Local File Systems.2050machine # [ 68.587552] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2051machine # [ 68.591282] systemd[1]: Starting Create SUID/SGID Wrappers...2052machine # [ 68.592943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2053machine # [ 68.594748] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672054machine # [ 68.597503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2055machine # [ 68.600813] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2056machine # [ 68.603181] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2057machine # [ 68.604991] systemd[1]: Reached target Remote File Systems.2058machine # [ 68.615475] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2059machine # [ 68.616990] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2060machine # [ 68.880867] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2061machine # [ 68.882663] systemd[1]: Finished Create SUID/SGID Wrappers.2062machine # [ 68.888662] systemd[1]: Starting linger-users.service...2063machine # [ 68.940196] systemd[1]: linger-users.service: Deactivated successfully.2064machine # [ 68.942244] systemd[1]: Finished linger-users.service.2065machine # [ 69.199067] nixos[3599]: finished switching to system configuration /nix/store/iqz1rm5y1g94lri4xpdd7arz7bxisbzi-nixos-system-machine-test2066machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 3.02 seconds)2067machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2068machine # [ 69.262069] sudo[3749]: 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.service2069machine # [ 69.279692] sudo[3749]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2070machine # [ 69.293394] sudo[3749]: pam_unix(sudo:session): session closed for user usertest2071machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2072machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2073machine # [ 69.341763] sudo[3755]: 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-owner2074machine # [ 69.357881] sudo[3755]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2075machine # [ 69.372098] sudo[3755]: pam_unix(sudo:session): session closed for user usertest2076machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.08 seconds)2077machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&12078machine # [ 69.927438] nixos[3764]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2079machine # [ 69.930562] systemd[1]: Stopped target Local File Systems.2080machine # [ 69.932844] systemd[1]: Stopped target Remote File Systems.2081machine # [ 70.414821] systemd[1]: Reload requested from client PID 3764 ('.switch-to-conf') (unit backdoor.service)...2082machine # [ 70.417671] systemd[1]: Reloading...2083machine # [ 71.042416] systemd[1]: Reloading finished in 621 ms.2084machine # [ 71.182542] systemd[636]: Stopped migrated.service.2085machine # [ 71.184618] systemd[636]: Reexecution requested from client PID 3833 ('switch-to-confi')...2086machine # [ 71.187871] systemd[636]: Reexecuting.2087machine # [ 71.223734] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2088machine # [ 71.409213] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2089machine # [ 71.416140] systemd[636]: Reload requested from client PID 3833 ('switch-to-confi')...2090machine # [ 71.418482] systemd[636]: Reloading...2091machine # [ 71.601930] systemd[636]: Reloading finished in 181 ms.2092machine # [ 71.608079] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2093machine # [ 71.618117] systemd[636]: Stopped Run user-specific NixOS activation.2094machine # [ 71.619629] systemd[636]: Stopping Run user-specific NixOS activation...2095machine # [ 71.623038] systemd[636]: Starting Run user-specific NixOS activation...2096machine # [ 71.674751] systemd[636]: Finished Run user-specific NixOS activation.2097machine # [ 71.679848] systemd[1]: Stopped target Reactivate sysinit units.2098machine # [ 71.681887] systemd[1]: Stopping Reactivate sysinit units...2099machine # [ 71.683577] systemd[1]: Reached target Reactivate sysinit units.2100machine # [ 71.686061] systemd[1]: Reached target Remote File Systems.2101machine # [ 71.690924] systemd[1]: Starting linger-users.service...2102machine # [ 71.692739] systemd[1]: Reached target Local File Systems.2103machine # [ 71.699173] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2104machine # [ 71.705230] systemd[1]: Starting Create SUID/SGID Wrappers...2105machine # [ 71.706756] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2106machine # [ 71.709761] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672107machine # [ 71.711886] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2108machine # [ 71.715419] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2109machine # [ 71.719545] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2110machine # [ 71.721633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2111machine # [ 71.724198] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2112machine # [ 71.791932] systemd[1]: linger-users.service: Deactivated successfully.2113machine # [ 71.795666] systemd[1]: Finished linger-users.service.2114machine # [ 72.093654] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2115machine # [ 72.096224] systemd[1]: Finished Create SUID/SGID Wrappers.2116machine # [ 72.352288] nixos[3764]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2117machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.98 seconds)2118machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2119machine # [ 72.395039] sudo[3901]: 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.service2120machine # [ 72.406704] sudo[3901]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2121machine # [ 72.419242] sudo[3901]: pam_unix(sudo:session): session closed for user usertest2122machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2123machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2124machine # [ 72.462902] sudo[3907]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2125machine # [ 72.476374] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2126machine # [ 72.487946] sudo[3907]: pam_unix(sudo:session): session closed for user usertest2127machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.07 seconds)2128machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2129machine # [ 72.523309] sudo[3913]: 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.service2130machine # [ 72.534408] sudo[3913]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2131machine # [ 72.544302] sudo[3913]: pam_unix(sudo:session): session closed for user usertest2132machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2133machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2134machine # [ 72.577073] sudo[3919]: 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.service2135machine # [ 72.587497] sudo[3919]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2136machine # [ 72.597384] sudo[3919]: pam_unix(sudo:session): session closed for user usertest2137machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)2138machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2139machine # [ 72.631335] sudo[3925]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2140machine # [ 72.640710] sudo[3925]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 72.648889] systemd[636]: Reload requested from client PID 3930 ('systemctl')...2142machine # [ 72.650479] systemd[636]: Reloading...2143machine # [ 72.793599] systemd[636]: Reloading finished in 141 ms.2144machine # [ 72.799047] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2145machine # [ 72.806748] sudo[3925]: pam_unix(sudo:session): session closed for user usertest2146machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2147machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2148machine # [ 72.846460] sudo[3935]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2149machine # [ 72.857523] sudo[3935]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2150machine # [ 72.870629] systemd[636]: Starting migrated.service...2151machine # [ 72.898948] systemd[636]: Finished migrated.service.2152machine # [ 72.902581] sudo[3935]: pam_unix(sudo:session): session closed for user usertest2153machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2154machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2155machine # [ 72.940385] sudo[3943]: 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.service2156machine # [ 72.949857] sudo[3943]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2157machine # [ 72.959781] sudo[3943]: pam_unix(sudo:session): session closed for user usertest2158machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2159machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2160machine # [ 72.994032] sudo[3949]: 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-owner2161machine # [ 73.004781] sudo[3949]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2162machine # [ 73.014567] sudo[3949]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2165machine # [ 73.051928] sudo[3955]: 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.service2166machine # [ 73.063276] sudo[3955]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 73.082816] sudo[3955]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2169machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12170machine # [ 73.582742] nixos[3964]: switching to system configuration /nix/store/nx9yamzsyd5gp8bpv0c4b15jny7yiiar-nixos-system-machine-test2171machine # [ 73.586377] systemd[1]: Stopped target Remote File Systems.2172machine # [ 73.587533] systemd[1]: Stopped target Local File Systems.2173machine # [ 74.034064] systemd[1]: Reload requested from client PID 3964 ('.switch-to-conf') (unit backdoor.service)...2174machine # [ 74.035808] systemd[1]: Reloading...2175machine # [ 78.333067] systemd[1]: Reloading finished in 4294 ms.2176machine # [ 78.456723] systemd[636]: Reexecution requested from client PID 4033 ('switch-to-confi')...2177machine # [ 78.459751] systemd[636]: Reexecuting.2178machine # [ 78.485826] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2179machine # [ 78.631294] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2180machine # [ 78.636957] systemd[636]: Reload requested from client PID 4033 ('switch-to-confi')...2181machine # [ 78.638593] systemd[636]: Reloading...2182machine # [ 78.774044] systemd[636]: Reloading finished in 133 ms.2183machine # [ 78.779840] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2184machine # [ 78.787061] systemd[636]: Stopped Run user-specific NixOS activation.2185machine # [ 78.787955] systemd[636]: Stopping Run user-specific NixOS activation...2186machine # [ 78.790744] systemd[636]: Starting Run user-specific NixOS activation...2187machine # [ 78.834934] systemd[636]: Reload requested from client PID 4045 ('systemctl') (unit nixos-activation.service)...2188machine # [ 78.836443] systemd[636]: Reloading...2189machine # [ 78.984945] systemd[636]: Reloading finished in 146 ms.2190machine # [ 78.990058] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2191machine # [ 79.002770] systemd[636]: Finished Run user-specific NixOS activation.2192machine # [ 79.004955] systemd[636]: Reload requested from client PID 4033 ('switch-to-confi')...2193machine # [ 79.006685] systemd[636]: Reloading...2194machine # [ 79.157723] systemd[636]: Reloading finished in 148 ms.2195machine # [ 79.162866] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 79.174074] systemd[1]: Stopped target Reactivate sysinit units.2197machine # [ 79.175601] systemd[1]: Stopping Reactivate sysinit units...2198machine # [ 79.176628] systemd[1]: Reached target Reactivate sysinit units.2199machine # [ 79.181501] systemd[1]: Reached target Local File Systems.2200machine # [ 79.185722] systemd[1]: Reached target Remote File Systems.2201machine # [ 79.189605] systemd[1]: Starting linger-users.service...2202machine # [ 79.192895] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2203machine # [ 79.196841] systemd[1]: Starting Create SUID/SGID Wrappers...2204machine # [ 79.197707] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2205machine # [ 79.199577] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672206machine # [ 79.202373] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2207machine # [ 79.204619] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2208machine # [ 79.206237] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2209machine # [ 79.211278] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2210machine # [ 79.212530] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2211machine # [ 79.271584] systemd[1]: linger-users.service: Deactivated successfully.2212machine # [ 79.274268] systemd[1]: Finished linger-users.service.2213machine # [ 79.498706] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2214machine # [ 79.501243] systemd[1]: Finished Create SUID/SGID Wrappers.2215machine # [ 79.758179] nixos[3964]: finished switching to system configuration /nix/store/nx9yamzsyd5gp8bpv0c4b15jny7yiiar-nixos-system-machine-test2216machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 6.68 seconds)2217machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2218machine # [ 79.827837] sudo[4112]: 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.service2219machine # [ 79.844600] sudo[4112]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2220machine # [ 79.853871] sudo[4112]: pam_unix(sudo:session): session closed for user usertest2221machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2222machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2223machine # [ 79.902131] sudo[4118]: 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-owner2224machine # [ 79.913296] sudo[4118]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2225machine # [ 79.926086] sudo[4118]: pam_unix(sudo:session): session closed for user usertest2226machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2227machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&12228machine # [ 80.386386] nixos[4127]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2229machine # [ 80.389091] systemd[1]: Stopped target Local File Systems.2230machine # [ 80.390292] systemd[1]: Stopped target Remote File Systems.2231machine # [ 80.734596] systemd[1]: Reload requested from client PID 4127 ('.switch-to-conf') (unit backdoor.service)...2232machine # [ 80.736295] systemd[1]: Reloading...2233machine # [ 81.238950] systemd[1]: Reloading finished in 500 ms.2234machine # [ 81.356242] systemd[636]: Stopped migrated.service.2235machine # [ 81.358078] systemd[636]: Reexecution requested from client PID 4196 ('switch-to-confi')...2236machine # [ 81.360511] systemd[636]: Reexecuting.2237machine # [ 81.388411] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2238machine # [ 81.547629] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2239machine # [ 81.553317] systemd[636]: Reload requested from client PID 4196 ('switch-to-confi')...2240machine # [ 81.555059] systemd[636]: Reloading...2241machine # [ 81.692801] systemd[636]: Reloading finished in 136 ms.2242machine # [ 81.698917] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2243machine # [ 81.706858] systemd[636]: Stopped Run user-specific NixOS activation.2244machine # [ 81.707847] systemd[636]: Stopping Run user-specific NixOS activation...2245machine # [ 81.710478] systemd[636]: Starting Run user-specific NixOS activation...2246machine # [ 81.743549] systemd[636]: Finished Run user-specific NixOS activation.2247machine # [ 81.747730] systemd[1]: Stopped target Reactivate sysinit units.2248machine # [ 81.748949] systemd[1]: Stopping Reactivate sysinit units...2249machine # [ 81.750218] systemd[1]: Reached target Reactivate sysinit units.2250machine # [ 81.752315] systemd[1]: Reached target Remote File Systems.2251machine # [ 81.758097] systemd[1]: Starting linger-users.service...2252machine # [ 81.765416] systemd[1]: Reached target Local File Systems.2253machine # [ 81.766312] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2254machine # [ 81.770111] systemd[1]: Starting Create SUID/SGID Wrappers...2255machine # [ 81.770944] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2256machine # [ 81.772719] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672257machine # [ 81.775542] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2258machine # [ 81.778433] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2259machine # [ 81.779983] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2260machine # [ 81.782516] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2261machine # [ 81.784651] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2262machine # [ 81.836591] systemd[1]: linger-users.service: Deactivated successfully.2263machine # [ 81.838588] systemd[1]: Finished linger-users.service.2264machine # [ 82.058856] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2265machine # [ 82.060604] systemd[1]: Finished Create SUID/SGID Wrappers.2266machine # [ 82.315567] nixos[4127]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2267machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.39 seconds)2268machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2269machine # [ 82.371296] sudo[4264]: 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.service2270machine # [ 82.386079] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2271machine # [ 82.405406] sudo[4264]: pam_unix(sudo:session): session closed for user usertest2272machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.09 seconds)2273machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2274machine # [ 82.448242] sudo[4270]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2275machine # [ 82.462971] sudo[4270]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2276machine # [ 82.474102] sudo[4270]: pam_unix(sudo:session): session closed for user usertest2277machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.07 seconds)2278machine: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service2279machine # [ 82.518971] sudo[4276]: 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.service2280machine # [ 82.531775] sudo[4276]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2281machine # [ 82.545494] sudo[4276]: pam_unix(sudo:session): session closed for user usertest2282machine: (finished: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.07 seconds)2283machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2284machine # [ 82.592146] sudo[4282]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2285machine # [ 82.604629] sudo[4282]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2286machine # [ 82.613686] systemd[636]: Reload requested from client PID 4287 ('systemctl')...2287machine # [ 82.616055] systemd[636]: Reloading...2288machine # [ 82.770499] systemd[636]: Reloading finished in 153 ms.2289machine # [ 82.774106] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2290machine # [ 82.782868] sudo[4282]: pam_unix(sudo:session): session closed for user usertest2291machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.24 seconds)2292machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2293machine # [ 82.826844] sudo[4292]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2294machine # [ 82.838645] sudo[4292]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2295machine # [ 82.851691] systemd[636]: Starting migrated.service...2296machine # [ 82.892154] systemd[636]: Finished migrated.service.2297machine # [ 82.896210] sudo[4292]: pam_unix(sudo:session): session closed for user usertest2298machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.11 seconds)2299machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2300machine # [ 82.946792] sudo[4300]: 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.service2301machine # [ 82.960483] sudo[4300]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2302machine # [ 82.969574] sudo[4300]: pam_unix(sudo:session): session closed for user usertest2303machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2304machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2305machine # [ 83.013333] sudo[4306]: 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-owner2306machine # [ 83.025970] sudo[4306]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2307machine # [ 83.036453] sudo[4306]: pam_unix(sudo:session): session closed for user usertest2308machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2309machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2310machine # [ 83.074590] sudo[4312]: 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.service2311machine # [ 83.086672] sudo[4312]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2312machine # [ 83.138245] sudo[4312]: pam_unix(sudo:session): session closed for user usertest2313machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2314machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12315machine # [ 83.658549] nixos[4321]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2316machine # [ 83.661255] systemd[1]: Stopped target Local File Systems.2317machine # [ 83.663098] systemd[1]: Stopped target Remote File Systems.2318machine # [ 84.130741] systemd[1]: Reload requested from client PID 4321 ('.switch-to-conf') (unit backdoor.service)...2319machine # [ 84.132697] systemd[1]: Reloading...2320machine # [ 84.679327] systemd[1]: Reloading finished in 544 ms.2321machine # [ 84.788692] systemd[636]: Reexecution requested from client PID 4390 ('switch-to-confi')...2322machine # [ 84.790545] systemd[636]: Reexecuting.2323machine # [ 84.819286] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2324machine # [ 84.971358] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2325machine # [ 84.977648] systemd[636]: Reload requested from client PID 4390 ('switch-to-confi')...2326machine # [ 84.979698] systemd[636]: Reloading...2327machine # [ 85.122720] systemd[636]: Reloading finished in 140 ms.2328machine # [ 85.127991] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2329machine # [ 85.136085] systemd[636]: Stopped Run user-specific NixOS activation.2330machine # [ 85.137144] systemd[636]: Stopping Run user-specific NixOS activation...2331machine # [ 85.140487] systemd[636]: Starting Run user-specific NixOS activation...2332machine # [ 85.173689] systemd[636]: Finished Run user-specific NixOS activation.2333machine # [ 85.176449] systemd[636]: Reload requested from client PID 4390 ('switch-to-confi')...2334machine # [ 85.178307] systemd[636]: Reloading...2335machine # [ 85.321798] systemd[636]: Reloading finished in 142 ms.2336machine # [ 85.327871] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2337machine # [ 85.336107] systemd[636]: Stopped migrated.service.2338machine # [ 85.336873] systemd[636]: Stopping migrated.service...2339machine # [ 85.339178] systemd[636]: Starting migrated.service...2340machine # [ 85.369952] systemd[636]: Finished migrated.service.2341machine # [ 85.376158] systemd[1]: Stopped target Reactivate sysinit units.2342machine # [ 85.377944] systemd[1]: Stopping Reactivate sysinit units...2343machine # [ 85.378862] systemd[1]: Reached target Reactivate sysinit units.2344machine # [ 85.380791] systemd[1]: Reached target Local File Systems.2345machine # [ 85.383170] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2346machine # [ 85.387059] systemd[1]: Starting Create SUID/SGID Wrappers...2347machine # [ 85.387911] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2348machine # [ 85.389090] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672349machine # [ 85.391136] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2350machine # [ 85.394749] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2351machine # [ 85.396169] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2352machine # [ 85.401598] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2353machine # [ 85.403119] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2354machine # [ 85.410761] systemd[1]: Reached target Remote File Systems.2355machine # [ 85.694781] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2356machine # [ 85.696683] systemd[1]: Finished Create SUID/SGID Wrappers.2357machine # [ 85.701301] systemd[1]: Starting linger-users.service...2358machine # [ 85.747483] systemd[1]: linger-users.service: Deactivated successfully.2359machine # [ 85.749098] systemd[1]: Finished linger-users.service.2360machine # [ 86.006884] nixos[4321]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2361machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.87 seconds)2362machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2363machine # [ 86.065904] sudo[4464]: 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.service2364machine # [ 86.083358] sudo[4464]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2365machine # [ 86.093973] sudo[4464]: pam_unix(sudo:session): session closed for user usertest2366machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2368machine # [ 86.134474] sudo[4470]: 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.service2369machine # [ 86.145850] sudo[4470]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2370machine # [ 86.166767] sudo[4470]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2373machine # [ 86.200728] sudo[4476]: 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-owner2374machine # [ 86.210848] sudo[4476]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 86.220292] sudo[4476]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2377machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12378machine # [ 86.739581] nixos[4485]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2379machine # [ 86.742467] systemd[1]: Stopped target Remote File Systems.2380machine # [ 86.744663] systemd[1]: Stopped target Local File Systems.2381machine # [ 87.118775] systemd[1]: Reload requested from client PID 4485 ('.switch-to-conf') (unit backdoor.service)...2382machine # [ 87.120684] systemd[1]: Reloading...2383machine # [ 87.666102] systemd[1]: Reloading finished in 542 ms.2384machine # [ 87.774348] systemd[636]: Reexecution requested from client PID 4554 ('switch-to-confi')...2385machine # [ 87.776840] systemd[636]: Reexecuting.2386machine # [ 87.803263] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2387machine # [ 87.936152] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2388machine # [ 87.941748] systemd[636]: Reload requested from client PID 4554 ('switch-to-confi')...2389machine # [ 87.943801] systemd[636]: Reloading...2390machine # [ 88.105097] systemd[636]: Reloading finished in 159 ms.2391machine # [ 88.110059] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2392machine # [ 88.118186] systemd[636]: Stopped Run user-specific NixOS activation.2393machine # [ 88.119260] systemd[636]: Stopping Run user-specific NixOS activation...2394machine # [ 88.123183] systemd[636]: Starting Run user-specific NixOS activation...2395machine # [ 88.169044] systemd[636]: Finished Run user-specific NixOS activation.2396machine # [ 88.173646] systemd[1]: Stopped target Reactivate sysinit units.2397machine # [ 88.174971] systemd[1]: Stopping Reactivate sysinit units...2398machine # [ 88.176245] systemd[1]: Reached target Reactivate sysinit units.2399machine # [ 88.178475] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2400machine # [ 88.180202] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2401machine # [ 88.183418] systemd[1]: Reached target Remote File Systems.2402machine # [ 88.187685] systemd[1]: Starting linger-users.service...2403machine # [ 88.193684] systemd[1]: Reached target Local File Systems.2404machine # [ 88.200070] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2405machine # [ 88.205309] systemd[1]: Starting Create SUID/SGID Wrappers...2406machine # [ 88.206977] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2407machine # [ 88.208884] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672408machine # [ 88.211451] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2409machine # [ 88.213607] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2410machine # [ 88.215086] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2411machine # [ 88.265725] systemd[1]: linger-users.service: Deactivated successfully.2412machine # [ 88.269072] systemd[1]: Finished linger-users.service.2413machine # [ 88.632877] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2414machine # [ 88.634949] systemd[1]: Finished Create SUID/SGID Wrappers.2415machine # [ 88.895697] nixos[4485]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2416machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.69 seconds)2417machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2418machine # [ 88.985760] sudo[4622]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2419machine # [ 89.000624] sudo[4622]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2420machine # [ 89.018087] sudo[4622]: pam_unix(sudo:session): session closed for user usertest2421machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.11 seconds)2422machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2423machine # [ 89.083268] sudo[4628]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2424machine # [ 89.101509] sudo[4628]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2425machine # [ 89.114716] systemd[636]: Reload requested from client PID 4633 ('systemctl')...2426machine # [ 89.116620] systemd[636]: Reloading...2427machine # [ 89.335887] systemd[636]: Reloading finished in 216 ms.2428machine # [ 89.342097] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2429machine # [ 89.352706] sudo[4628]: pam_unix(sudo:session): session closed for user usertest2430machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.33 seconds)2431machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&12432machine # [ 89.860263] nixos[4641]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2433machine # [ 89.863272] systemd[1]: Stopped target Remote File Systems.2434machine # [ 89.865455] systemd[1]: Stopped target Local File Systems.2435machine # [ 90.313578] systemd[1]: Reload requested from client PID 4641 ('.switch-to-conf') (unit backdoor.service)...2436machine # [ 90.315936] systemd[1]: Reloading...2437machine # [ 90.868349] systemd[1]: Reloading finished in 549 ms.2438machine # [ 90.998330] systemd[636]: Stopped migrated.service.2439machine # [ 91.000960] systemd[636]: Reexecution requested from client PID 4710 ('switch-to-confi')...2440machine # [ 91.002220] systemd[636]: Reexecuting.2441machine # [ 91.027163] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2442machine # [ 91.163210] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2443machine # [ 91.168611] systemd[636]: Reload requested from client PID 4710 ('switch-to-confi')...2444machine # [ 91.170453] systemd[636]: Reloading...2445machine # [ 91.301620] systemd[636]: Reloading finished in 129 ms.2446machine # [ 91.305871] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2447machine # [ 91.312581] systemd[636]: Stopped Run user-specific NixOS activation.2448machine # [ 91.314201] systemd[636]: Stopping Run user-specific NixOS activation...2449machine # [ 91.317051] systemd[636]: Starting Run user-specific NixOS activation...2450machine # [ 91.355366] systemd[636]: Finished Run user-specific NixOS activation.2451machine # [ 91.358975] systemd[1]: Stopped target Reactivate sysinit units.2452machine # [ 91.360167] systemd[1]: Stopping Reactivate sysinit units...2453machine # [ 91.360938] systemd[1]: Reached target Reactivate sysinit units.2454machine # [ 91.365074] systemd[1]: Reached target Local File Systems.2455machine # [ 91.367064] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2456machine # [ 91.370877] systemd[1]: Starting Create SUID/SGID Wrappers...2457machine # [ 91.371722] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2458machine # [ 91.372899] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672459machine # [ 91.376739] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2460machine # [ 91.379144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2461machine # [ 91.382229] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2462machine # [ 91.383466] systemd[1]: Reached target Remote File Systems.2463machine # [ 91.389944] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2464machine # [ 91.391332] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2465machine # [ 91.643716] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2466machine # [ 91.644957] systemd[1]: Finished Create SUID/SGID Wrappers.2467machine # [ 91.649655] systemd[1]: Starting linger-users.service...2468machine # [ 91.705444] systemd[1]: linger-users.service: Deactivated successfully.2469machine # [ 91.707314] systemd[1]: Finished linger-users.service.2470machine # [ 91.964709] nixos[4641]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2471machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.61 seconds)2472machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2473machine # [ 92.031781] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2474machine # [ 92.048579] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2475machine # [ 92.057827] sudo[4778]: pam_unix(sudo:session): session closed for user usertest2476machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.09 seconds)2477machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2478machine # [ 92.090479] sudo[4784]: 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.service2479machine # [ 92.102287] sudo[4784]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2480machine # [ 92.113154] sudo[4784]: pam_unix(sudo:session): session closed for user usertest2481machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2482machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2483machine # [ 92.151558] sudo[4790]: 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.service2484machine # [ 92.163329] sudo[4790]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2485machine # [ 92.173322] sudo[4790]: pam_unix(sudo:session): session closed for user usertest2486machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)2487machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2488machine # [ 92.210562] sudo[4796]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2489machine # [ 92.221567] sudo[4796]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2490machine # [ 92.228979] systemd[636]: Reload requested from client PID 4801 ('systemctl')...2491machine # [ 92.231151] systemd[636]: Reloading...2492machine # [ 92.372471] systemd[636]: Reloading finished in 140 ms.2493machine # [ 92.377060] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2494machine # [ 92.386240] sudo[4796]: pam_unix(sudo:session): session closed for user usertest2495machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2496machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2497machine # [ 92.427410] sudo[4806]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2498machine # [ 92.438518] sudo[4806]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2499machine # [ 92.451507] systemd[636]: Starting migrated.service...2500machine # [ 92.484041] systemd[636]: Finished migrated.service.2501machine # [ 92.488061] sudo[4806]: pam_unix(sudo:session): session closed for user usertest2502machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)2503machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2504machine # [ 92.527189] sudo[4814]: 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.service2505machine # [ 92.538513] sudo[4814]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2506machine # [ 92.548311] sudo[4814]: pam_unix(sudo:session): session closed for user usertest2507machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2508machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2509machine # [ 92.589638] sudo[4820]: 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-owner2510machine # [ 92.602549] sudo[4820]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2511machine # [ 92.613054] sudo[4820]: pam_unix(sudo:session): session closed for user usertest2512machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2513machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2514machine # [ 92.652377] sudo[4826]: 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.service2515machine # [ 92.663503] sudo[4826]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2516machine # [ 92.682453] sudo[4826]: pam_unix(sudo:session): session closed for user usertest2517machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2518machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12519machine # [ 93.123884] nixos[4835]: switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2520machine # [ 93.126272] systemd[1]: Stopped target Local File Systems.2521machine # [ 93.128310] systemd[1]: Stopped target Remote File Systems.2522machine # [ 93.491791] systemd[1]: Reload requested from client PID 4835 ('.switch-to-conf') (unit backdoor.service)...2523machine # [ 93.493697] systemd[1]: Reloading...2524machine # [ 93.970728] systemd[1]: Reloading finished in 474 ms.2525machine # [ 94.068324] systemd[636]: Reexecution requested from client PID 4904 ('switch-to-confi')...2526machine # [ 94.070443] systemd[636]: Reexecuting.2527machine # [ 94.098342] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2528machine # [ 94.232793] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2529machine # [ 94.238890] systemd[636]: Reload requested from client PID 4904 ('switch-to-confi')...2530machine # [ 94.240170] systemd[636]: Reloading...2531machine # [ 94.378163] systemd[636]: Reloading finished in 136 ms.2532machine # [ 94.384119] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2533machine # [ 94.392349] systemd[636]: Stopped Run user-specific NixOS activation.2534machine # [ 94.394146] systemd[636]: Stopping Run user-specific NixOS activation...2535machine # [ 94.396579] systemd[636]: Starting Run user-specific NixOS activation...2536machine # [ 94.429852] systemd[636]: Finished Run user-specific NixOS activation.2537machine # [ 94.431828] systemd[636]: Reload requested from client PID 4904 ('switch-to-confi')...2538machine # [ 94.434235] systemd[636]: Reloading...2539machine # [ 94.575950] systemd[636]: Reloading finished in 140 ms.2540machine # [ 94.582112] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2541machine # [ 94.595267] systemd[1]: Stopped target Reactivate sysinit units.2542machine # [ 94.596967] systemd[1]: Stopping Reactivate sysinit units...2543machine # [ 94.598328] systemd[1]: Reached target Reactivate sysinit units.2544machine # [ 94.600091] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2545machine # [ 94.601826] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2546machine # [ 94.605443] systemd[1]: Reached target Remote File Systems.2547machine # [ 94.609391] systemd[1]: Starting linger-users.service...2548machine # [ 94.615051] systemd[1]: Reached target Local File Systems.2549machine # [ 94.622258] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2550machine # [ 94.627401] systemd[1]: Starting Create SUID/SGID Wrappers...2551machine # [ 94.628498] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2552machine # [ 94.631958] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672553machine # [ 94.634952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2554machine # [ 94.636990] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2555machine # [ 94.638838] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2556machine # [ 94.688532] systemd[1]: linger-users.service: Deactivated successfully.2557machine # [ 94.691764] systemd[1]: Finished linger-users.service.2558machine # [ 94.938752] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2559machine # [ 94.941968] systemd[1]: Finished Create SUID/SGID Wrappers.2560machine # [ 95.195283] nixos[4835]: finished switching to system configuration /nix/store/r5qm0l8z1x8gzbaqb9pj38q7s6js36ml-nixos-system-machine-test2561machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.51 seconds)2562machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2563machine # [ 95.240943] sudo[4976]: 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.service2564machine # [ 95.252775] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2565machine # [ 95.271753] sudo[4976]: pam_unix(sudo:session): session closed for user usertest2566machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2567machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2568machine # [ 95.312422] sudo[4982]: 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-owner2569machine # [ 95.324391] sudo[4982]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2570machine # [ 95.335157] sudo[4982]: pam_unix(sudo:session): session closed for user usertest2571machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2572machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2573machine # [ 95.375328] sudo[4988]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2574machine # [ 95.386533] sudo[4988]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2575machine # [ 95.397556] sudo[4988]: pam_unix(sudo:session): session closed for user usertest2576machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.06 seconds)2577machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2578machine # [ 95.439592] sudo[4994]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2579machine # [ 95.451726] sudo[4994]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2580machine # [ 95.460183] systemd[636]: Reload requested from client PID 4999 ('systemctl')...2581machine # [ 95.462373] systemd[636]: Reloading...2582machine # [ 95.645048] systemd[636]: Reloading finished in 181 ms.2583machine # [ 95.649863] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2584machine # [ 95.658438] sudo[4994]: pam_unix(sudo:session): session closed for user usertest2585machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.26 seconds)2586machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2587machine # [ 95.699743] sudo[5004]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2588machine # [ 95.711366] sudo[5004]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2589machine # [ 95.722847] systemd[636]: Stopped migrated.service.2590machine # [ 95.726277] sudo[5004]: pam_unix(sudo:session): session closed for user usertest2591machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.07 seconds)2592machine: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&12593machine # [ 96.182212] nixos[5013]: switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2594machine # [ 96.184650] systemd[1]: Stopped target Remote File Systems.2595machine # [ 96.186425] systemd[1]: Stopped target Local File Systems.2596machine # [ 96.568894] systemd[1]: Reload requested from client PID 5013 ('.switch-to-conf') (unit backdoor.service)...2597machine # [ 96.570616] systemd[1]: Reloading...2598machine # [ 97.047869] systemd[1]: Reloading finished in 475 ms.2599machine # [ 97.154609] systemd[636]: Reexecution requested from client PID 5082 ('switch-to-confi')...2600machine # [ 97.156389] systemd[636]: Reexecuting.2601machine # [ 97.181143] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2602machine # [ 97.304561] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2603machine # [ 97.309951] systemd[636]: Reload requested from client PID 5082 ('switch-to-confi')...2604machine # [ 97.311373] systemd[636]: Reloading...2605machine # [ 97.436592] systemd[636]: Reloading finished in 123 ms.2606machine # [ 97.441868] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2607machine # [ 97.448204] systemd[636]: Stopped Run user-specific NixOS activation.2608machine # [ 97.450069] systemd[636]: Stopping Run user-specific NixOS activation...2609machine # [ 97.452206] systemd[636]: Starting Run user-specific NixOS activation...2610machine # [ 97.483576] systemd[636]: Finished Run user-specific NixOS activation.2611machine # [ 97.487573] systemd[1]: Stopped target Reactivate sysinit units.2612machine # [ 97.488536] systemd[1]: Stopping Reactivate sysinit units...2613machine # [ 97.489448] systemd[1]: Reached target Reactivate sysinit units.2614machine # [ 97.491430] systemd[1]: Reached target Remote File Systems.2615machine # [ 97.495262] systemd[1]: Starting linger-users.service...2616machine # [ 97.502923] systemd[1]: Reached target Local File Systems.2617machine # [ 97.510637] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2618machine # [ 97.515990] systemd[1]: Starting Create SUID/SGID Wrappers...2619machine # [ 97.516949] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2620machine # [ 97.519282] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672621machine # [ 97.522890] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2622machine # [ 97.525248] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2623machine # [ 97.528399] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2624machine # [ 97.529754] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2625machine # [ 97.531357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2626machine # [ 97.572395] systemd[1]: linger-users.service: Deactivated successfully.2627machine # [ 97.574624] systemd[1]: Finished linger-users.service.2628machine # [ 97.800098] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2629machine # [ 97.802386] systemd[1]: Finished Create SUID/SGID Wrappers.2630machine # [ 98.056713] nixos[5013]: finished switching to system configuration /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test2631machine: (finished: must succeed: /nix/store/z44kcj5yc4gj8p301y54y84cqsn1z3pm-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.34 seconds)2632(finished: subtest: user services, in 72.76 seconds)2633(finished: run the VM test script, in 98.94 seconds)2634test script finished in 99.70s2635cleanup2636kill QemuMachine (pid 45)2637machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2638(finished: cleanup, in 0.17 seconds)2639additionally exposed symbols:2640 machine, other,2641 vlan1,2642 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