vm-test-run-switch-test-user
checks.x86_64-linux.switchTest-user
· build #36
· 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.uRPqm6rYed', 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: 4033415b-4a95-4cab-bca8-4c27543dc2bd17machine # 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 # Starting virtiofs daemons...27machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-09-15T03:07:34Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-09-15T03:07:34Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-09-15T03:07:34Z INFO virtiofsd] Client connected, servicing requests39machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)40machine # 41machine # 42machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0043machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...44machine # 45machine # 46machine # 47machine # 48machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0049machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...50machine # 51machine # 52machine # Booting from ROM...53machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 202654machine # [ 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/init regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-closure-info/registration console=ttyS0,115200n8 console=tty055machine # [ 0.000000] BIOS-provided physical RAM map:56machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable57machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved58machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved59machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable60machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved61machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved62machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved63machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved64machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved65machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved66machine # [ 0.000000] NX (Execute Disable) protection: active67machine # [ 0.000000] APIC: Static calls initialized68machine # [ 0.000000] SMBIOS 2.8 present.69machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201470machine # [ 0.000000] DMI: Memory slots populated: 1/171machine # [ 0.000000] Hypervisor detected: KVM72machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000073machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0074machine # [ 0.000000] kvm-clock: using sched offset of 472763266 cycles75machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76machine # [ 0.000004] tsc: Detected 2400.012 MHz processor77machine # [ 0.000804] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078machine # [ 0.000837] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79machine # [ 0.000839] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80machine # [ 0.002724] found SMP MP-table at [mem 0x000f5450-0x000f545f]81machine # [ 0.002735] Using GB pages for direct mapping82machine # [ 0.002772] RAMDISK: [mem 0x3e3d9000-0x3ffcffff]83machine # [ 0.002779] ACPI: Early table checksum verification disabled84machine # [ 0.002781] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85machine # [ 0.002784] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86machine # [ 0.002788] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002794] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002797] ACPI: FACS 0x000000003FFE0000 00004089machine # [ 0.002798] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90machine # [ 0.002800] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002801] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002803] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002804] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94machine # [ 0.002805] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95machine # [ 0.002806] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96machine # [ 0.002806] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97machine # [ 0.002807] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98machine # [ 0.002807] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99machine # [ 0.002808] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100machine # [ 0.003030] No NUMA configuration found101machine # [ 0.003031] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102machine # [ 0.003033] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103machine # [ 0.005206] Zone ranges:104machine # [ 0.005207] DMA [mem 0x0000000000001000-0x0000000000ffffff]105machine # [ 0.005209] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106machine # [ 0.005210] Normal empty107machine # [ 0.005211] Device empty108machine # [ 0.005211] Movable zone start for each node109machine # [ 0.005212] Early memory node ranges110machine # [ 0.005212] node 0: [mem 0x0000000000001000-0x000000000009efff]111machine # [ 0.005213] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112machine # [ 0.005215] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113machine # [ 0.005233] On node 0, zone DMA: 1 pages in unavailable ranges114machine # [ 0.005473] On node 0, zone DMA: 97 pages in unavailable ranges115machine # [ 0.021678] On node 0, zone DMA32: 40 pages in unavailable ranges116machine # [ 0.022118] ACPI: PM-Timer IO Port: 0x608117machine # [ 0.022127] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118machine # [ 0.022151] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119machine # [ 0.022154] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120machine # [ 0.022155] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121machine # [ 0.022156] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122machine # [ 0.022157] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123machine # [ 0.022158] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124machine # [ 0.022160] ACPI: Using ACPI (MADT) for SMP configuration information125machine # [ 0.022161] ACPI: HPET id: 0x8086a201 base: 0xfed00000126machine # [ 0.022164] TSC deadline timer available127machine # [ 0.022168] CPU topo: Max. logical packages: 1128machine # [ 0.022168] CPU topo: Max. logical dies: 1129machine # [ 0.022169] CPU topo: Max. dies per package: 1130machine # [ 0.022172] CPU topo: Max. threads per core: 1131machine # [ 0.022173] CPU topo: Num. cores per package: 1132machine # [ 0.022173] CPU topo: Num. threads per package: 1133machine # [ 0.022173] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134machine # [ 0.022188] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135machine # [ 0.022214] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136machine # [ 0.022216] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137machine # [ 0.022217] [mem 0x40000000-0xafffffff] available for PCI devices138machine # [ 0.022218] Booting paravirtualized kernel on KVM139machine # [ 0.022220] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140machine # [ 0.026642] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141machine # [ 0.028658] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142machine # [ 0.028697] kvm-guest: PV spinlocks disabled, single CPU143machine # [ 0.028699] 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/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/init regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-closure-info/registration console=ttyS0,115200n8 console=tty0144machine # [ 0.028788] Unknown kernel command line parameters "regInfo=/nix/store/yhvprbdijx9p7r34dqpk77b6gcwi2vfh-closure-info/registration", will be passed to user space.145machine # [ 0.028800] random: crng init done146machine # [ 0.028801] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147machine # [ 0.029790] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148machine # [ 0.030294] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149machine # [ 0.030322] Fallback order for Node 0: 0150machine # [ 0.030325] Built 1 zonelists, mobility grouping on. Total pages: 262006151machine # [ 0.030326] Policy zone: DMA32152machine # [ 0.032589] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153machine # [ 0.034980] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154machine # [ 0.037061] allocated 2097152 bytes of page_ext155machine # [ 0.046375] ftrace: allocating 48736 entries in 192 pages156machine # [ 0.046376] ftrace: allocated 192 pages with 2 groups157machine # [ 0.047158] Dynamic Preempt: lazy158machine # [ 0.047291] rcu: Preemptible hierarchical RCU implementation.159machine # [ 0.047291] rcu: RCU event tracing is enabled.160machine # [ 0.047292] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.161machine # [ 0.047293] Trampoline variant of Tasks RCU enabled.162machine # [ 0.047294] Rude variant of Tasks RCU enabled.163machine # [ 0.047294] Tracing variant of Tasks RCU enabled.164machine # [ 0.047294] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165machine # [ 0.047295] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166machine # [ 0.047341] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.047343] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.047344] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.051622] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16170machine # [ 0.051877] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.051884] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172machine # [ 0.051983] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173machine # [ 0.055444] Console: colour VGA+ 80x25174machine # [ 0.055448] printk: legacy console [tty0] enabled175machine # [ 0.084503] printk: legacy console [ttyS0] enabled176machine # [ 0.190142] ACPI: Core revision 20250807177machine # [ 0.191026] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178machine # [ 0.192596] APIC: Switch to symmetric I/O mode setup179machine # [ 0.193667] x2apic enabled180machine # [ 0.194405] APIC: Switched APIC routing to: physical x2apic181machine # [ 0.196268] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182machine # [ 0.197299] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns183machine # [ 0.199046] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)184machine # [ 0.200127] x86/cpu: User Mode Instruction Prevention (UMIP) activated185machine # [ 0.202169] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186machine # [ 0.203043] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187machine # [ 0.204047] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188machine # [ 0.205043] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189machine # [ 0.206043] Transient Scheduler Attacks: Vulnerable: No microcode190machine # [ 0.207043] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191machine # [ 0.208043] Speculative Return Stack Overflow: Mitigation: Safe RET192machine # [ 0.209043] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193machine # [ 0.211051] Spectre V2 : Enabling IBPB for BPF194machine # [ 0.212044] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195machine # [ 0.213044] active return thunk: srso_alias_return_thunk196machine # [ 0.214735] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197machine # [ 0.216023] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198machine # [ 0.216848] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199machine # [ 0.218043] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200machine # [ 0.219043] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201machine # [ 0.220043] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202machine # [ 0.221043] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203machine # [ 0.222043] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.223043] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.224832] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.225835] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.226836] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.227844] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209machine # [ 0.261998] Freeing SMP alternatives memory: 44K210machine # [ 0.262611] pid_max: default: 32768 minimum: 301211machine # [ 0.263133] LSM: initializing lsm=capability,landlock,yama,bpf,ima212machine # [ 0.264136] landlock: Up and running.213machine # [ 0.265517] Yama: becoming mindful.214machine # [ 0.266263] LSM support for eBPF active215machine # [ 0.267137] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216machine # [ 0.268063] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.270822] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218machine # [ 0.271551] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219machine # [ 0.272047] ... version: 2220machine # [ 0.272789] ... bit width: 48221machine # [ 0.273046] ... generic counters: 6222machine # [ 0.273815] ... generic bitmap: 000000000000003f223machine # [ 0.274055] ... fixed-purpose counters: 0224machine # [ 0.274813] ... fixed-purpose bitmap: 0000000000000000225machine # [ 0.275745] ... value mask: 0000ffffffffffff226machine # [ 0.276046] ... max period: 00007fffffffffff227machine # [ 0.276980] ... global_ctrl mask: 000000000000003f228machine # [ 0.277909] signal: max sigframe size: 3376229machine # [ 0.278674] rcu: Hierarchical SRCU implementation.230machine # [ 0.279049] rcu: Max phase no-delay instances is 400.231machine # [ 0.284442] smp: Bringing up secondary CPUs ...232machine # [ 0.285059] smp: Brought up 1 node, 1 CPU233machine # [ 0.285780] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)234machine # [ 0.286200] Memory: 941508K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 99160K reserved, 0K cma-reserved)235machine # [ 0.287252] devtmpfs: initialized236machine # [ 0.288071] x86/mm: Memory block size: 128MB237machine # [ 0.289775] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238machine # [ 0.291039] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239machine # [ 0.292130] pinctrl core: initialized pinctrl subsystem240machine # [ 0.293268] PM: RTC time: 03:07:34, date: 2026-09-15241machine # [ 0.296912] NET: Registered PF_NETLINK/PF_ROUTE protocol family242machine # [ 0.298216] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243machine # [ 0.299060] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244machine # [ 0.300192] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245machine # [ 0.301055] audit: initializing netlink subsys (disabled)246machine # [ 0.302220] thermal_sys: Registered thermal governor 'fair_share'247machine # [ 0.302222] thermal_sys: Registered thermal governor 'bang_bang'248machine # [ 0.303049] audit: type=2000 audit(1789441655.342:1): state=initialized audit_enabled=0 res=1249machine # [ 0.305047] thermal_sys: Registered thermal governor 'step_wise'250machine # [ 0.305048] thermal_sys: Registered thermal governor 'user_space'251machine # [ 0.306045] thermal_sys: Registered thermal governor 'power_allocator'252machine # [ 0.307061] cpuidle: using governor menu253machine # [ 0.309299] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254machine # [ 0.310278] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255machine # [ 0.311049] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256machine # [ 0.312057] PCI: Using configuration type 1 for base access257machine # [ 0.313216] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258machine # [ 0.320319] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259machine # [ 0.321047] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260machine # [ 0.326047] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261machine # [ 0.327046] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262machine # [ 0.335628] ACPI: Added _OSI(Module Device)263machine # [ 0.336047] ACPI: Added _OSI(Processor Device)264machine # [ 0.336796] ACPI: Added _OSI(Processor Aggregator Device)265machine # [ 0.343629] ACPI: 1 ACPI AML tables successfully acquired and loaded266machine # [ 0.347012] ACPI: Interpreter enabled267machine # [ 0.347566] ACPI: PM: (supports S0 S3 S4 S5)268machine # [ 0.348046] ACPI: Using IOAPIC for interrupt routing269machine # [ 0.348956] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270machine # [ 0.354046] PCI: Using E820 reservations for host bridge windows271machine # [ 0.355171] ACPI: Enabled 2 GPEs in block 00 to 3F272machine # [ 0.363883] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273machine # [ 0.365412] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274machine # [ 0.366125] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275machine # [ 0.367167] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276machine # [ 0.368382] PCI host bridge to bus 0000:00277machine # [ 0.369050] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278machine # [ 0.370046] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279machine # [ 0.371045] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280machine # [ 0.372046] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281machine # [ 0.373045] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282machine # [ 0.374046] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283machine # [ 0.375046] pci_bus 0000:00: root bus resource [bus 00-ff]284machine # [ 0.376109] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285machine # [ 0.377477] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286machine # [ 0.380132] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287machine # [ 0.381057] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288machine # [ 0.382051] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289machine # [ 0.383334] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290machine # [ 0.385074] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291machine # [ 0.388093] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292machine # [ 0.388995] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293machine # [ 0.389835] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294machine # [ 0.390961] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295machine # [ 0.392419] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296machine # [ 0.394934] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297machine # [ 0.395756] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298machine # [ 0.396067] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299machine # [ 0.397615] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.400086] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.401052] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302machine # [ 0.402070] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303machine # [ 0.403589] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.405944] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305machine # [ 0.406758] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306machine # [ 0.407066] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307machine # [ 0.408051] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.409597] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.411088] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310machine # [ 0.412069] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311machine # [ 0.413603] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312machine # [ 0.415059] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313machine # [ 0.416080] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314machine # [ 0.417711] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315machine # [ 0.419086] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316machine # [ 0.420074] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317machine # [ 0.421620] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318machine # [ 0.423759] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319machine # [ 0.424074] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320machine # [ 0.425615] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321machine # [ 0.427827] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322machine # [ 0.428800] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323machine # [ 0.429072] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324machine # [ 0.430624] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325machine # [ 0.432676] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326machine # [ 0.433056] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327machine # [ 0.434097] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328machine # [ 0.435603] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329machine # [ 0.436751] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330machine # [ 0.437266] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331machine # [ 0.438895] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332machine # [ 0.439841] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333machine # [ 0.441741] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334machine # [ 0.442258] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335machine # [ 0.444800] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336machine # [ 0.445320] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337machine # [ 0.446358] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338machine # [ 0.447289] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339machine # [ 0.449101] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340machine # [ 0.449908] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341machine # [ 0.451072] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342machine # [ 0.453476] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343machine # [ 0.457029] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344machine # [ 0.457845] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345machine # [ 0.458830] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346machine # [ 0.459832] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347machine # [ 0.460825] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348machine # [ 0.461141] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349machine # [ 0.462154] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350machine # [ 0.463147] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351machine # [ 0.464089] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352machine # [ 0.465024] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353machine # [ 0.465749] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354machine # [ 0.466058] ACPI: PCI: Interrupt link GSID configured for IRQ 19355machine # [ 0.466968] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356machine # [ 0.467748] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357machine # [ 0.468057] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358machine # [ 0.468975] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359machine # [ 0.470820] iommu: Default domain type: Translated360machine # [ 0.471699] iommu: DMA domain TLB invalidation policy: lazy mode361machine # [ 0.472330] ACPI: bus type USB registered362machine # [ 0.473092] usbcore: registered new interface driver usbfs363machine # [ 0.473978] usbcore: registered new interface driver hub364machine # [ 0.474702] usbcore: registered new device driver usb365machine # [ 0.475881] NetLabel: Initializing366machine # [ 0.476445] NetLabel: domain hash size = 128367machine # [ 0.477045] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368machine # [ 0.477961] NetLabel: unlabeled traffic allowed by default369machine # [ 0.478720] PCI: Using ACPI for IRQ routing370machine # [ 0.522291] pci 0000:00:01.0: vgaarb: setting as boot VGA device371machine # [ 0.523042] pci 0000:00:01.0: vgaarb: bridge control possible372machine # [ 0.523042] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373machine # [ 0.523050] vgaarb: loaded374machine # [ 0.523744] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375machine # [ 0.524047] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376machine # [ 0.528127] clocksource: Switched to clocksource kvm-clock377machine # [ 0.531469] VFS: Disk quotas dquot_6.6.0378machine # [ 0.532138] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379machine # [ 0.533447] pnp: PnP ACPI init380machine # [ 0.534226] ACPI: IRQ 4 override to edge(!), high(!)381machine # [ 0.535129] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382machine # [ 0.536617] pnp: PnP ACPI: found 5 devices383machine # [ 0.544063] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384machine # [ 0.545459] clocksource: Switched to clocksource acpi_pm385machine # [ 0.546384] NET: Registered PF_INET protocol family386machine # [ 0.547391] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387machine # [ 0.563635] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388machine # [ 0.565077] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389machine # [ 0.566339] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390machine # [ 0.567556] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391machine # [ 0.568760] TCP: Hash tables configured (established 8192 bind 8192)392machine # [ 0.569821] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393machine # [ 0.571028] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394machine # [ 0.572096] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.573336] NET: Registered PF_UNIX/PF_LOCAL protocol family396machine # [ 0.574383] NET: Registered PF_XDP protocol family397machine # [ 0.575271] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398machine # [ 0.576324] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399machine # [ 0.577408] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400machine # [ 0.578534] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401machine # [ 0.579720] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402machine # [ 0.580888] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403machine # [ 0.582647] ACPI: \_SB_.GSIA: Enabled at IRQ 16404machine # [ 0.585003] ACPI: \_SB_.GSIB: Enabled at IRQ 17405machine # [ 0.587111] ACPI: \_SB_.GSIC: Enabled at IRQ 18406machine # [ 0.589317] ACPI: \_SB_.GSID: Enabled at IRQ 19407machine # [ 0.591104] PCI: CLS 0 bytes, default 64408machine # [ 0.592147] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns409machine # [ 0.593944] Trying to unpack rootfs image as initramfs...410machine # [ 0.637964] Initialise system trusted keyrings411machine # [ 0.640230] workingset: timestamp_bits=40 max_order=18 bucket_order=0412machine # [ 0.663354] Key type asymmetric registered413machine # [ 0.664068] Asymmetric key parser 'x509' registered414machine # [ 0.664966] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415machine # [ 0.670322] io scheduler mq-deadline registered416machine # [ 0.671107] io scheduler kyber registered417machine # [ 0.674716] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418machine # [ 0.676002] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419machine # [ 0.679968] Linux agpgart interface v0.103420machine # [ 0.680768] ACPI: bus type drm_connector registered421machine # [ 0.683581] usbcore: registered new interface driver usbserial_generic422machine # [ 0.684663] usbserial: USB Serial support registered for generic423machine # [ 0.688188] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424machine # [ 0.688188] Please enable it if your BIOS has the CPPC option.425machine # [ 0.690456] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426machine # [ 0.694350] drop_monitor: Initializing network drop monitor service427machine # [ 0.695505] NET: Registered PF_INET6 protocol family428machine # [ 0.698603] Segment Routing with IPv6429machine # [ 0.701202] In-situ OAM (IOAM) with IPv6430machine # [ 0.702259] IPI shorthand broadcast: enabled431machine # [ 0.710577] sched_clock: Marking stable (568014312, 142158704)->(784194879, -74021863)432machine # [ 0.715283] registered taskstats version 1433machine # [ 0.716286] Loading compiled-in X.509 certificates434machine # [ 0.734191] Demotion targets for Node 0: null435machine # [ 0.735062] Key type .fscrypt registered436machine # [ 0.738178] Key type fscrypt-provisioning registered437machine # [ 0.739189] ima: No TPM chip found, activating TPM-bypass!438machine # [ 0.740139] ima: Allocated hash algorithm: sha1439machine # [ 0.742207] ima: No architecture policies found440machine # [ 0.743211] PM: Magic number: 10:525:108441machine # [ 0.744786] RAS: Correctable Errors collector initialized.442machine # [ 0.754843] clk: Disabling unused clocks443machine # [ 0.758191] PM: genpd: Disabling unused power domains444machine # [ 0.905877] Freeing initrd memory: 28636K445machine # [ 0.908977] Freeing unused decrypted memory: 2028K446machine # [ 0.911595] Freeing unused kernel image (initmem) memory: 3644K447machine # [ 0.912713] Write protecting the kernel read-only data: 32768k448machine # [ 0.914595] Freeing unused kernel image (text/rodata gap) memory: 1200K449machine # [ 0.916118] Freeing unused kernel image (rodata/data gap) memory: 736K450machine # [ 0.967253] x86/mm: Checked W+X mappings: passed, no W+X pages found.451machine # [ 0.968354] Run /init as init process452machine # [ 0.978775] systemd[1]: Inserted module 'autofs4'453machine # [ 0.998939] fuse: init (API version 7.45)454machine # [ 1.006422] ACPI: \_SB_.GSIG: Enabled at IRQ 22455machine # [ 1.008679] ACPI: \_SB_.GSIH: Enabled at IRQ 23456machine # [ 1.011598] ACPI: \_SB_.GSIE: Enabled at IRQ 20457machine # [ 1.013602] ACPI: \_SB_.GSIF: Enabled at IRQ 21458machine # [ 1.018450] virtiofs virtio5: discovered new tag: nix-store459machine # [ 1.020426] virtiofs virtio5: virtio_fs_setup_dax: No cache capability460machine # [ 1.027558] virtiofs virtio6: discovered new tag: shared461machine # [ 1.029297] virtiofs virtio6: virtio_fs_setup_dax: No cache capability462machine # [ 1.032785] virtiofs virtio7: discovered new tag: xchg463machine # [ 1.034332] virtiofs virtio7: virtio_fs_setup_dax: No cache capability464machine # [ 1.057354] systemd[1]: Successfully made /usr/ read-only.465machine # [ 1.392977] systemd[1]: systemd 262-e2c8482 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)466machine # [ 1.397791] systemd[1]: Detected virtualization kvm.467machine # [ 1.398645] systemd[1]: Detected architecture x86-64.468machine # [ 1.399487] systemd[1]: Running in initrd.469machine # [ 1.400588] systemd[1]: Initializing machine ID from random generator.470machine # [ 1.401874] systemd[1]: Hostname set to <machine>.471machine # [ 1.474932] systemd[1]: bpf-restrict-fs: LSM BPF program attached472machine # [ 1.511914] systemd[1]: Queued start job for default target Initrd Default Target.473machine # [ 1.516506] systemd[1]: Created slice Slice /system/modprobe.474machine # [ 1.517728] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.475machine # [ 1.519209] systemd[1]: Expecting device /dev/disk/by-label/nixos...476machine # [ 1.520366] systemd[1]: Reached target Path Units.477machine # [ 1.521298] systemd[1]: Reached target Slice Units.478machine # [ 1.522213] systemd[1]: Reached target Swaps.479machine # [ 1.523042] systemd[1]: Reached target Timer Units.480machine # [ 1.524062] systemd[1]: Listening on D-Bus System Message Bus Socket.481machine # [ 1.525342] systemd[1]: Listening on Journal Socket (/dev/log).482machine # [ 1.526532] systemd[1]: Listening on Journal Sockets.483machine # [ 1.527544] systemd[1]: Listening on udev Kernel Socket.484machine # [ 1.528579] systemd[1]: Reached target Socket Units.485machine # [ 1.530522] systemd[1]: Starting Create List of Static Device Nodes...486machine # [ 1.534249] systemd[1]: Starting Load Kernel Module configfs...487machine # [ 1.543247] systemd[1]: Starting Journal Service...488machine # [ 1.553281] systemd[1]: Starting Load Kernel Modules...489machine # [ 1.573259] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os490machine # [ 1.582261] systemd[1]: Starting Coldplug All udev Devices...491machine # [ 1.595573] systemd[1]: Finished Create List of Static Device Nodes.492machine # [ 1.598998] systemd[1]: modprobe@configfs.service: Deactivated successfully.493machine # [ 1.605263] systemd-journald[66]: Collecting audit messages is disabled.494machine # [ 1.610581] systemd[1]: Finished Load Kernel Module configfs.495machine # [ 1.613660] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config496machine # [ 1.624975] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.497machine # [ 1.628266] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...498machine # [ 1.636362] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev499machine # [ 1.670000] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.500machine # [ 1.676301] systemd[1]: Finished Load Kernel Modules.501machine # [ 1.683426] systemd[1]: Starting Apply Kernel Variables...502machine # [ 1.693254] systemd[1]: Starting Create Static Device Nodes in /dev...503machine # [ 1.719479] systemd[1]: Started Journal Service.504machine # [ 1.576729] systemd-modules-load[67]: Inserted module 'dm_mod'505machine # [ 1.582076] systemd-modules-load[67]: Inserted module 'virtio_balloon'506machine # [ 1.584190] systemd-modules-load[67]: Inserted module 'virtio_gpu'507machine # [ 1.593240] systemd[1]: Finished Apply Kernel Variables.508machine # [ 1.595161] systemd[1]: Finished Create Static Device Nodes in /dev.509machine # [ 1.599453] systemd[1]: Reached target Preparation for Local File Systems.510machine # [ 1.600454] systemd[1]: Reached target Local File Systems.511machine # [ 1.604076] systemd[1]: Starting Create System Files and Directories...512machine # [ 1.611070] systemd[1]: Starting Rule-based Manager for Device Events and Files...513machine # [ 1.636483] systemd[1]: Finished Create System Files and Directories.514machine # [ 1.659252] systemd-udevd[81]: Using default interface naming scheme 'v261'.515machine # [ 1.682429] systemd[1]: Started Rule-based Manager for Device Events and Files.516machine # [ 1.735078] systemd[1]: Finished Coldplug All udev Devices.517machine # [ 1.737721] systemd[1]: Reached target System Initialization.518machine # [ 1.738603] systemd[1]: Reached target Basic System.519machine # [ 2.066705] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12520machine # [ 2.069283] virtio_blk virtio2: 1/0/0 default/read/poll queues521machine # [ 2.070917] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)522machine # [ 2.075967] serio: i8042 KBD port at 0x60,0x64 irq 1523machine # [ 2.083535] ehci-pci 0000:00:1d.7: EHCI Host Controller524machine # [ 2.084287] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1525machine # [ 2.086017] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000526machine # [ 2.088195] serio: i8042 AUX port at 0x60,0x64 irq 12527machine # [ 2.093408] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00528machine # [ 2.097248] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18529machine # [ 2.098371] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.101557] usb usb1: Product: EHCI Host Controller531machine # [ 2.102366] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd532machine # [ 2.103095] usb usb1: SerialNumber: 0000:00:1d.7533machine # [ 2.105454] hub 1-0:1.0: USB hub found534machine # [ 2.107189] hub 1-0:1.0: 6 ports detected535machine # [ 2.110761] uhci_hcd 0000:00:1d.0: UHCI Host Controller536machine # [ 2.111480] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2537machine # [ 2.123377] uhci_hcd 0000:00:1d.0: detected 2 ports538machine # [ 2.129696] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180539machine # [ 2.136433] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18540machine # [ 2.137524] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1541machine # [ 2.153427] SCSI subsystem initialized542machine # [ 2.155585] usb usb2: Product: UHCI Host Controller543machine # [ 2.161197] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd544machine # [ 2.161934] usb usb2: SerialNumber: 0000:00:1d.0545machine # [ 2.176267] hub 2-0:1.0: USB hub found546machine # [ 2.178275] hub 2-0:1.0: 2 ports detected547machine # [ 2.041111] (udev-worker)[86]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.548machine # [ 2.043505] systemd[1]: Starting Virtual Console Setup...549machine # [ 2.045614] (udev-worker)[84]: Network interface NamePolicy= disabled on kernel command line.550machine # [ 2.048660] (udev-worker)[86]: Network interface NamePolicy= disabled on kernel command line.551machine # [ 2.209617] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0552machine # [ 2.218226] uhci_hcd 0000:00:1d.1: UHCI Host Controller553machine # [ 2.218967] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3554machine # [ 2.088673] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.555machine # [ 2.091307] systemd[1]: Finished Virtual Console Setup.556machine # [ 2.237219] uhci_hcd 0000:00:1d.1: detected 2 ports557machine # [ 2.238040] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0558machine # [ 2.251710] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18559machine # [ 2.252887] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1560machine # [ 2.113060] systemd[1]: Found device /dev/disk/by-label/nixos.561machine # [ 2.113939] systemd[1]: Reached target Initrd Root Device.562machine # [ 2.115163] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...563machine # [ 2.264450] usb usb3: Product: UHCI Host Controller564machine # [ 2.265136] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd565machine # [ 2.271191] usb usb3: SerialNumber: 0000:00:1d.1566machine # [ 2.273127] hub 3-0:1.0: USB hub found567machine # [ 2.277956] hub 3-0:1.0: 2 ports detected568machine # [ 2.283347] uhci_hcd 0000:00:1d.2: UHCI Host Controller569machine # [ 2.284128] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4570machine # [ 2.287214] uhci_hcd 0000:00:1d.2: detected 2 ports571machine # [ 2.288045] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0572machine # [ 2.292483] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18573machine # [ 2.295850] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1574machine # [ 2.297344] usb usb4: Product: UHCI Host Controller575machine # [ 2.298079] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd576machine # [ 2.161207] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks577machine # [ 2.165447] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.578machine # [ 2.310437] usb usb4: SerialNumber: 0000:00:1d.2579machine # [ 2.313336] hub 4-0:1.0: USB hub found580machine # [ 2.314516] hub 4-0:1.0: 2 ports detected581machine # [ 2.340927] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode582machine # [ 2.342058] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)583machine # [ 2.346214] usb 1-1: new high-speed USB device number 2 using ehci-pci584machine # [ 2.354074] ahci 0000:00:1f.2: flags: 64bit ncq only585machine # [ 2.360728] scsi host0: ahci586machine # [ 2.362536] scsi host1: ahci587machine # [ 2.364249] scsi host2: ahci588machine # [ 2.365948] scsi host3: ahci589machine # [ 2.367697] scsi host4: ahci590machine # [ 2.369299] scsi host5: ahci591machine # [ 2.369910] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1592machine # [ 2.372095] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1593machine # [ 2.373329] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1594machine # [ 2.374448] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1595machine # [ 2.375592] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1596machine # [ 2.376746] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1597machine # [ 2.479524] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00598machine # [ 2.482447] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10599machine # [ 2.485916] usb 1-1: Product: QEMU USB Tablet600machine # [ 2.487703] usb 1-1: Manufacturer: QEMU601machine # [ 2.489646] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1602machine # [ 2.523854] hid: raw HID events driver (C) Jiri Kosina603machine # [ 2.453078] systemd[1]: Mounting /sysroot...604machine # [ 2.687706] ata1: SATA link down (SStatus 0 SControl 300)605machine # [ 2.690675] ata4: SATA link down (SStatus 0 SControl 300)606machine # [ 2.693111] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)607machine # [ 2.695576] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100608machine # [ 2.697645] ata3.00: applying bridge limits609machine # [ 2.699538] ata2: SATA link down (SStatus 0 SControl 300)610machine # [ 2.701870] ata6: SATA link down (SStatus 0 SControl 300)611machine # [ 2.704211] ata5: SATA link down (SStatus 0 SControl 300)612machine # [ 2.706383] ata3.00: configured for UDMA/100613machine # [ 2.708758] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5614machine # [ 2.778349] usbcore: registered new interface driver usbhid615machine # [ 2.782173] usbhid: USB HID core driver616machine # [ 2.792881] 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/input2617machine # [ 2.796256] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0618machine # [ 2.802443] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray619machine # [ 2.820370] cdrom: Uniform CD-ROM driver Revision: 3.20620machine # [ 2.836882] EXT4-fs (vda): mounted filesystem 4033415b-4a95-4cab-bca8-4c27543dc2bd r/w with ordered data mode. Quota mode: none.621machine # [ 2.699099] systemd[1]: Mounted /sysroot.622machine # [ 2.702075] systemd[1]: Reached target Initrd Root File System.623machine # [ 2.704657] systemd[1]: Starting Mountpoints Configured in the Real Root...624machine # [ 2.715676] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload.625machine # [ 2.721069] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)...626machine # [ 2.722533] systemd[1]: Reloading...627machine # [ 2.795105] systemd[1]: Reloading finished in 74 ms.628machine # [ 2.803192] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace...629machine # [ 2.807162] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace...630machine # [ 2.811897] systemd[1]: initrd-parse-etc.service: Deactivated successfully.631machine # [ 2.813722] systemd[1]: Finished Mountpoints Configured in the Real Root.632machine # [ 2.814702] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.633machine # [ 3.454331] systemd[1]: Mounting /sysroot/nix/.ro-store...634machine # [ 3.462180] systemd[1]: Mounting /sysroot/nix/.rw-store...635machine # [ 3.470152] systemd[1]: Mounting /sysroot/run...636machine # [ 3.480299] systemd[1]: Mounting /sysroot/tmp/shared...637machine # [ 3.490803] systemd[1]: Mounting /sysroot/tmp/xchg...638machine # [ 3.534873] systemd[1]: Mounted /sysroot/nix/.ro-store.639machine # [ 3.536294] systemd[1]: Mounted /sysroot/nix/.rw-store.640machine # [ 3.538250] systemd[1]: Mounted /sysroot/run.641machine # [ 3.539237] systemd[1]: Mounted /sysroot/tmp/shared.642machine # [ 3.542222] systemd[1]: Mounted /sysroot/tmp/xchg.643machine # [ 3.545616] systemd[1]: Starting rw-sysroot-nix-store.service...644machine # [ 3.556756] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.645machine # [ 3.558295] systemd[1]: Finished rw-sysroot-nix-store.service.646machine # [ 4.455428] systemd[1]: Mounting /sysroot/nix/store...647machine # [ 4.506228] systemd[1]: Mounted /sysroot/nix/store.648machine # [ 4.508942] systemd[1]: Reached target Initrd File Systems.649machine # [ 4.513135] systemd[1]: Starting Find NixOS closure...650machine # [ 4.518705] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...651machine # [ 4.541674] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.652machine # [ 4.544754] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.653machine # [ 4.551292] systemd[1]: Finished Find NixOS closure.654machine # [ 4.553226] systemd[1]: Reached target Initrd Default Target.655machine # [ 4.554594] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...656machine # [ 4.568943] systemd[1]: Stopped target Initrd Default Target.657machine # [ 4.570240] systemd[1]: Stopped target Basic System.658machine # [ 4.571251] systemd[1]: Stopped target Initrd Root Device.659machine # [ 4.573121] systemd[1]: Stopped target Path Units.660machine # [ 4.573839] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.661machine # [ 4.574839] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.662machine # [ 4.575840] systemd[1]: Stopped target Slice Units.663machine # [ 4.576550] systemd[1]: Stopped target Socket Units.664machine # [ 4.577418] systemd[1]: Stopped target System Initialization.665machine # [ 4.578211] systemd[1]: Stopped target Swaps.666machine # [ 4.579100] systemd[1]: Stopped target Timer Units.667machine # [ 4.580115] systemd[1]: dbus.socket: Deactivated successfully.668machine # [ 4.581117] systemd[1]: Closed D-Bus System Message Bus Socket.669machine # [ 4.582106] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.670machine # [ 4.583700] systemd[1]: Stopped Find NixOS closure.671machine # [ 4.584735] systemd[1]: Starting rw-sysroot-nix-store.service...672machine # [ 4.586096] systemd[1]: systemd-sysctl.service: Deactivated successfully.673machine # [ 4.587261] systemd[1]: Stopped Apply Kernel Variables.674machine # [ 4.589034] systemd[1]: systemd-modules-load.service: Deactivated successfully.675machine # [ 4.589989] systemd[1]: Stopped Load Kernel Modules.676machine # [ 4.590891] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.677machine # [ 4.592601] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.678machine # [ 4.593968] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.679machine # [ 4.594950] systemd[1]: Stopped Create System Files and Directories.680machine # [ 4.595832] systemd[1]: Stopped target Local File Systems.681machine # [ 4.598109] systemd[1]: Stopped target Preparation for Local File Systems.682machine # [ 4.600064] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.683machine # [ 4.601038] systemd[1]: Stopped Coldplug All udev Devices.684machine # [ 4.603506] systemd[1]: Stopping Rule-based Manager for Device Events and Files...685machine # [ 4.604527] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.686machine # [ 4.605551] systemd[1]: Stopped Virtual Console Setup.687machine # [ 4.611784] systemd[1]: initrd-cleanup.service: Deactivated successfully.688machine # [ 4.616070] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.689machine # [ 4.617439] systemd[1]: systemd-udevd.service: Deactivated successfully.690machine # [ 4.619553] systemd[1]: Stopped Rule-based Manager for Device Events and Files.691machine # [ 4.626919] systemd[1]: Starting Cleanup udev Database...692machine # [ 4.628152] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.693machine # [ 4.629200] systemd[1]: Stopped Create Static Device Nodes in /dev.694machine # [ 4.631115] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.695machine # [ 4.632200] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.696machine # [ 4.633161] systemd[1]: kmod-static-nodes.service: Deactivated successfully.697machine # [ 4.634085] systemd[1]: Stopped Create List of Static Device Nodes.698machine # [ 4.635350] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.699machine # [ 4.636717] systemd[1]: Finished rw-sysroot-nix-store.service.700machine # [ 4.653269] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.701machine # [ 4.655243] systemd[1]: Finished Cleanup udev Database.702machine # [ 4.656224] systemd[1]: Reached target Switch Root.703machine # [ 4.658069] systemd[1]: Starting NixOS Activation...704machine # [ 4.730703] initrd-nixos-activation-start[189]: booting system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test705machine # [ 4.757967] initrd-nixos-activation-start[189]: running activation script...706machine # [ 4.953531] initrd-nixos-activation-start[212]: setting up /etc...707machine # [ 5.051739] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.708machine # [ 5.054070] systemd[1]: Finished NixOS Activation.709machine # [ 5.055312] systemd[1]: Starting Switch Root...710machine # [ 5.068889] systemd[1]: Switching root.711machine # [ 5.342617] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).712machine # [ 5.419761] NET: Registered PF_VSOCK protocol family713machine # [ 5.777283] systemd[1]: systemd 262-e2c8482 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)714machine # [ 5.787577] systemd[1]: Detected virtualization kvm.715machine # [ 5.789352] systemd[1]: Detected architecture x86-64.716machine # [ 5.791229] systemd[1]: Detected first boot.717machine # [ 5.795000] systemd[1]: Initializing machine ID from random generator.718machine # [ 5.902836] systemd[1]: bpf-restrict-fs: LSM BPF program attached719machine # [ 5.982626] systemd[1]: Applying preset policy.720machine # [ 6.142186] systemd[1]: Populated /etc with preset unit settings.721machine # [ 6.312120] systemd[1]: initrd-switch-root.service: Deactivated successfully.722machine # [ 6.313476] systemd[1]: Stopped initrd-switch-root.service.723machine # [ 6.315568] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.724machine # [ 6.317624] systemd[1]: Created slice Slice /system/getty.725machine # [ 6.318864] systemd[1]: Created slice User and Session Slice.726machine # [ 6.319804] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.727machine # [ 6.320988] systemd[1]: Started Forward Password Requests to Wall Directory Watch.728machine # [ 6.322066] systemd[1]: Expecting device /dev/hvc0...729machine # [ 6.322807] systemd[1]: Expecting device /dev/ttyS0...730machine # [ 6.323532] systemd[1]: Reached target Local Encrypted Volumes.731machine # [ 6.324365] systemd[1]: Stopped target initrd-fs.target.732machine # [ 6.325095] systemd[1]: Stopped target initrd-root-fs.target.733machine # [ 6.325930] systemd[1]: Stopped target initrd-switch-root.target.734machine # [ 6.326771] systemd[1]: Reached target Virtual Machines and Containers.735machine # [ 6.327686] systemd[1]: Reached target Path Units.736machine # [ 6.328368] systemd[1]: Reached target Remote File Systems.737machine # [ 6.329169] systemd[1]: Reached target Slice Units.738machine # [ 6.329871] systemd[1]: Reached target Swaps.739machine # [ 6.331460] systemd[1]: Listening on Query the User Interactively for a Password.740machine # [ 6.333628] systemd[1]: Listening on Process Core Dump Socket.741machine # [ 6.335222] systemd[1]: Listening on Credential Encryption/Decryption.742machine # [ 6.336877] systemd[1]: Listening on Factory Reset Management.743machine # [ 6.337799] systemd[1]: Listening on Hostname Service Socket.744machine # [ 6.351700] systemd[1]: Starting Journal Log Access Socket...745machine # [ 6.353296] systemd[1]: Listening on Journal Audit Socket.746machine # [ 6.355775] systemd[1]: Listening on Console Output Muting Service Socket.747machine # [ 6.356885] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.748machine # [ 6.358006] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os749machine # [ 6.359292] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki750machine # [ 6.362923] systemd[1]: Listening on Disk Repartitioning Service Socket.751machine # [ 6.363966] systemd[1]: Listening on udev Varlink Socket.752machine # [ 6.366195] systemd[1]: Mounting Huge Pages File System...753machine # [ 6.368394] systemd[1]: Mounting POSIX Message Queue File System...754machine # [ 6.375437] systemd[1]: Mounting Kernel Debug File System...755machine # [ 6.382350] systemd[1]: Mounting Kernel Trace File System...756machine # [ 6.390676] systemd[1]: Starting Create List of Static Device Nodes...757machine # [ 6.399705] systemd[1]: Starting Load Kernel Module configfs...758machine # [ 6.404824] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm759machine # [ 6.415182] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore760machine # [ 6.425874] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse761machine # [ 6.443046] systemd[1]: Mounting FUSE Control File System...762machine # [ 6.446584] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67763machine # [ 6.458282] systemd[1]: Starting Journal Service...764machine # [ 6.464421] systemd[1]: Starting Load Kernel Modules...765machine # [ 6.475706] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...766machine # [ 6.487362] systemd[1]: Starting Remount Root and Kernel File Systems...767machine # [ 6.491317] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os768machine # [ 6.501635] systemd[1]: Starting Coldplug All udev Devices...769machine # [ 6.507731] systemd-journald[283]: Collecting audit messages is enabled.770machine # [ 6.522842] systemd[1]: Listening on Journal Log Access Socket.771machine # [ 6.527444] systemd[1]: Mounted Huge Pages File System.772machine # [ 6.386901] systemd[1]: Queued start job for default target Multi-User System.773machine # [ 6.389335] systemd[1]: systemd-journald.service: Deactivated successfully.774machine # [ 6.535484] systemd[1]: Started Journal Service.775machine # [ 6.397788] systemd[1]: Mounted POSIX Message Queue File System.776machine # [ 6.399000] systemd[1]: Mounted Kernel Debug File System.777machine # [ 6.407630] systemd[1]: Mounted Kernel Trace File System.778machine # [ 6.409502] systemd[1]: Finished Create List of Static Device Nodes.779machine # [ 6.413131] systemd[1]: modprobe@configfs.service: Deactivated successfully.780machine # [ 6.556562] loop: module loaded781machine # [ 6.419211] systemd[1]: Finished Load Kernel Module configfs.782machine # [ 6.420227] systemd[1]: Mounted FUSE Control File System.783machine # [ 6.421186] systemd-modules-load[284]: Inserted module 'loop'784machine # [ 6.435950] systemd[1]: Mounting Kernel Configuration File System...785machine # [ 6.443424] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...786machine # [ 6.444661] systemd[1]: Finished Load Kernel Modules.787machine # [ 6.454851] systemd[1]: Starting Firewall...788machine # [ 6.603183] EXT4-fs (vda): re-mounted 4033415b-4a95-4cab-bca8-4c27543dc2bd.789machine # [ 6.467066] systemd[1]: Starting Apply Kernel Variables...790machine # [ 6.480158] systemd[1]: Finished Remount Root and Kernel File Systems.791machine # [ 6.492278] systemd-oomd[286]: No swap; memory pressure usage will be degraded792machine # [ 6.498518] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.793machine # [ 6.501168] systemd[1]: Listening on Disk Image Download Service Socket.794machine # [ 6.509154] systemd[1]: Starting Flush Journal to Persistent Storage...795machine # [ 6.510084] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore796machine # [ 6.517475] systemd[1]: Starting Load/Save OS Random Seed...797machine # [ 6.518339] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os798machine # [ 6.714909] systemd-journald[283]: Received client request to flush runtime journal.799machine # [ 6.671559] systemd[1]: Mounted Kernel Configuration File System.800machine # [ 6.673518] systemd[1]: Finished Load/Save OS Random Seed.801machine # [ 6.675255] systemd[1]: Reached target First Boot Complete.802machine # [ 6.676115] systemd[1]: Finished Apply Kernel Variables.803machine # [ 6.676842] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.804machine # [ 6.678124] systemd[1]: Starting Create Static Device Nodes in /dev...805machine # [ 6.681384] systemd[1]: Finished Flush Journal to Persistent Storage.806machine # [ 6.769928] systemd[1]: Finished Coldplug All udev Devices.807machine # [ 6.777437] systemd[1]: Finished Create Static Device Nodes in /dev.808machine # [ 6.779271] systemd[1]: Reached target Preparation for Local File Systems.809machine # [ 6.781246] systemd[1]: Starting Rule-based Manager for Device Events and Files...810machine # [ 6.826139] systemd-udevd[323]: Using default interface naming scheme 'v261'.811machine # [ 6.876083] systemd[1]: Started Rule-based Manager for Device Events and Files.812machine # [ 6.988651] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse813machine # [ 7.065959] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.814machine # [ 7.092057] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.815machine # [ 7.118316] (udev-worker)[356]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.816machine # [ 7.121136] (udev-worker)[356]: Network interface NamePolicy= disabled on kernel command line.817machine # [ 7.125834] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line.818machine # [ 7.174524] systemd[1]: Mounting /run/wrappers...819machine # [ 7.209342] systemd[1]: Mounted /run/wrappers.820machine # [ 7.210156] systemd[1]: Reached target Local File Systems.821machine # [ 7.215126] systemd[1]: Listening on Boot Loader Control Service Socket.822machine # [ 7.217100] systemd[1]: Starting Create SUID/SGID Wrappers...823machine # [ 7.217893] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.824machine # [ 7.223271] systemd[1]: Starting Save Transient machine-id to Disk...825machine # [ 7.228472] systemd[1]: Starting Create System Files and Directories...826machine # [ 7.322119] systemd[1]: Condition check resulted in Virtio network device being skipped.827machine # [ 7.323382] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore828machine # [ 7.326179] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.829machine # [ 7.327940] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67830machine # [ 7.330377] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore831machine # [ 7.332723] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os832machine # [ 7.335137] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os833machine # [ 7.346148] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.834machine # [ 7.354666] systemd[1]: Finished Save Transient machine-id to Disk.835machine # [ 7.359656] systemd[1]: Finished Create System Files and Directories.836machine # [ 7.370361] systemd[1]: Starting Rebuild Journal Catalog...837machine # [ 7.373580] systemd[1]: Starting Record System Boot/Shutdown in UTMP...838machine # [ 7.450185] systemd[1]: Finished Record System Boot/Shutdown in UTMP.839machine # [ 7.475127] systemd[1]: Finished Rebuild Journal Catalog.840machine # [ 7.480620] systemd[1]: Starting Update is Completed...841machine # [ 7.523449] systemd[1]: Finished Update is Completed.842machine # [ 7.682870] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3843machine # [ 7.726828] ACPI: button: Power Button [PWRF]844machine # [ 7.739527] mousedev: PS/2 mouse device common for all mice845machine # [ 7.784398] rtc_cmos PNP0B00:00: RTC can wake from S4846machine # [ 7.643694] systemd[1]: Finished Firewall.847machine # [ 7.658225] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.848machine # [ 7.659743] systemd[1]: Finished Create SUID/SGID Wrappers.849machine # [ 7.661870] systemd[1]: Reached target System Initialization.850machine # [ 7.663395] systemd[1]: Started Discard unused filesystem blocks once a week.851machine # [ 7.665492] systemd[1]: Started Daily Cleanup of Temporary Directories.852machine # [ 7.666388] systemd[1]: Reached target Timer Units.853machine # [ 7.667174] systemd[1]: Listening on D-Bus System Message Bus Socket.854machine # [ 7.668625] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.855machine # [ 7.669798] systemd[1]: Reached target Socket Units.856machine # [ 7.671153] systemd[1]: Reached target Basic System.857machine # [ 7.814429] rtc_cmos PNP0B00:00: registered as rtc0858machine # [ 7.674315] systemd[1]: Started backdoor.service.859machine # [ 7.683064] systemd[1]: Starting Import lastlog data into lastlog2 database...860machine # [ 7.828388] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console861machine # [ 7.688609] systemd[1]: Starting Name Service Cache Daemon (nsncd)...862machine # [ 7.693092] systemd[1]: Starting Post-Boot Actions...863machine # [ 7.837347] rtc_cmos PNP0B00:00: setting system clock to 2026-09-15T03:07:42 UTC (1789441662)864machine # [ 7.838564] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs865machine # [ 7.839966] systemd-journald[283]: Time jumped backwards, rotating.866machine # [ 7.747571] systemd[1]: Started Reset console on configuration changes.867machine # [ 7.748955] systemd[1]: Starting resolvconf update...868machine # [ 7.792859] systemd[1]: Starting D-Bus System Message Bus...869machine # connecting to host...870machine # [ 7.820034] systemd[1]: Finished Post-Boot Actions.871machine # [ 7.831291] nsncd[493]: Sep 15 03:07:42.634 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"872machine # [ 7.836311] systemd[1]: Started Name Service Cache Daemon (nsncd).873machine # [ 7.837205] systemd[1]: Reached target Host and Network Name Lookups.874machine # [ 7.838724] systemd[1]: Reached target User and Group Name Lookups.875machine: Guest shell says: b'Spawning backdoor root shell...\n'876machine: connected to guest root shell877machine: (connecting took 8.51 seconds)878machine: (finished: waiting for the VM to finish booting, in 8.74 seconds)879machine # [ 7.866763] systemd[1]: Starting User Login Management...880machine # [ 7.902290] systemd[1]: Finished Import lastlog data into lastlog2 database.881machine # [ 7.907190] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4882machine # [ 7.928003] parport_pc 00:02: reported by Plug and Play ACPI883machine # [ 7.928091] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]884machine # [ 8.012596] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized885machine # [ 8.170813] Console: switching to colour dummy device 80x25886machine # [ 8.174899] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt887machine # [ 8.175005] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD888machine # [ 8.242742] [drm] Found bochs VGA, ID 0xb0c5.889machine # [ 8.242744] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.890machine # [ 8.002752] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'...891machine # [ 8.105880] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync'892machine # [ 8.108141] dbus-broker-launch[501]: Invalid user-name in /nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"893machine # [ 8.113164] systemd[1]: Stopped target Host and Network Name Lookups.894machine # [ 8.114422] systemd[1]: Stopping Host and Network Name Lookups...895machine # [ 8.119550] systemd[1]: Stopped target User and Group Name Lookups.896machine # [ 8.121146] systemd[1]: Stopping User and Group Name Lookups...897machine # [ 8.121981] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...898machine # [ 8.127216] systemd[1]: nscd.service: Deactivated successfully.899machine # [ 8.128049] systemd[1]: Stopped Name Service Cache Daemon (nsncd).900machine # [ 8.128845] systemd[1]: Starting Name Service Cache Daemon (nsncd)...901machine # [ 8.129691] systemd[1]: Started D-Bus System Message Bus.902machine # [ 8.130569] systemd-logind[522]: New seat seat0.903machine # [ 8.276036] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6904machine # [ 8.135939] systemd[1]: Started User Login Management.905machine # [ 8.139579] dbus-broker-launch[501]: Ready906machine # [ 8.142208] systemd[1]: Starting linger-users.service...907machine # [ 8.286358] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5908machine # [ 8.293979] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic909machine # [ 8.299185] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0910machine # [ 8.169196] systemd[1]: Started Name Service Cache Daemon (nsncd).911machine # [ 8.171778] nsncd[572]: Sep 15 03:07:42.975 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"912machine # [ 8.174678] systemd[1]: Reached target Host and Network Name Lookups.913machine # [ 8.176946] systemd[1]: Reached target User and Group Name Lookups.914machine # [ 8.208114] systemd[1]: Finished resolvconf update.915machine # [ 8.212348] systemd[1]: Reached target Preparation for Network.916machine # [ 8.217435] systemd[1]: Starting DHCP Client...917machine # [ 8.220094] systemd[1]: Starting Address configuration of eth1...918machine # [ 8.233116] systemd[1]: Starting Extra networking commands....919machine # [ 8.294066] systemd[1]: Created slice Slice /user/1001.920machine # [ 8.294841] systemd[1]: Starting Virtual Console Setup...921machine # [ 8.296282] systemd[1]: Starting User Runtime Directory /run/user/1001...922machine # [ 8.312754] systemd[1]: linger-users.service: Deactivated successfully.923machine # [ 8.315990] systemd[1]: Finished linger-users.service.924machine # [ 8.338293] systemd-logind[522]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)925machine # [ 8.339611] systemd-logind[522]: Watching system buttons on /dev/input/event2 (Power Button)926machine # [ 8.508056] Console: switching to colour frame buffer device 160x50927machine # [ 8.524574] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device928machine # [ 8.386730] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.929machine # [ 8.394410] systemd[1]: Stopped Virtual Console Setup.930machine # [ 8.395145] systemd[1]: Starting Virtual Console Setup...931machine # [ 8.560187] ppdev: user-space parallel port driver932machine # [ 8.437068] systemd[1]: Finished User Runtime Directory /run/user/1001.933machine # [ 8.454781] systemd[1]: Starting User Manager for UID 1001...934machine # [ 8.471325] systemd-logind[522]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)935machine # [ 8.643514] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)936machine # [ 8.663528] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)937machine # [ 8.551898] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.938machine # [ 8.553380] systemd[1]: Stopped Virtual Console Setup.939machine # [ 8.557688] systemd[1]: Starting Virtual Console Setup...940machine # [ 8.562618] (systemd)[623]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)941machine # [ 8.577378] network-addresses-eth1-start[606]: adding address 192.168.1.1/24... done942machine # [ 8.584265] systemd-logind[522]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.943machine # [ 8.616812] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy944machine # [ 8.690252] systemd[1]: Finished Address configuration of eth1.945machine # [ 8.691149] network-addresses-eth1-start[606]: adding address 2001:db8:1::1/64... done946machine # [ 8.812922] dhcpcd[631]: dhcpcd-10.3.2 starting947machine # [ 8.822981] systemd[623]: Queued start job for default target Main User Target.948machine # [ 8.834350] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy949machine # [ 8.835811] systemd[623]: Created slice User Application Slice.950machine # [ 8.836616] systemd[623]: Started Daily Cleanup of User's Temporary Directories.951machine # [ 8.839413] systemd[623]: Reached target Paths.952machine # [ 8.842191] systemd[623]: Reached target Timers.953machine # [ 8.842861] systemd[623]: Starting D-Bus User Message Bus Socket...954machine # [ 8.845424] systemd[623]: Starting Create User Files and Directories...955machine # [ 8.853298] dhcpcd[689]: dev: loaded udev956machine # [ 9.005468] kvm_amd: TSC scaling supported957machine # [ 9.005908] kvm_amd: Nested Virtualization enabled958machine # [ 9.009121] kvm_amd: Nested Paging enabled959machine # [ 9.009527] kvm_amd: LBR virtualization supported960machine # [ 9.009995] kvm_amd: Virtual VMLOAD VMSAVE supported961machine # [ 9.011649] kvm_amd: Virtual GIF supported962machine # [ 9.012042] kvm_amd: Virtual NMI enabled963machine # [ 8.879628] systemd[623]: Finished Create User Files and Directories.964machine # [ 8.882398] systemd[1]: Finished Extra networking commands..965machine # [ 8.884556] systemd[1]: Reached target Network.966machine # [ 9.028208] 8021q: 802.1Q VLAN Support v1.8967machine # [ 9.028673] 8021q: adding VLAN 0 to HW filter on device eth1968machine # [ 8.892415] systemd[1]: Starting Permit User Sessions...969machine # [ 8.913575] systemd[623]: Listening on D-Bus User Message Bus Socket.970machine # [ 8.914735] systemd[623]: Reached target Sockets.971machine # [ 8.920253] systemd[623]: Reached target Basic System.972machine # [ 8.920968] systemd[1]: Started User Manager for UID 1001.973machine # [ 8.923308] systemd[623]: Starting Run user-specific NixOS activation...974machine # [ 8.945089] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.975machine # [ 9.109023] EDAC MC: Ver: 3.0.0976machine # [ 8.992716] systemd[1]: Finished Permit User Sessions.977machine # [ 8.997097] systemd[1]: Started Getty on tty1.978machine # [ 8.998428] systemd[1]: Reached target Login Prompts.979machine # [ 9.014807] systemd[623]: Finished Run user-specific NixOS activation.980machine # [ 9.015753] systemd[623]: Reached target Main User Target.981machine # [ 9.016486] systemd[623]: Startup finished in 409ms.982machine # [ 9.179768] cfg80211: Loading compiled-in X.509 certificates for regulatory database983machine # [ 9.191483] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'984machine # [ 9.192357] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'985machine # [ 9.194322] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2986machine # [ 9.195168] cfg80211: failed to load regulatory.db987machine # [ 9.222570] 8021q: adding VLAN 0 to HW filter on device eth0988machine # [ 9.081565] dhcpcd[689]: eth0: waiting for carrier989machine # [ 9.082471] dhcpcd[689]: eth0: carrier acquired990machine # [ 9.087846] dhcpcd[689]: DUID 00:01:00:01:32:3b:72:ff:52:54:00:12:34:56991machine # [ 9.089141] dhcpcd[689]: eth0: IAID 00:12:34:56992machine # [ 9.089884] dhcpcd[689]: eth0: adding address fe80::5054:ff:fe12:3456993machine # [ 9.131441] systemd-vconsole-setup[632]: Configuration of first virtual console was skipped, ignoring remaining ones.994machine # [ 9.134614] systemd[1]: Finished Virtual Console Setup.995machine # [ 10.239971] dhcpcd[689]: eth0: soliciting a DHCP lease996machine # [ 10.399545] NET: Registered PF_PACKET protocol family997machine # [ 10.263958] dhcpcd[689]: eth0: offered 10.0.2.15 from 10.0.2.2998machine # [ 10.266204] dhcpcd[689]: eth0: probing address 10.0.2.15/24999machine # [ 11.033910] dhcpcd[689]: eth0: soliciting an IPv6 router1000machine # [ 11.036533] dhcpcd[689]: eth0: Router Advertisement from fe80::21001machine # [ 11.038936] dhcpcd[689]: eth0: adding address fec0::5054:ff:fe12:3456/641002machine # [ 11.041292] dhcpcd[689]: eth0: adding route to fec0::/641003machine # [ 11.043235] dhcpcd[689]: eth0: adding default route via fe80::21004machine # [ 15.386197] dhcpcd[689]: eth0: leased 10.0.2.15 for 86400 seconds1005machine # [ 15.387781] dhcpcd[689]: eth0: adding route to 10.0.2.0/241006machine # [ 15.388757] dhcpcd[689]: eth0: adding default route via 10.0.2.21007machine # [ 15.445471] systemd[1]: Started DHCP Client.1008machine # [ 15.447541] systemd[1]: Reached target Multi-User System.1009machine # [ 15.448620] systemd[1]: Startup finished in 829ms (kernel) + 4.428s (initrd) + 10.189s (userspace) = 15.447s.1010machine: (finished: waiting for unit multi-user.target, in 16.46 seconds)1011machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test1012machine # Checking switch inhibitors... done1013machine # [ 15.765098] nixos[841]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1014machine # [ 15.767281] systemd[1]: Stopped target Remote File Systems.1015machine # [ 15.768757] systemd[1]: Stopped target Local File Systems.1016machine # activating the configuration...1017machine # setting up /etc...1018machine # [ 15.984989] systemd[1]: Reload requested from client PID 841 ('.switch-to-conf') (unit backdoor.service)...1019machine # [ 15.986718] systemd[1]: Reloading...1020machine # [ 16.210935] systemd[1]: Reloading finished in 223 ms.1021machine # reloading user units for usertest...1022machine # [ 16.240933] systemd[623]: Created slice User Core Session Slice.1023machine # [ 16.243135] systemd[623]: Starting D-Bus User Message Bus...1024machine # [ 16.264719] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.import1.service'1025machine # [ 16.267364] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1026machine # [ 16.269964] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1027machine # [ 16.272478] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1028machine # [ 16.275068] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1029machine # [ 16.276618] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1030machine # [ 16.278509] systemd[623]: Started D-Bus User Message Bus.1031machine # [ 16.281841] dbus-broker-launch[911]: Ready1032machine # [ 16.319950] systemd[623]: Reexecution requested from client PID 910 ('switch-to-confi')...1033machine # [ 16.321203] systemd[623]: Reexecuting.1034machine # [ 16.337158] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1035machine # [ 16.408423] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1036machine # [ 16.413368] systemd[623]: Reload requested from client PID 910 ('switch-to-confi')...1037machine # [ 16.414792] systemd[623]: Reloading...1038machine # [ 16.485433] systemd[623]: Reloading finished in 69 ms.1039machine # [ 16.490853] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1040machine # restarting the following user units: nixos-activation.service1041machine # [ 16.498150] systemd[623]: Stopped Run user-specific NixOS activation.1042machine # [ 16.499051] systemd[623]: Stopping Run user-specific NixOS activation...1043machine # [ 16.501195] systemd[623]: Starting Run user-specific NixOS activation...1044machine # [ 16.519919] systemd[623]: Finished Run user-specific NixOS activation.1045machine # restarting sysinit-reactivation.target1046machine # [ 16.529362] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1047machine # [ 16.596884] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1048machine # [ 16.598332] systemd[1]: Reached target Reactivate sysinit units.1049machine # [ 16.600936] systemd[1]: Reached target Remote File Systems.1050machine # [ 16.605449] systemd[1]: Starting linger-users.service...1051machine # [ 16.612068] systemd[1]: Reached target Local File Systems.1052machine # [ 16.612831] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1053machine # [ 16.617827] systemd[1]: Starting Create SUID/SGID Wrappers...1054machine # [ 16.619116] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1055machine # [ 16.620971] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671056machine # [ 16.624129] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1057machine # [ 16.625896] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1058machine # [ 16.630294] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1059machine # [ 16.631653] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1060machine # [ 16.633172] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1061machine # [ 16.666684] systemd[1]: linger-users.service: Deactivated successfully.1062machine # [ 16.668435] systemd[1]: Finished linger-users.service.1063machine # [ 16.763680] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1064machine # [ 16.765301] systemd[1]: Finished Create SUID/SGID Wrappers.1065machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1066machine # [ 17.026499] nixos[841]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1067machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test, in 1.46 seconds)1068machine: must succeed: rm /etc/NIXOS1069machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)1070machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test/bin/switch-to-configuration test1071machine # this should succeed (config: /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test, action: test)1072machine # Checking switch inhibitors... done1073machine # [ 17.283050] nixos[988]: switching to system configuration /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test1074machine # activating the configuration...1075machine # [ 17.286478] systemd[1]: Stopped target Local File Systems.1076machine # [ 17.287352] systemd[1]: Stopped target Remote File Systems.1077machine # removing user ‘usertest’1078machine # setting up /etc...1079machine # [ 17.507872] systemd[1]: Reload requested from client PID 988 ('.switch-to-conf') (unit backdoor.service)...1080machine # [ 17.509606] systemd[1]: Reloading...1081machine # [ 17.796978] systemd[1]: Reloading finished in 286 ms.1082machine # reloading user units for usertest...1083machine # [ 17.864076] systemd[623]: Reexecution requested from client PID 1059 ('switch-to-confi')...1084machine # [ 17.865368] systemd[623]: Reexecuting.1085machine # [ 17.883972] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1086machine # [ 17.957495] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1087machine # [ 17.962568] systemd[623]: Reload requested from client PID 1059 ('switch-to-confi')...1088machine # [ 17.963921] systemd[623]: Reloading...1089machine # [ 18.040045] systemd[623]: Reloading finished in 74 ms.1090machine # [ 18.045830] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1091machine # restarting the following user units: nixos-activation.service1092machine # [ 18.052609] systemd[623]: Stopped Run user-specific NixOS activation.1093machine # [ 18.054098] systemd[623]: Stopping Run user-specific NixOS activation...1094machine # [ 18.056060] systemd[623]: Starting Run user-specific NixOS activation...1095machine # [ 18.075931] systemd[623]: Finished Run user-specific NixOS activation.1096machine # restarting sysinit-reactivation.target1097machine # [ 18.079327] systemd[1]: Stopped target Reactivate sysinit units.1098machine # [ 18.080652] systemd[1]: Stopping Reactivate sysinit units...1099machine # [ 18.081574] systemd[1]: Reached target Reactivate sysinit units.1100machine # reloading the following units: dbus-broker.service1101machine # [ 18.084623] dbus-broker-launch[501]: Caught SIGHUP, trigger reload.1102machine # [ 18.086119] systemd[1]: Reloading D-Bus System Message Bus...1103machine # [ 18.090819] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'...1104machine # [ 18.092556] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync'1105machine # [ 18.093467] dbus-broker-launch[501]: Invalid user-name in /nix/store/19aiqw6rbcrmy1mdrgsaq7p6p1hxm12h-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1106machine # [ 18.103894] systemd[1]: Reloaded D-Bus System Message Bus.1107machine # restarting the following units: network-addresses-eth1.service1108machine # [ 18.108543] systemd[1]: Stopping Address configuration of eth1...1109machine # [ 18.129710] network-addresses-eth1-pre-stop[1071]: deleting address 192.168.1.1/24... done1110machine # [ 18.134661] network-addresses-eth1-pre-stop[1071]: deleting address 2001:db8:1::1/64... done1111machine # [ 18.139576] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1112machine # [ 18.141132] systemd[1]: Stopped Address configuration of eth1.1113machine # [ 18.144379] systemd[1]: Starting Address configuration of eth1...1114machine # [ 18.171376] network-addresses-eth1-start[1076]: adding address 192.168.1.2/24... done1115machine # [ 18.176698] network-addresses-eth1-start[1076]: adding address 2001:db8:1::2/64... done1116machine # [ 18.183613] systemd[1]: Finished Address configuration of eth1.1117machine # [ 18.185823] systemd[1]: Reached target Local File Systems.1118machine # [ 18.190418] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1119machine # [ 18.194096] systemd[1]: Starting Create SUID/SGID Wrappers...1120machine # [ 18.195854] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1121machine # [ 18.197035] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671122machine # [ 18.199082] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1123machine # [ 18.200477] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 18.204117] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1125machine # [ 18.209528] systemd[1]: Reached target Remote File Systems.1126machine # [ 18.213899] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1127machine # [ 18.215589] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1128machine # [ 18.352512] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1129machine # [ 18.354099] systemd[1]: Finished Create SUID/SGID Wrappers.1130machine # [ 18.357633] systemd[1]: Starting linger-users.service...1131machine # [ 18.376516] linger-users-start[1148]: id: ‘usertest’: no such user1132machine # [ 18.378066] linger-users-start[1146]: Removing linger for missing user usertest1133machine # [ 18.382600] systemd[1]: linger-users.service: Deactivated successfully.1134machine # [ 18.383968] systemd[1]: Finished linger-users.service.1135machine # [ 18.641927] nixos[988]: finished switching to system configuration /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test1136machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/by0sa33w3cx0cj0q3h4a6pb6ma3d3rkn-nixos-system-other-test/bin/switch-to-configuration test, in 1.60 seconds)1137machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11138machine # [ 18.869095] nixos[1154]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1139machine # [ 18.871421] systemd[1]: Stopped target Remote File Systems.1140machine # [ 18.873189] systemd[1]: Stopped target Local File Systems.1141machine # [ 19.080900] systemd[1]: Reload requested from client PID 1154 ('.switch-to-conf') (unit backdoor.service)...1142machine # [ 19.082979] systemd[1]: Reloading...1143machine # [ 19.319977] systemd[1]: Reloading finished in 235 ms.1144machine # [ 19.379366] systemd[623]: Reexecution requested from client PID 1224 ('switch-to-confi')...1145machine # [ 19.381763] systemd[623]: Reexecuting.1146machine # [ 19.401230] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1147machine # [ 19.473441] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1148machine # [ 19.478852] systemd[623]: Reload requested from client PID 1224 ('switch-to-confi')...1149machine # [ 19.480614] systemd[623]: Reloading...1150machine # [ 19.550779] systemd[623]: Reloading finished in 69 ms.1151machine # [ 19.555813] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1152machine # [ 19.562107] systemd[623]: Stopped Run user-specific NixOS activation.1153machine # [ 19.562968] systemd[623]: Stopping Run user-specific NixOS activation...1154machine # [ 19.564968] systemd[623]: Starting Run user-specific NixOS activation...1155machine # [ 19.583809] systemd[623]: Finished Run user-specific NixOS activation.1156machine # [ 19.586790] systemd[1]: Stopped target Reactivate sysinit units.1157machine # [ 19.587982] systemd[1]: Stopping Reactivate sysinit units...1158machine # [ 19.589241] systemd[1]: Reached target Reactivate sysinit units.1159machine # [ 19.591790] dbus-broker-launch[501]: Caught SIGHUP, trigger reload.1160machine # [ 19.593389] systemd[1]: Reloading D-Bus System Message Bus...1161machine # [ 19.596743] dbus-broker-launch[501]: Looking up NSS user entry for 'systemd-timesync'...1162machine # [ 19.598459] dbus-broker-launch[501]: NSS returned no entry for 'systemd-timesync'1163machine # [ 19.599440] dbus-broker-launch[501]: Invalid user-name in /nix/store/abzvksrj3003zwg0iwcfzxlrk3ywxf85-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1164machine # [ 19.606553] systemd[1]: Reloaded D-Bus System Message Bus.1165machine # [ 19.610526] systemd[1]: Stopping Address configuration of eth1...1166machine # [ 19.629967] network-addresses-eth1-pre-stop[1236]: deleting address 192.168.1.2/24... done1167machine # [ 19.634894] network-addresses-eth1-pre-stop[1236]: deleting address 2001:db8:1::2/64... done1168machine # [ 19.639798] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1169machine # [ 19.641377] systemd[1]: Stopped Address configuration of eth1.1170machine # [ 19.644757] systemd[1]: Starting Address configuration of eth1...1171machine # [ 19.672498] network-addresses-eth1-start[1241]: adding address 192.168.1.1/24... done1172machine # [ 19.677743] network-addresses-eth1-start[1241]: adding address 2001:db8:1::1/64... done1173machine # [ 19.684699] systemd[1]: Finished Address configuration of eth1.1174machine # [ 19.689289] systemd[1]: Reached target Remote File Systems.1175machine # [ 19.691953] systemd[1]: Starting linger-users.service...1176machine # [ 19.699286] systemd[1]: Reached target Local File Systems.1177machine # [ 19.700079] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1178machine # [ 19.701244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1179machine # [ 19.705583] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1180machine # [ 19.711307] systemd[1]: Starting Create SUID/SGID Wrappers...1181machine # [ 19.712111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1182machine # [ 19.713449] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671183machine # [ 19.716475] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1184machine # [ 19.719117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1185machine # [ 19.720286] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1186machine # [ 19.743136] systemd[1]: linger-users.service: Deactivated successfully.1187machine # [ 19.744652] systemd[1]: Finished linger-users.service.1188machine # [ 19.856770] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1189machine # [ 19.858511] systemd[1]: Finished Create SUID/SGID Wrappers.1190machine # [ 20.115797] nixos[1154]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1191machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.46 seconds)1192subtest: user services1193machine: waiting for unit user@1001.service1194machine: (finished: waiting for unit user@1001.service, in 0.06 seconds)1195machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11196machine # [ 20.375860] nixos[1314]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1197machine # [ 20.378492] systemd[1]: Stopped target Remote File Systems.1198machine # [ 20.379822] systemd[1]: Stopped target Local File Systems.1199machine # [ 20.599460] systemd[1]: Reload requested from client PID 1314 ('.switch-to-conf') (unit backdoor.service)...1200machine # [ 20.601117] systemd[1]: Reloading...1201machine # [ 20.828382] systemd[1]: Reloading finished in 226 ms.1202machine # [ 20.892922] systemd[623]: Reexecution requested from client PID 1383 ('switch-to-confi')...1203machine # [ 20.894774] systemd[623]: Reexecuting.1204machine # [ 20.915062] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 20.989979] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 20.994992] systemd[623]: Reload requested from client PID 1383 ('switch-to-confi')...1207machine # [ 20.996712] systemd[623]: Reloading...1208machine # [ 21.068649] systemd[623]: Reloading finished in 70 ms.1209machine # [ 21.073815] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1210machine # [ 21.080258] systemd[623]: Starting usertest.service...1211machine # [ 21.096827] systemd[623]: Finished usertest.service.1212machine # [ 21.099367] systemd[623]: Stopped Run user-specific NixOS activation.1213machine # [ 21.100233] systemd[623]: Stopping Run user-specific NixOS activation...1214machine # [ 21.102241] systemd[623]: Starting Run user-specific NixOS activation...1215machine # [ 21.121398] systemd[623]: Finished Run user-specific NixOS activation.1216machine # [ 21.124363] systemd[1]: Stopped target Reactivate sysinit units.1217machine # [ 21.125629] systemd[1]: Stopping Reactivate sysinit units...1218machine # [ 21.126567] systemd[1]: Reached target Reactivate sysinit units.1219machine # [ 21.129608] systemd[1]: Reached target Remote File Systems.1220machine # [ 21.133416] systemd[1]: Starting linger-users.service...1221machine # [ 21.135582] systemd[1]: Reached target Local File Systems.1222machine # [ 21.140980] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1223machine # [ 21.142340] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1224machine # [ 21.147430] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1225machine # [ 21.152127] systemd[1]: Starting Create SUID/SGID Wrappers...1226machine # [ 21.152916] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1227machine # [ 21.155683] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671228machine # [ 21.157586] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1229machine # [ 21.160101] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1230machine # [ 21.161291] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1231machine # [ 21.193909] systemd[1]: linger-users.service: Deactivated successfully.1232machine # [ 21.195528] systemd[1]: Finished linger-users.service.1233machine # [ 21.303433] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1234machine # [ 21.305109] systemd[1]: Finished Create SUID/SGID Wrappers.1235machine # [ 21.561533] nixos[1314]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1236machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.38 seconds)1237machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1238machine # [ 21.612738] sudo[1453]: 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.service1239machine # [ 21.626789] sudo[1453]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1240machine # [ 21.637895] sudo[1453]: pam_unix(sudo:session): session closed for user usertest1241machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1242machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11243machine # [ 21.814156] nixos[1462]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1244machine # [ 21.816404] systemd[1]: Stopped target Remote File Systems.1245machine # [ 21.818462] systemd[1]: Stopped target Local File Systems.1246machine # [ 22.052987] systemd[1]: Reload requested from client PID 1462 ('.switch-to-conf') (unit backdoor.service)...1247machine # [ 22.054695] systemd[1]: Reloading...1248machine # [ 22.282061] systemd[1]: Reloading finished in 223 ms.1249machine # [ 22.344509] systemd[623]: Reexecution requested from client PID 1532 ('switch-to-confi')...1250machine # [ 22.345946] systemd[623]: Reexecuting.1251machine # [ 22.364979] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1252machine # [ 22.438350] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 22.443512] systemd[623]: Reload requested from client PID 1532 ('switch-to-confi')...1254machine # [ 22.444902] systemd[623]: Reloading...1255machine # [ 22.517173] systemd[623]: Reloading finished in 70 ms.1256machine # [ 22.522816] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 22.528939] systemd[623]: Stopped Run user-specific NixOS activation.1258machine # [ 22.529920] systemd[623]: Stopping Run user-specific NixOS activation...1259machine # [ 22.532432] systemd[623]: Starting Run user-specific NixOS activation...1260machine # [ 22.550778] systemd[623]: Finished Run user-specific NixOS activation.1261machine # [ 22.553660] systemd[1]: Stopped target Reactivate sysinit units.1262machine # [ 22.554921] systemd[1]: Stopping Reactivate sysinit units...1263machine # [ 22.555847] systemd[1]: Reached target Reactivate sysinit units.1264machine # [ 22.557307] systemd[1]: Reached target Local File Systems.1265machine # [ 22.560322] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 22.561945] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1267machine # [ 22.565116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1268machine # [ 22.567811] systemd[1]: Starting Create SUID/SGID Wrappers...1269machine # [ 22.569272] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1270machine # [ 22.571134] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671271machine # [ 22.573071] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1272machine # [ 22.574523] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1273machine # [ 22.575692] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1274machine # [ 22.584258] systemd[1]: Reached target Remote File Systems.1275machine # [ 22.705094] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1276machine # [ 22.706659] systemd[1]: Finished Create SUID/SGID Wrappers.1277machine # [ 22.710606] systemd[1]: Starting linger-users.service...1278machine # [ 22.736219] systemd[1]: linger-users.service: Deactivated successfully.1279machine # [ 22.737655] systemd[1]: Finished linger-users.service.1280machine # [ 22.994801] nixos[1462]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1281machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.36 seconds)1282machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11283machine # [ 23.205116] nixos[1603]: switching to system configuration /nix/store/2vps2kbm93fsps7wg2g2cg3zr98ga33i-nixos-system-machine-test1284machine # [ 23.207445] systemd[1]: Stopped target Local File Systems.1285machine # [ 23.209162] systemd[1]: Stopped target Remote File Systems.1286machine # [ 23.441588] systemd[1]: Reload requested from client PID 1603 ('.switch-to-conf') (unit backdoor.service)...1287machine # [ 23.443436] systemd[1]: Reloading...1288machine # [ 23.670552] systemd[1]: Reloading finished in 225 ms.1289machine # [ 23.741877] systemd[623]: Stopped usertest.service.1290machine # [ 23.743440] systemd[623]: Reexecution requested from client PID 1672 ('switch-to-confi')...1291machine # [ 23.745927] systemd[623]: Reexecuting.1292machine # [ 23.765092] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1293machine # [ 23.841442] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1294machine # [ 23.846821] systemd[623]: Reload requested from client PID 1672 ('switch-to-confi')...1295machine # [ 23.848173] systemd[623]: Reloading...1296machine # [ 23.920419] systemd[623]: Reloading finished in 71 ms.1297machine # [ 23.925827] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 23.932564] systemd[623]: Starting usertest.service...1299machine # [ 23.947800] systemd[623]: Finished usertest.service.1300machine # [ 23.950134] systemd[623]: Stopped Run user-specific NixOS activation.1301machine # [ 23.951246] systemd[623]: Stopping Run user-specific NixOS activation...1302machine # [ 23.953575] systemd[623]: Starting Run user-specific NixOS activation...1303machine # [ 23.971309] systemd[623]: Finished Run user-specific NixOS activation.1304machine # [ 23.974069] systemd[1]: Stopped target Reactivate sysinit units.1305machine # [ 23.975582] systemd[1]: Stopping Reactivate sysinit units...1306machine # [ 23.976698] systemd[1]: Reached target Reactivate sysinit units.1307machine # [ 23.979346] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1308machine # [ 23.981196] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1309machine # [ 23.983361] systemd[1]: Reached target Local File Systems.1310machine # [ 23.986359] systemd[1]: Reached target Remote File Systems.1311machine # [ 23.990552] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1312machine # [ 23.995097] systemd[1]: Starting Create SUID/SGID Wrappers...1313machine # [ 23.995990] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1314machine # [ 23.997946] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671315machine # [ 24.000184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1316machine # [ 24.001812] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1317machine # [ 24.005679] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1318machine # [ 24.136563] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1319machine # [ 24.140509] systemd[1]: Finished Create SUID/SGID Wrappers.1320machine # [ 24.142117] systemd[1]: Starting linger-users.service...1321machine # [ 24.169061] systemd[1]: linger-users.service: Deactivated successfully.1322machine # [ 24.171673] systemd[1]: Finished linger-users.service.1323machine # [ 24.427930] nixos[1603]: finished switching to system configuration /nix/store/2vps2kbm93fsps7wg2g2cg3zr98ga33i-nixos-system-machine-test1324machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1325machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1326machine # [ 24.474707] sudo[1742]: 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.service1327machine # [ 24.488805] sudo[1742]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1328machine # [ 24.499865] sudo[1742]: pam_unix(sudo:session): session closed for user usertest1329machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1330machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11331machine # [ 24.677049] nixos[1751]: switching to system configuration /nix/store/npabl8y25vjdyx718glf6bsylj955mg4-nixos-system-machine-test1332machine # [ 24.679657] systemd[1]: Stopped target Remote File Systems.1333machine # [ 24.681289] systemd[1]: Stopped target Local File Systems.1334machine # [ 24.903858] systemd[1]: Reload requested from client PID 1751 ('.switch-to-conf') (unit backdoor.service)...1335machine # [ 24.905470] systemd[1]: Reloading...1336machine # [ 25.144091] systemd[1]: Reloading finished in 237 ms.1337machine # [ 25.224276] systemd[623]: Reexecution requested from client PID 1820 ('switch-to-confi')...1338machine # [ 25.226132] systemd[623]: Reexecuting.1339machine # [ 25.245980] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1340machine # [ 25.321784] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1341machine # [ 25.327396] systemd[623]: Reload requested from client PID 1820 ('switch-to-confi')...1342machine # [ 25.330129] systemd[623]: Reloading...1343machine # [ 25.402859] systemd[623]: Reloading finished in 73 ms.1344machine # [ 25.407832] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1345machine # [ 25.411594] systemd[623]: Stopped usertest.service.1346machine # [ 25.412397] systemd[623]: Stopping usertest.service...1347machine # [ 25.414584] systemd[623]: Starting usertest.service...1348machine # [ 25.428741] systemd[623]: Finished usertest.service.1349machine # [ 25.433301] systemd[623]: Stopped Run user-specific NixOS activation.1350machine # [ 25.435064] systemd[623]: Stopping Run user-specific NixOS activation...1351machine # [ 25.436692] systemd[623]: Starting Run user-specific NixOS activation...1352machine # [ 25.455519] systemd[623]: Finished Run user-specific NixOS activation.1353machine # [ 25.458400] systemd[1]: Stopped target Reactivate sysinit units.1354machine # [ 25.459632] systemd[1]: Stopping Reactivate sysinit units...1355machine # [ 25.460619] systemd[1]: Reached target Reactivate sysinit units.1356machine # [ 25.463193] systemd[1]: Reached target Remote File Systems.1357machine # [ 25.465153] systemd[1]: Starting linger-users.service...1358machine # [ 25.470383] systemd[1]: Reached target Local File Systems.1359machine # [ 25.474297] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1360machine # [ 25.476094] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1361machine # [ 25.478741] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1362machine # [ 25.483083] systemd[1]: Starting Create SUID/SGID Wrappers...1363machine # [ 25.483874] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1364machine # [ 25.486446] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671365machine # [ 25.488945] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1366machine # [ 25.491227] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1367machine # [ 25.493131] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1368machine # [ 25.527844] systemd[1]: linger-users.service: Deactivated successfully.1369machine # [ 25.528993] systemd[1]: Finished linger-users.service.1370machine # [ 25.636735] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1371machine # [ 25.638518] systemd[1]: Finished Create SUID/SGID Wrappers.1372machine # [ 25.896215] nixos[1751]: finished switching to system configuration /nix/store/npabl8y25vjdyx718glf6bsylj955mg4-nixos-system-machine-test1373machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.40 seconds)1374machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1375machine # [ 25.941755] sudo[1891]: 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.service1376machine # [ 25.957907] sudo[1891]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1377machine # [ 25.970468] sudo[1891]: pam_unix(sudo:session): session closed for user usertest1378machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1379machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11380machine # [ 26.148474] nixos[1900]: switching to system configuration /nix/store/09vl9xafd5p28ikjwy3ka1rkczkwid7n-nixos-system-machine-test1381machine # [ 26.150657] systemd[1]: Stopped target Remote File Systems.1382machine # [ 26.152477] systemd[1]: Stopped target Local File Systems.1383machine # [ 26.371250] systemd[1]: Reload requested from client PID 1900 ('.switch-to-conf') (unit backdoor.service)...1384machine # [ 26.372849] systemd[1]: Reloading...1385machine # [ 26.595753] systemd[1]: Reloading finished in 221 ms.1386machine # [ 26.672911] systemd[623]: Reexecution requested from client PID 1969 ('switch-to-confi')...1387machine # [ 26.675810] systemd[623]: Reexecuting.1388machine # [ 26.696125] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1389machine # [ 26.775192] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1390machine # [ 26.780838] systemd[623]: Reload requested from client PID 1969 ('switch-to-confi')...1391machine # [ 26.782730] systemd[623]: Reloading...1392machine # [ 26.849654] systemd[623]: Reloading finished in 66 ms.1393machine # [ 26.854836] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1394machine # [ 26.860137] systemd[623]: Reloading usertest.service...1395machine # [ 26.875492] systemd[623]: Reloaded usertest.service.1396machine # [ 26.881086] systemd[623]: Stopped Run user-specific NixOS activation.1397machine # [ 26.881935] systemd[623]: Stopping Run user-specific NixOS activation...1398machine # [ 26.883460] systemd[623]: Starting Run user-specific NixOS activation...1399machine # [ 26.902940] systemd[623]: Finished Run user-specific NixOS activation.1400machine # [ 26.905957] systemd[1]: Stopped target Reactivate sysinit units.1401machine # [ 26.907373] systemd[1]: Stopping Reactivate sysinit units...1402machine # [ 26.908303] systemd[1]: Reached target Reactivate sysinit units.1403machine # [ 26.909687] systemd[1]: Reached target Remote File Systems.1404machine # [ 26.913447] systemd[1]: Starting linger-users.service...1405machine # [ 26.919045] systemd[1]: Reached target Local File Systems.1406machine # [ 26.926068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1407machine # [ 26.931338] systemd[1]: Starting Create SUID/SGID Wrappers...1408machine # [ 26.932244] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1409machine # [ 26.934369] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671410machine # [ 26.937339] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1411machine # [ 26.939781] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1412machine # [ 26.942270] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1413machine # [ 26.943399] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 26.944578] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1415machine # [ 26.978094] systemd[1]: linger-users.service: Deactivated successfully.1416machine # [ 26.979843] systemd[1]: Finished linger-users.service.1417machine # [ 27.077293] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1418machine # [ 27.078916] systemd[1]: Finished Create SUID/SGID Wrappers.1419machine # [ 27.336723] nixos[1900]: finished switching to system configuration /nix/store/09vl9xafd5p28ikjwy3ka1rkczkwid7n-nixos-system-machine-test1420machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.37 seconds)1421machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1422machine # [ 27.384879] sudo[2039]: 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.service1423machine # [ 27.400526] sudo[2039]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1424machine # [ 27.410379] sudo[2039]: pam_unix(sudo:session): session closed for user usertest1425machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1426machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11427machine # [ 27.589434] nixos[2048]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1428machine # [ 27.591656] systemd[1]: Stopped target Remote File Systems.1429machine # [ 27.593242] systemd[1]: Stopped target Local File Systems.1430machine # [ 27.805605] systemd[1]: Reload requested from client PID 2048 ('.switch-to-conf') (unit backdoor.service)...1431machine # [ 27.807145] systemd[1]: Reloading...1432machine # [ 28.042631] systemd[1]: Reloading finished in 234 ms.1433machine # [ 28.108465] systemd[623]: Stopped usertest.service.1434machine # [ 28.109782] systemd[623]: Reexecution requested from client PID 2117 ('switch-to-confi')...1435machine # [ 28.111657] systemd[623]: Reexecuting.1436machine # [ 28.131122] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 28.206690] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1438machine # [ 28.211880] systemd[623]: Reload requested from client PID 2117 ('switch-to-confi')...1439machine # [ 28.213627] systemd[623]: Reloading...1440machine # [ 28.309485] systemd[623]: Reloading finished in 94 ms.1441machine # [ 28.314837] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 28.322028] systemd[623]: Starting usertest.service...1443machine # [ 28.337160] systemd[623]: Finished usertest.service.1444machine # [ 28.340060] systemd[623]: Stopped Run user-specific NixOS activation.1445machine # [ 28.340947] systemd[623]: Stopping Run user-specific NixOS activation...1446machine # [ 28.342614] systemd[623]: Starting Run user-specific NixOS activation...1447machine # [ 28.362083] systemd[623]: Finished Run user-specific NixOS activation.1448machine # [ 28.365173] systemd[1]: Stopped target Reactivate sysinit units.1449machine # [ 28.366087] systemd[1]: Stopping Reactivate sysinit units...1450machine # [ 28.366982] systemd[1]: Reached target Reactivate sysinit units.1451machine # [ 28.368765] systemd[1]: Reached target Remote File Systems.1452machine # [ 28.371873] systemd[1]: Reached target Local File Systems.1453machine # [ 28.374171] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1454machine # [ 28.377226] systemd[1]: Starting Create SUID/SGID Wrappers...1455machine # [ 28.378565] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1456machine # [ 28.379709] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671457machine # [ 28.381633] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1458machine # [ 28.383032] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1459machine # [ 28.384154] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1460machine # [ 28.391764] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1461machine # [ 28.392953] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1462machine # [ 28.521531] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1463machine # [ 28.523185] systemd[1]: Finished Create SUID/SGID Wrappers.1464machine # [ 28.526830] systemd[1]: Starting linger-users.service...1465machine # [ 28.554884] systemd[1]: linger-users.service: Deactivated successfully.1466machine # [ 28.556927] systemd[1]: Finished linger-users.service.1467machine # [ 28.813507] nixos[2048]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1468machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.41 seconds)1469machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1470machine # [ 28.862779] sudo[2188]: 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.service1471machine # [ 28.877066] sudo[2188]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1472machine # [ 28.888582] sudo[2188]: pam_unix(sudo:session): session closed for user usertest1473machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1474machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11475machine # [ 29.071343] nixos[2197]: switching to system configuration /nix/store/agid4d0pd3ijbf4z9kj6dswg7pal0n7h-nixos-system-machine-test1476machine # [ 29.073703] systemd[1]: Stopped target Local File Systems.1477machine # [ 29.075370] systemd[1]: Stopped target Remote File Systems.1478machine # [ 29.315787] systemd[1]: Reload requested from client PID 2197 ('.switch-to-conf') (unit backdoor.service)...1479machine # [ 29.317409] systemd[1]: Reloading...1480machine # [ 29.524043] systemd[1]: Reloading finished in 204 ms.1481machine # [ 29.589355] systemd[623]: Reexecution requested from client PID 2266 ('switch-to-confi')...1482machine # [ 29.591941] systemd[623]: Reexecuting.1483machine # [ 29.611542] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 29.697710] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 29.703614] systemd[623]: Reload requested from client PID 2266 ('switch-to-confi')...1486machine # [ 29.704924] systemd[623]: Reloading...1487machine # [ 29.778747] systemd[623]: Reloading finished in 72 ms.1488machine # [ 29.784945] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1489machine # [ 29.790203] systemd[623]: Reloading usertest.service...1490machine # [ 29.805406] systemd[623]: Reloaded usertest.service.1491machine # [ 29.809864] systemd[623]: Stopped Run user-specific NixOS activation.1492machine # [ 29.810865] systemd[623]: Stopping Run user-specific NixOS activation...1493machine # [ 29.813146] systemd[623]: Starting Run user-specific NixOS activation...1494machine # [ 29.830758] systemd[623]: Finished Run user-specific NixOS activation.1495machine # [ 29.833721] systemd[1]: Stopped target Reactivate sysinit units.1496machine # [ 29.834963] systemd[1]: Stopping Reactivate sysinit units...1497machine # [ 29.835979] systemd[1]: Reached target Reactivate sysinit units.1498machine # [ 29.837688] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1499machine # [ 29.839232] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1500machine # [ 29.841469] systemd[1]: Reached target Local File Systems.1501machine # [ 29.845116] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1502machine # [ 29.848208] systemd[1]: Starting Create SUID/SGID Wrappers...1503machine # [ 29.849905] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1504machine # [ 29.851068] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671505machine # [ 29.852976] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1506machine # [ 29.854385] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 29.855541] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 29.862812] systemd[1]: Reached target Remote File Systems.1509machine # [ 29.984202] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1510machine # [ 29.986069] systemd[1]: Finished Create SUID/SGID Wrappers.1511machine # [ 29.989567] systemd[1]: Starting linger-users.service...1512machine # [ 30.017280] systemd[1]: linger-users.service: Deactivated successfully.1513machine # [ 30.018712] systemd[1]: Finished linger-users.service.1514machine # [ 30.275695] nixos[2197]: finished switching to system configuration /nix/store/agid4d0pd3ijbf4z9kj6dswg7pal0n7h-nixos-system-machine-test1515machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.39 seconds)1516machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1517machine # [ 30.324064] sudo[2336]: 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.service1518machine # [ 30.339407] sudo[2336]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1519machine # [ 30.346408] sudo[2336]: pam_unix(sudo:session): session closed for user usertest1520machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1521machine: must fail: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11522machine # [ 30.511742] nixos[2345]: switching to system configuration /nix/store/jp42z24brpvgsw866j7n27f78ib74l1q-nixos-system-machine-test1523machine # [ 30.514025] systemd[1]: Stopped target Remote File Systems.1524machine # [ 30.515704] systemd[1]: Stopped target Local File Systems.1525machine # [ 30.743364] systemd[1]: Reload requested from client PID 2345 ('.switch-to-conf') (unit backdoor.service)...1526machine # [ 30.744970] systemd[1]: Reloading...1527machine # [ 30.974100] systemd[1]: Reloading finished in 227 ms.1528machine # [ 31.045557] systemd[623]: Stopped usertest.service.1529machine # [ 31.048089] systemd[623]: Reexecution requested from client PID 2414 ('switch-to-confi')...1530machine # [ 31.049383] systemd[623]: Reexecuting.1531machine # [ 31.072175] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1532machine # [ 31.151699] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1533machine # [ 31.157654] systemd[623]: Reload requested from client PID 2414 ('switch-to-confi')...1534machine # [ 31.158923] systemd[623]: Reloading...1535machine # [ 31.232255] systemd[623]: Reloading finished in 72 ms.1536machine # [ 31.237835] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1537machine # [ 31.243852] systemd[623]: Starting usertest.service...1538machine # [ 31.261457] systemd[623]: usertest.service: Main process exited, code=exited, status=1/FAILURE1539machine # [ 31.262752] systemd[623]: usertest.service: Failed with result 'exit-code'.1540machine # [ 31.264363] systemd[623]: Failed to start usertest.service.1541machine # [ 31.266766] systemd[623]: Stopped Run user-specific NixOS activation.1542machine # [ 31.268244] systemd[623]: Stopping Run user-specific NixOS activation...1543machine # [ 31.270246] systemd[623]: Starting Run user-specific NixOS activation...1544machine # [ 31.288693] systemd[623]: Finished Run user-specific NixOS activation.1545machine # [ 31.291761] systemd[1]: Stopped target Reactivate sysinit units.1546machine # [ 31.292962] systemd[1]: Stopping Reactivate sysinit units...1547machine # [ 31.293947] systemd[1]: Reached target Reactivate sysinit units.1548machine # [ 31.295928] systemd[1]: Reached target Remote File Systems.1549machine # [ 31.297624] systemd[1]: Reached target Local File Systems.1550machine # [ 31.300458] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1551machine # [ 31.302106] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1552machine # [ 31.307221] systemd[1]: Starting linger-users.service...1553machine # [ 31.315870] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1554machine # [ 31.318760] systemd[1]: Starting Create SUID/SGID Wrappers...1555machine # [ 31.320190] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1556machine # [ 31.321293] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671557machine # [ 31.323233] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1558machine # [ 31.324624] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1559machine # [ 31.325770] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1560machine # [ 31.365217] systemd[1]: linger-users.service: Deactivated successfully.1561machine # [ 31.366829] systemd[1]: Finished linger-users.service.1562machine # [ 31.501497] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1563machine # [ 31.505670] systemd[1]: Finished Create SUID/SGID Wrappers.1564machine # [ 31.761735] nixos[2345]: switching to system configuration /nix/store/jp42z24brpvgsw866j7n27f78ib74l1q-nixos-system-machine-test failed (status 4)1565machine: (finished: must fail: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1566machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11567machine # [ 31.974298] nixos[2488]: switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1568machine # [ 31.976835] systemd[1]: Stopped target Local File Systems.1569machine # [ 31.978565] systemd[1]: Stopped target Remote File Systems.1570machine # [ 32.186947] systemd[1]: Reload requested from client PID 2488 ('.switch-to-conf') (unit backdoor.service)...1571machine # [ 32.188597] systemd[1]: Reloading...1572machine # [ 32.431172] systemd[1]: Reloading finished in 240 ms.1573machine # [ 32.495545] systemd[623]: Reexecution requested from client PID 2557 ('switch-to-confi')...1574machine # [ 32.497146] systemd[623]: Reexecuting.1575machine # [ 32.518278] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 32.591716] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1577machine # [ 32.596903] systemd[623]: Reload requested from client PID 2557 ('switch-to-confi')...1578machine # [ 32.598713] systemd[623]: Reloading...1579machine # [ 32.675561] systemd[623]: Reloading finished in 76 ms.1580machine # [ 32.680858] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1581machine # [ 32.687280] systemd[623]: Starting usertest.service...1582machine # [ 32.703165] systemd[623]: Finished usertest.service.1583machine # [ 32.705395] systemd[623]: Stopped Run user-specific NixOS activation.1584machine # [ 32.707152] systemd[623]: Stopping Run user-specific NixOS activation...1585machine # [ 32.708588] systemd[623]: Starting Run user-specific NixOS activation...1586machine # [ 32.728205] systemd[623]: Finished Run user-specific NixOS activation.1587machine # [ 32.731277] systemd[1]: Stopped target Reactivate sysinit units.1588machine # [ 32.732667] systemd[1]: Stopping Reactivate sysinit units...1589machine # [ 32.733650] systemd[1]: Reached target Reactivate sysinit units.1590machine # [ 32.736366] systemd[1]: Reached target Local File Systems.1591machine # [ 32.740403] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1592machine # [ 32.742096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1593machine # [ 32.744726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1594machine # [ 32.750935] systemd[1]: Starting Create SUID/SGID Wrappers...1595machine # [ 32.751772] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1596machine # [ 32.752904] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671597machine # [ 32.754839] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1598machine # [ 32.756265] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1599machine # [ 32.757413] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1600machine # [ 32.762052] systemd[1]: Reached target Remote File Systems.1601machine # [ 32.890133] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1602machine # [ 32.893191] systemd[1]: Finished Create SUID/SGID Wrappers.1603machine # [ 32.895454] systemd[1]: Starting linger-users.service...1604machine # [ 32.925504] systemd[1]: linger-users.service: Deactivated successfully.1605machine # [ 32.927081] systemd[1]: Finished linger-users.service.1606machine # [ 33.183809] nixos[2488]: finished switching to system configuration /nix/store/qyjw77mc0hv6f7jsza7mnq5pada13yvq-nixos-system-machine-test1607machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1608machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1609machine # [ 33.231554] sudo[2627]: 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.service1610machine # [ 33.245699] sudo[2627]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1611machine # [ 33.257059] sudo[2627]: pam_unix(sudo:session): session closed for user usertest1612machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1613machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11614machine # [ 33.441693] nixos[2636]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1615machine # [ 33.443923] systemd[1]: Stopped target Remote File Systems.1616machine # [ 33.445589] systemd[1]: Stopped target Local File Systems.1617machine # [ 33.643613] systemd[1]: Reload requested from client PID 2636 ('.switch-to-conf') (unit backdoor.service)...1618machine # [ 33.645155] systemd[1]: Reloading...1619machine # [ 33.878354] systemd[1]: Reloading finished in 231 ms.1620machine # [ 33.944501] systemd[623]: Stopped usertest.service.1621machine # [ 33.945812] systemd[623]: Reexecution requested from client PID 2705 ('switch-to-confi')...1622machine # [ 33.948244] systemd[623]: Reexecuting.1623machine # [ 33.969960] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1624machine # [ 34.041514] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1625machine # [ 34.046454] systemd[623]: Reload requested from client PID 2705 ('switch-to-confi')...1626machine # [ 34.047723] systemd[623]: Reloading...1627machine # [ 34.119370] systemd[623]: Reloading finished in 70 ms.1628machine # [ 34.124829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1629machine # [ 34.132108] systemd[623]: Stopped Run user-specific NixOS activation.1630machine # [ 34.132983] systemd[623]: Stopping Run user-specific NixOS activation...1631machine # [ 34.134805] systemd[623]: Starting Run user-specific NixOS activation...1632machine # [ 34.154218] systemd[623]: Finished Run user-specific NixOS activation.1633machine # [ 34.157053] systemd[1]: Stopped target Reactivate sysinit units.1634machine # [ 34.158560] systemd[1]: Stopping Reactivate sysinit units...1635machine # [ 34.159482] systemd[1]: Reached target Reactivate sysinit units.1636machine # [ 34.161280] systemd[1]: Reached target Local File Systems.1637machine # [ 34.165610] systemd[1]: Reached target Remote File Systems.1638machine # [ 34.168629] systemd[1]: Starting linger-users.service...1639machine # [ 34.175068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1640machine # [ 34.178870] systemd[1]: Starting Create SUID/SGID Wrappers...1641machine # [ 34.179687] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1642machine # [ 34.181356] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671643machine # [ 34.183248] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1644machine # [ 34.184666] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1645machine # [ 34.185801] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1646machine # [ 34.190283] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1647machine # [ 34.191518] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1648machine # [ 34.228275] systemd[1]: linger-users.service: Deactivated successfully.1649machine # [ 34.229885] systemd[1]: Finished linger-users.service.1650machine # [ 34.333474] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1651machine # [ 34.335098] systemd[1]: Finished Create SUID/SGID Wrappers.1652machine # [ 34.591477] nixos[2636]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1653machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.34 seconds)1654machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1655machine # [ 34.636544] sudo[2773]: 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.service1656machine # [ 34.657172] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1657machine # [ 34.668903] sudo[2773]: pam_unix(sudo:session): session closed for user usertest1658machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1659machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1660machine # [ 34.709418] sudo[2779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1661machine # [ 34.719348] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1662machine # [ 34.726589] sudo[2779]: pam_unix(sudo:session): session closed for user usertest1663machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1664machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service1665machine # [ 34.749095] sudo[2786]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service1666machine # [ 34.756866] sudo[2786]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1667machine # [ 34.763137] sudo[2786]: pam_unix(sudo:session): session closed for user usertest1668machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)1669machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1670machine # [ 34.782695] sudo[2792]: 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.service1671machine # [ 34.790461] sudo[2792]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1672machine # [ 34.796124] sudo[2792]: pam_unix(sudo:session): session closed for user usertest1673machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1674machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1675machine # [ 34.815561] sudo[2798]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1676machine # [ 34.823467] sudo[2798]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1677machine # [ 34.828651] systemd[623]: Reload requested from client PID 2803 ('systemctl')...1678machine # [ 34.829776] systemd[623]: Reloading...1679machine # [ 34.906545] systemd[623]: Reloading finished in 75 ms.1680machine # [ 34.911848] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1681machine # [ 34.919071] sudo[2798]: pam_unix(sudo:session): session closed for user usertest1682machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)1683machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1684machine # [ 34.939727] sudo[2808]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1685machine # [ 34.947261] sudo[2808]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1686machine # [ 34.956683] systemd[623]: Starting migrated.service...1687machine # [ 34.972560] systemd[623]: Finished migrated.service.1688machine # [ 34.975303] sudo[2808]: pam_unix(sudo:session): session closed for user usertest1689machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)1690machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1691machine # [ 34.995162] sudo[2816]: 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.service1692machine # [ 35.002465] sudo[2816]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1693machine # [ 35.009202] sudo[2816]: pam_unix(sudo:session): session closed for user usertest1694machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)1695machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1696machine # [ 35.028825] sudo[2822]: 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-owner1697machine # [ 35.037540] sudo[2822]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1698machine # [ 35.043122] sudo[2822]: pam_unix(sudo:session): session closed for user usertest1699machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1700machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1701machine # [ 35.062919] sudo[2828]: 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.service1702machine # [ 35.070803] sudo[2828]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1703machine # [ 35.082781] sudo[2828]: pam_unix(sudo:session): session closed for user usertest1704machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1705machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11706machine # [ 35.267629] nixos[2837]: switching to system configuration /nix/store/bkgcxsnl7zqy0k8d2x1px2izidbzbc7l-nixos-system-machine-test1707machine # [ 35.270056] systemd[1]: Stopped target Local File Systems.1708machine # [ 35.271678] systemd[1]: Stopped target Remote File Systems.1709machine # [ 35.500611] systemd[1]: Reload requested from client PID 2837 ('.switch-to-conf') (unit backdoor.service)...1710machine # [ 35.502145] systemd[1]: Reloading...1711machine # [ 35.729218] systemd[1]: Reloading finished in 225 ms.1712machine # [ 35.795079] systemd[623]: Reexecution requested from client PID 2906 ('switch-to-confi')...1713machine # [ 35.797677] systemd[623]: Reexecuting.1714machine # [ 35.817961] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1715machine # [ 35.894191] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1716machine # [ 35.899286] systemd[623]: Reload requested from client PID 2906 ('switch-to-confi')...1717machine # [ 35.900738] systemd[623]: Reloading...1718machine # [ 35.972333] systemd[623]: Reloading finished in 70 ms.1719machine # [ 35.976847] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1720machine # [ 35.983151] systemd[623]: Stopped Run user-specific NixOS activation.1721machine # [ 35.984183] systemd[623]: Stopping Run user-specific NixOS activation...1722machine # [ 35.986680] systemd[623]: Starting Run user-specific NixOS activation...1723machine # [ 36.023432] systemd[623]: Reload requested from client PID 2918 ('systemctl') (unit nixos-activation.service)...1724machine # [ 36.025715] systemd[623]: Reloading...1725machine # [ 36.099738] systemd[623]: Reloading finished in 74 ms.1726machine # [ 36.105026] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1727machine # [ 36.115862] systemd[623]: Stopped migrated.service.1728machine # [ 36.124580] systemd[623]: Finished Run user-specific NixOS activation.1729machine # [ 36.126494] systemd[623]: Reload requested from client PID 2906 ('switch-to-confi')...1730machine # [ 36.127989] systemd[623]: Reloading...1731machine # [ 36.198842] systemd[623]: Reloading finished in 69 ms.1732machine # [ 36.204850] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 36.211326] systemd[623]: Starting migrated.service...1734machine # [ 36.228963] systemd[623]: Finished migrated.service.1735machine # [ 36.231891] systemd[1]: Stopped target Reactivate sysinit units.1736machine # [ 36.233166] systemd[1]: Stopping Reactivate sysinit units...1737machine # [ 36.233939] systemd[1]: Reached target Reactivate sysinit units.1738machine # [ 36.236314] systemd[1]: Reached target Remote File Systems.1739machine # [ 36.243058] systemd[1]: Starting linger-users.service...1740machine # [ 36.248367] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1741machine # [ 36.249654] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1742machine # [ 36.253860] systemd[1]: Reached target Local File Systems.1743machine # [ 36.258067] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1744machine # [ 36.261238] systemd[1]: Starting Create SUID/SGID Wrappers...1745machine # [ 36.263335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1746machine # [ 36.264993] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671747machine # [ 36.266927] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1748machine # [ 36.268309] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1749machine # [ 36.269509] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1750machine # [ 36.300883] systemd[1]: linger-users.service: Deactivated successfully.1751machine # [ 36.303272] systemd[1]: Finished linger-users.service.1752machine # [ 36.410153] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1753machine # [ 36.411806] systemd[1]: Finished Create SUID/SGID Wrappers.1754machine # [ 36.669427] nixos[2837]: finished switching to system configuration /nix/store/bkgcxsnl7zqy0k8d2x1px2izidbzbc7l-nixos-system-machine-test1755machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 1.59 seconds)1756machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1757machine # [ 36.716750] sudo[2988]: 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.service1758machine # [ 36.732819] sudo[2988]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1759machine # [ 36.744396] sudo[2988]: pam_unix(sudo:session): session closed for user usertest1760machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1761machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1762machine # [ 36.763657] sudo[2994]: 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.service1763machine # [ 36.771305] sudo[2994]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1764machine # [ 36.783540] sudo[2994]: pam_unix(sudo:session): session closed for user usertest1765machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1766machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1767machine # [ 36.802894] sudo[3000]: 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-owner1768machine # [ 36.810280] sudo[3000]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1769machine # [ 36.815840] sudo[3000]: pam_unix(sudo:session): session closed for user usertest1770machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1771machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11772machine # [ 36.995275] nixos[3009]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1773machine # [ 36.999191] systemd[1]: Stopped target Local File Systems.1774machine # [ 37.000236] systemd[1]: Stopped target Remote File Systems.1775machine # [ 37.206259] systemd[1]: Reload requested from client PID 3009 ('.switch-to-conf') (unit backdoor.service)...1776machine # [ 37.207902] systemd[1]: Reloading...1777machine # [ 37.440981] systemd[1]: Reloading finished in 231 ms.1778machine # [ 37.501883] systemd[623]: Stopped migrated.service.1779machine # [ 37.503077] systemd[623]: Reexecution requested from client PID 3078 ('switch-to-confi')...1780machine # [ 37.504830] systemd[623]: Reexecuting.1781machine # [ 37.526045] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1782machine # [ 37.603265] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1783machine # [ 37.608697] systemd[623]: Reload requested from client PID 3078 ('switch-to-confi')...1784machine # [ 37.611053] systemd[623]: Reloading...1785machine # [ 37.684088] systemd[623]: Reloading finished in 73 ms.1786machine # [ 37.690071] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1787machine # [ 37.696617] systemd[623]: Stopped Run user-specific NixOS activation.1788machine # [ 37.698067] systemd[623]: Stopping Run user-specific NixOS activation...1789machine # [ 37.700291] systemd[623]: Starting Run user-specific NixOS activation...1790machine # [ 37.719245] systemd[623]: Finished Run user-specific NixOS activation.1791machine # [ 37.722062] systemd[1]: Stopped target Reactivate sysinit units.1792machine # [ 37.723567] systemd[1]: Stopping Reactivate sysinit units...1793machine # [ 37.724604] systemd[1]: Reached target Reactivate sysinit units.1794machine # [ 37.726245] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1795machine # [ 37.727961] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1796machine # [ 37.730532] systemd[1]: Reached target Local File Systems.1797machine # [ 37.735989] systemd[1]: Reached target Remote File Systems.1798machine # [ 37.738632] systemd[1]: Starting linger-users.service...1799machine # [ 37.747223] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1800machine # [ 37.750297] systemd[1]: Starting Create SUID/SGID Wrappers...1801machine # [ 37.751515] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1802machine # [ 37.753909] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671803machine # [ 37.757098] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1804machine # [ 37.758699] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1805machine # [ 37.759987] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1806machine # [ 37.792982] systemd[1]: linger-users.service: Deactivated successfully.1807machine # [ 37.794531] systemd[1]: Finished linger-users.service.1808machine # [ 37.942093] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1809machine # [ 37.944239] systemd[1]: Finished Create SUID/SGID Wrappers.1810machine # [ 38.200580] nixos[3009]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1811machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.39 seconds)1812machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1813machine # [ 38.245536] sudo[3147]: 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.service1814machine # [ 38.260414] sudo[3147]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1815machine # [ 38.269176] sudo[3147]: pam_unix(sudo:session): session closed for user usertest1816machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1817machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1818machine # [ 38.289524] sudo[3153]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1819machine # [ 38.296935] sudo[3153]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1820machine # [ 38.302480] sudo[3153]: pam_unix(sudo:session): session closed for user usertest1821machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)1822machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service1823machine # [ 38.322360] sudo[3159]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service1824machine # [ 38.330478] sudo[3159]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1825machine # [ 38.335815] sudo[3159]: pam_unix(sudo:session): session closed for user usertest1826machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)1827machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1828machine # [ 38.355421] sudo[3165]: 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.service1829machine # [ 38.362897] sudo[3165]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1830machine # [ 38.368412] sudo[3165]: pam_unix(sudo:session): session closed for user usertest1831machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1832machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1833machine # [ 38.387489] sudo[3171]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1834machine # [ 38.394675] sudo[3171]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1835machine # [ 38.400347] systemd[623]: Reload requested from client PID 3176 ('systemctl')...1836machine # [ 38.401514] systemd[623]: Reloading...1837machine # [ 38.475196] systemd[623]: Reloading finished in 72 ms.1838machine # [ 38.480868] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1839machine # [ 38.487676] sudo[3171]: pam_unix(sudo:session): session closed for user usertest1840machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)1841machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1842machine # [ 38.508312] sudo[3181]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1843machine # [ 38.515814] sudo[3181]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1844machine # [ 38.524910] systemd[623]: Starting migrated.service...1845machine # [ 38.539961] systemd[623]: Finished migrated.service.1846machine # [ 38.542787] sudo[3181]: pam_unix(sudo:session): session closed for user usertest1847machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.05 seconds)1848machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1849machine # [ 38.562318] sudo[3189]: 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.service1850machine # [ 38.570391] sudo[3189]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1851machine # [ 38.576712] sudo[3189]: pam_unix(sudo:session): session closed for user usertest1852machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)1853machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1854machine # [ 38.596284] sudo[3195]: 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-owner1855machine # [ 38.603548] sudo[3195]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1856machine # [ 38.609132] sudo[3195]: pam_unix(sudo:session): session closed for user usertest1857machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1858machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1859machine # [ 38.628815] sudo[3201]: 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.service1860machine # [ 38.636359] sudo[3201]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1861machine # [ 38.649649] sudo[3201]: pam_unix(sudo:session): session closed for user usertest1862machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1863machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11864machine # [ 38.827503] nixos[3210]: switching to system configuration /nix/store/1xzhpa0nhjdshfdpqxxvbxc7l2cmdwp7-nixos-system-machine-test1865machine # [ 38.829914] systemd[1]: Stopped target Local File Systems.1866machine # [ 38.831787] systemd[1]: Stopped target Remote File Systems.1867machine # [ 39.061827] systemd[1]: Reload requested from client PID 3210 ('.switch-to-conf') (unit backdoor.service)...1868machine # [ 39.063457] systemd[1]: Reloading...1869machine # [ 39.308955] systemd[1]: Reloading finished in 244 ms.1870machine # [ 39.375869] systemd[623]: Reexecution requested from client PID 3279 ('switch-to-confi')...1871machine # [ 39.377160] systemd[623]: Reexecuting.1872machine # [ 39.399427] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1873machine # [ 39.478961] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1874machine # [ 39.484151] systemd[623]: Reload requested from client PID 3279 ('switch-to-confi')...1875machine # [ 39.485770] systemd[623]: Reloading...1876machine # [ 39.560601] systemd[623]: Reloading finished in 73 ms.1877machine # [ 39.564853] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1878machine # [ 39.571047] systemd[623]: Stopped Run user-specific NixOS activation.1879machine # [ 39.572059] systemd[623]: Stopping Run user-specific NixOS activation...1880machine # [ 39.574491] systemd[623]: Starting Run user-specific NixOS activation...1881machine # [ 39.597736] systemd[623]: Reload requested from client PID 3291 ('systemctl') (unit nixos-activation.service)...1882machine # [ 39.599458] systemd[623]: Reloading...1883machine # [ 39.674486] systemd[623]: Reloading finished in 73 ms.1884machine # [ 39.680047] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1885machine # [ 39.690986] systemd[623]: Finished Run user-specific NixOS activation.1886machine # [ 39.693290] systemd[623]: Reload requested from client PID 3279 ('switch-to-confi')...1887machine # [ 39.694718] systemd[623]: Reloading...1888machine # [ 39.771979] systemd[623]: Reloading finished in 75 ms.1889machine # [ 39.777876] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1890machine # [ 39.784151] systemd[623]: Stopped migrated.service.1891machine # [ 39.785143] systemd[623]: Stopping migrated.service...1892machine # [ 39.787619] systemd[623]: Starting migrated.service...1893machine # [ 39.803873] systemd[623]: Finished migrated.service.1894machine # [ 39.809600] systemd[1]: Stopped target Reactivate sysinit units.1895machine # [ 39.811369] systemd[1]: Stopping Reactivate sysinit units...1896machine # [ 39.812486] systemd[1]: Reached target Reactivate sysinit units.1897machine # [ 39.816544] systemd[1]: Reached target Local File Systems.1898machine # [ 39.817604] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1899machine # [ 39.819951] systemd[1]: Starting Create SUID/SGID Wrappers...1900machine # [ 39.821327] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1901machine # [ 39.823498] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671902machine # [ 39.825456] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1903machine # [ 39.826863] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1904machine # [ 39.828076] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1905machine # [ 39.831626] systemd[1]: Reached target Remote File Systems.1906machine # [ 39.841928] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1907machine # [ 39.843726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1908machine # [ 39.966463] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1909machine # [ 39.968332] systemd[1]: Finished Create SUID/SGID Wrappers.1910machine # [ 39.972081] systemd[1]: Starting linger-users.service...1911machine # [ 39.998353] systemd[1]: linger-users.service: Deactivated successfully.1912machine # [ 40.000502] systemd[1]: Finished linger-users.service.1913machine # [ 40.256726] nixos[3210]: finished switching to system configuration /nix/store/1xzhpa0nhjdshfdpqxxvbxc7l2cmdwp7-nixos-system-machine-test1914machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 1.61 seconds)1915machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1916machine # [ 40.302872] sudo[3360]: 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.service1917machine # [ 40.316725] sudo[3360]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1918machine # [ 40.324358] sudo[3360]: pam_unix(sudo:session): session closed for user usertest1919machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1920machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1921machine # [ 40.344150] sudo[3366]: 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.service1922machine # [ 40.351580] sudo[3366]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1923machine # [ 40.364257] sudo[3366]: pam_unix(sudo:session): session closed for user usertest1924machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1925machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1926machine # [ 40.383671] sudo[3372]: 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-owner1927machine # [ 40.390976] sudo[3372]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1928machine # [ 40.396689] sudo[3372]: pam_unix(sudo:session): session closed for user usertest1929machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1930machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&11931machine # [ 40.576865] nixos[3381]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1932machine # [ 40.580352] systemd[1]: Stopped target Local File Systems.1933machine # [ 40.581348] systemd[1]: Stopped target Remote File Systems.1934machine # [ 40.787316] systemd[1]: Reload requested from client PID 3381 ('.switch-to-conf') (unit backdoor.service)...1935machine # [ 40.789184] systemd[1]: Reloading...1936machine # [ 41.006857] systemd[1]: Reloading finished in 215 ms.1937machine # [ 41.097543] systemd[623]: Stopped migrated.service.1938machine # [ 41.101951] systemd[623]: Reexecution requested from client PID 3450 ('switch-to-confi')...1939machine # [ 41.103454] systemd[623]: Reexecuting.1940machine # [ 41.123951] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1941machine # [ 41.196235] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1942machine # [ 41.201147] systemd[623]: Reload requested from client PID 3450 ('switch-to-confi')...1943machine # [ 41.203209] systemd[623]: Reloading...1944machine # [ 41.275418] systemd[623]: Reloading finished in 70 ms.1945machine # [ 41.280833] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1946machine # [ 41.287302] systemd[623]: Stopped Run user-specific NixOS activation.1947machine # [ 41.289060] systemd[623]: Stopping Run user-specific NixOS activation...1948machine # [ 41.290896] systemd[623]: Starting Run user-specific NixOS activation...1949machine # [ 41.309877] systemd[623]: Finished Run user-specific NixOS activation.1950machine # [ 41.313058] systemd[1]: Stopped target Reactivate sysinit units.1951machine # [ 41.314387] systemd[1]: Stopping Reactivate sysinit units...1952machine # [ 41.315527] systemd[1]: Reached target Reactivate sysinit units.1953machine # [ 41.319697] systemd[1]: Reached target Remote File Systems.1954machine # [ 41.322688] systemd[1]: Starting linger-users.service...1955machine # [ 41.324241] systemd[1]: Reached target Local File Systems.1956machine # [ 41.331374] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1957machine # [ 41.333425] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1958machine # [ 41.341104] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1959machine # [ 41.345303] systemd[1]: Starting Create SUID/SGID Wrappers...1960machine # [ 41.346846] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1961machine # [ 41.348221] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671962machine # [ 41.350454] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1963machine # [ 41.352113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1964machine # [ 41.356072] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1965machine # [ 41.381991] systemd[1]: linger-users.service: Deactivated successfully.1966machine # [ 41.383834] systemd[1]: Finished linger-users.service.1967machine # [ 41.494513] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1968machine # [ 41.497361] systemd[1]: Finished Create SUID/SGID Wrappers.1969machine # [ 41.751437] nixos[3381]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test1970machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.36 seconds)1971machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1972machine # [ 41.786219] sudo[3519]: 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.service1973machine # [ 41.794844] sudo[3519]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1974machine # [ 41.802553] sudo[3519]: pam_unix(sudo:session): session closed for user usertest1975machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1976machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1977machine # [ 41.822284] sudo[3525]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1978machine # [ 41.829333] sudo[3525]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1979machine # [ 41.834713] sudo[3525]: pam_unix(sudo:session): session closed for user usertest1980machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)1981machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service1982machine # [ 41.854316] sudo[3531]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service1983machine # [ 41.862512] sudo[3531]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1984machine # [ 41.867820] sudo[3531]: pam_unix(sudo:session): session closed for user usertest1985machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)1986machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1987machine # [ 41.886873] sudo[3537]: 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.service1988machine # [ 41.894351] sudo[3537]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1989machine # [ 41.899862] sudo[3537]: pam_unix(sudo:session): session closed for user usertest1990machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1991machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1992machine # [ 41.918892] sudo[3543]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1993machine # [ 41.926430] sudo[3543]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1994machine # [ 41.931585] systemd[623]: Reload requested from client PID 3548 ('systemctl')...1995machine # [ 41.933057] systemd[623]: Reloading...1996machine # [ 42.008062] systemd[623]: Reloading finished in 73 ms.1997machine # [ 42.013909] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1998machine # [ 42.020436] sudo[3543]: pam_unix(sudo:session): session closed for user usertest1999machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2000machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2001machine # [ 42.040665] sudo[3553]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2002machine # [ 42.048461] sudo[3553]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2003machine # [ 42.058531] systemd[623]: Starting migrated.service...2004machine # [ 42.073236] systemd[623]: Finished migrated.service.2005machine # [ 42.075985] sudo[3553]: pam_unix(sudo:session): session closed for user usertest2006machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.05 seconds)2007machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2008machine # [ 42.095314] sudo[3561]: 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.service2009machine # [ 42.103276] sudo[3561]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2010machine # [ 42.112437] sudo[3561]: pam_unix(sudo:session): session closed for user usertest2011machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)2012machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2013machine # [ 42.131746] sudo[3567]: 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-owner2014machine # [ 42.139499] sudo[3567]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2015machine # [ 42.145157] sudo[3567]: pam_unix(sudo:session): session closed for user usertest2016machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2017machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2018machine # [ 42.165699] sudo[3573]: 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.service2019machine # [ 42.173518] sudo[3573]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2020machine # [ 42.185079] sudo[3573]: pam_unix(sudo:session): session closed for user usertest2021machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2022machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&12023machine # [ 42.365379] nixos[3582]: switching to system configuration /nix/store/cwpxy45c0q1prpi6ln6czv279fsvqzpl-nixos-system-machine-test2024machine # [ 42.367679] systemd[1]: Stopped target Local File Systems.2025machine # [ 42.369308] systemd[1]: Stopped target Remote File Systems.2026machine # [ 42.590195] systemd[1]: Reload requested from client PID 3582 ('.switch-to-conf') (unit backdoor.service)...2027machine # [ 42.591810] systemd[1]: Reloading...2028machine # [ 42.829101] systemd[1]: Reloading finished in 235 ms.2029machine # [ 42.886759] systemd[623]: Reexecution requested from client PID 3651 ('switch-to-confi')...2030machine # [ 42.889331] systemd[623]: Reexecuting.2031machine # [ 42.909036] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2032machine # [ 42.987900] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2033machine # [ 42.993045] systemd[623]: Reload requested from client PID 3651 ('switch-to-confi')...2034machine # [ 42.994407] systemd[623]: Reloading...2035machine # [ 43.066803] systemd[623]: Reloading finished in 71 ms.2036machine # [ 43.071854] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2037machine # [ 43.079061] systemd[623]: Stopped Run user-specific NixOS activation.2038machine # [ 43.079970] systemd[623]: Stopping Run user-specific NixOS activation...2039machine # [ 43.081605] systemd[623]: Starting Run user-specific NixOS activation...2040machine # [ 43.104858] systemd[623]: Reload requested from client PID 3663 ('systemctl') (unit nixos-activation.service)...2041machine # [ 43.106530] systemd[623]: Reloading...2042machine # [ 43.187293] systemd[623]: Reloading finished in 79 ms.2043machine # [ 43.193028] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 43.203392] systemd[623]: Finished Run user-specific NixOS activation.2045machine # [ 43.205383] systemd[623]: Reload requested from client PID 3651 ('switch-to-confi')...2046machine # [ 43.207104] systemd[623]: Reloading...2047machine # [ 43.279229] systemd[623]: Reloading finished in 70 ms.2048machine # [ 43.284845] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2049machine # [ 43.291241] systemd[623]: Reloading migrated.service...2050machine # [ 43.305946] systemd[623]: Reloaded migrated.service.2051machine # [ 43.311405] systemd[1]: Stopped target Reactivate sysinit units.2052machine # [ 43.312698] systemd[1]: Stopping Reactivate sysinit units...2053machine # [ 43.313763] systemd[1]: Reached target Reactivate sysinit units.2054machine # [ 43.316899] systemd[1]: Reached target Local File Systems.2055machine # [ 43.321063] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2056machine # [ 43.324250] systemd[1]: Starting Create SUID/SGID Wrappers...2057machine # [ 43.325103] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2058machine # [ 43.326246] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672059machine # [ 43.328190] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2060machine # [ 43.329661] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2061machine # [ 43.333298] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2062machine # [ 43.336111] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2063machine # [ 43.337426] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2064machine # [ 43.343862] systemd[1]: Reached target Remote File Systems.2065machine # [ 43.468212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2066machine # [ 43.469913] systemd[1]: Finished Create SUID/SGID Wrappers.2067machine # [ 43.473531] systemd[1]: Starting linger-users.service...2068machine # [ 43.500395] systemd[1]: linger-users.service: Deactivated successfully.2069machine # [ 43.501846] systemd[1]: Finished linger-users.service.2070machine # [ 43.759484] nixos[3582]: finished switching to system configuration /nix/store/cwpxy45c0q1prpi6ln6czv279fsvqzpl-nixos-system-machine-test2071machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 1.58 seconds)2072machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2073machine # [ 43.806750] sudo[3732]: 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.service2074machine # [ 43.819782] sudo[3732]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2075machine # [ 43.828550] sudo[3732]: pam_unix(sudo:session): session closed for user usertest2076machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2077machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2078machine # [ 43.849602] sudo[3738]: 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-owner2079machine # [ 43.857455] sudo[3738]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2080machine # [ 43.862767] sudo[3738]: pam_unix(sudo:session): session closed for user usertest2081machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2082machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12083machine # [ 44.058314] nixos[3747]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2084machine # [ 44.060431] systemd[1]: Stopped target Local File Systems.2085machine # [ 44.062055] systemd[1]: Stopped target Remote File Systems.2086machine # [ 44.267043] systemd[1]: Reload requested from client PID 3747 ('.switch-to-conf') (unit backdoor.service)...2087machine # [ 44.268687] systemd[1]: Reloading...2088machine # [ 44.497026] systemd[1]: Reloading finished in 226 ms.2089machine # [ 44.560207] systemd[623]: Stopped migrated.service.2090machine # [ 44.562079] systemd[623]: Reexecution requested from client PID 3816 ('switch-to-confi')...2091machine # [ 44.563306] systemd[623]: Reexecuting.2092machine # [ 44.583965] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2093machine # [ 44.656362] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2094machine # [ 44.660950] systemd[623]: Reload requested from client PID 3816 ('switch-to-confi')...2095machine # [ 44.662550] systemd[623]: Reloading...2096machine # [ 44.734167] systemd[623]: Reloading finished in 70 ms.2097machine # [ 44.740828] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2098machine # [ 44.747117] systemd[623]: Stopped Run user-specific NixOS activation.2099machine # [ 44.748093] systemd[623]: Stopping Run user-specific NixOS activation...2100machine # [ 44.750577] systemd[623]: Starting Run user-specific NixOS activation...2101machine # [ 44.769063] systemd[623]: Finished Run user-specific NixOS activation.2102machine # [ 44.771961] systemd[1]: Stopped target Reactivate sysinit units.2103machine # [ 44.773258] systemd[1]: Stopping Reactivate sysinit units...2104machine # [ 44.774300] systemd[1]: Reached target Reactivate sysinit units.2105machine # [ 44.776345] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2106machine # [ 44.778244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2107machine # [ 44.780514] systemd[1]: Reached target Local File Systems.2108machine # [ 44.783930] systemd[1]: Reached target Remote File Systems.2109machine # [ 44.786459] systemd[1]: Starting linger-users.service...2110machine # [ 44.788773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2111machine # [ 44.794435] systemd[1]: Starting Create SUID/SGID Wrappers...2112machine # [ 44.797521] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2113machine # [ 44.798887] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672114machine # [ 44.801437] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2115machine # [ 44.802859] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2116machine # [ 44.806164] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2117machine # [ 44.844475] systemd[1]: linger-users.service: Deactivated successfully.2118machine # [ 44.845969] systemd[1]: Finished linger-users.service.2119machine # [ 44.950334] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2120machine # [ 44.953667] systemd[1]: Finished Create SUID/SGID Wrappers.2121machine # [ 45.206468] nixos[3747]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2122machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.34 seconds)2123machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2124machine # [ 45.232364] sudo[3884]: 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.service2125machine # [ 45.243804] sudo[3884]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2126machine # [ 45.251887] sudo[3884]: pam_unix(sudo:session): session closed for user usertest2127machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)2128machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2129machine # [ 45.271761] sudo[3890]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2130machine # [ 45.278961] sudo[3890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2131machine # [ 45.284438] sudo[3890]: pam_unix(sudo:session): session closed for user usertest2132machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)2133machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service2134machine # [ 45.307573] sudo[3896]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service2135machine # [ 45.315259] sudo[3896]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2136machine # [ 45.320830] sudo[3896]: pam_unix(sudo:session): session closed for user usertest2137machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds)2138machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2139machine # [ 45.340237] sudo[3902]: 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.service2140machine # [ 45.347698] sudo[3902]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 45.353246] sudo[3902]: pam_unix(sudo:session): session closed for user usertest2142machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)2143machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2144machine # [ 45.372244] sudo[3908]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2145machine # [ 45.379674] sudo[3908]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2146machine # [ 45.385231] systemd[623]: Reload requested from client PID 3913 ('systemctl')...2147machine # [ 45.386601] systemd[623]: Reloading...2148machine # [ 45.461329] systemd[623]: Reloading finished in 73 ms.2149machine # [ 45.465837] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2150machine # [ 45.471617] sudo[3908]: pam_unix(sudo:session): session closed for user usertest2151machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2152machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2153machine # [ 45.493201] sudo[3918]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2154machine # [ 45.500694] sudo[3918]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2155machine # [ 45.509518] systemd[623]: Starting migrated.service...2156machine # [ 45.524901] systemd[623]: Finished migrated.service.2157machine # [ 45.527897] sudo[3918]: pam_unix(sudo:session): session closed for user usertest2158machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.05 seconds)2159machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2160machine # [ 45.547819] sudo[3926]: 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.service2161machine # [ 45.555440] sudo[3926]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2162machine # [ 45.561731] sudo[3926]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2165machine # [ 45.581538] sudo[3932]: 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-owner2166machine # [ 45.589520] sudo[3932]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 45.594701] sudo[3932]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2169machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2170machine # [ 45.617690] sudo[3938]: 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.service2171machine # [ 45.627597] sudo[3938]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2172machine # [ 45.638857] sudo[3938]: pam_unix(sudo:session): session closed for user usertest2173machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2174machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12175machine # [ 45.823552] nixos[3947]: switching to system configuration /nix/store/fvr2zrgck1z1ikj5b7nib0jch6gpwwhn-nixos-system-machine-test2176machine # [ 45.825856] systemd[1]: Stopped target Local File Systems.2177machine # [ 45.827598] systemd[1]: Stopped target Remote File Systems.2178machine # [ 46.046856] systemd[1]: Reload requested from client PID 3947 ('.switch-to-conf') (unit backdoor.service)...2179machine # [ 46.048444] systemd[1]: Reloading...2180machine # [ 46.275381] systemd[1]: Reloading finished in 225 ms.2181machine # [ 46.342268] systemd[623]: Reexecution requested from client PID 4016 ('switch-to-confi')...2182machine # [ 46.344323] systemd[623]: Reexecuting.2183machine # [ 46.365026] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2184machine # [ 46.439079] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2185machine # [ 46.444910] systemd[623]: Reload requested from client PID 4016 ('switch-to-confi')...2186machine # [ 46.446715] systemd[623]: Reloading...2187machine # [ 46.517323] systemd[623]: Reloading finished in 69 ms.2188machine # [ 46.522844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 46.529944] systemd[623]: Stopped Run user-specific NixOS activation.2190machine # [ 46.530804] systemd[623]: Stopping Run user-specific NixOS activation...2191machine # [ 46.532295] systemd[623]: Starting Run user-specific NixOS activation...2192machine # [ 46.555179] systemd[623]: Reload requested from client PID 4028 ('systemctl') (unit nixos-activation.service)...2193machine # [ 46.556862] systemd[623]: Reloading...2194machine # [ 46.631995] systemd[623]: Reloading finished in 73 ms.2195machine # [ 46.638040] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 46.647953] systemd[623]: Finished Run user-specific NixOS activation.2197machine # [ 46.649973] systemd[623]: Reload requested from client PID 4016 ('switch-to-confi')...2198machine # [ 46.651791] systemd[623]: Reloading...2199machine # [ 46.726367] systemd[623]: Reloading finished in 73 ms.2200machine # [ 46.730844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2201machine # [ 46.739477] systemd[1]: Stopped target Reactivate sysinit units.2202machine # [ 46.740717] systemd[1]: Stopping Reactivate sysinit units...2203machine # [ 46.741926] systemd[1]: Reached target Reactivate sysinit units.2204machine # [ 46.743169] systemd[1]: Reached target Remote File Systems.2205machine # [ 46.744702] systemd[1]: Reached target Local File Systems.2206machine # [ 46.747355] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2207machine # [ 46.749102] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2208machine # [ 46.754582] systemd[1]: Starting linger-users.service...2209machine # [ 46.760383] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2210machine # [ 46.763448] systemd[1]: Starting Create SUID/SGID Wrappers...2211machine # [ 46.764225] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2212machine # [ 46.765351] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672213machine # [ 46.767261] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2214machine # [ 46.768774] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2215machine # [ 46.769885] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 46.811730] systemd[1]: linger-users.service: Deactivated successfully.2217machine # [ 46.813560] systemd[1]: Finished linger-users.service.2218machine # [ 46.914900] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2219machine # [ 46.917675] systemd[1]: Finished Create SUID/SGID Wrappers.2220machine # [ 47.172494] nixos[3947]: finished switching to system configuration /nix/store/fvr2zrgck1z1ikj5b7nib0jch6gpwwhn-nixos-system-machine-test2221machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 1.54 seconds)2222machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2223machine # [ 47.209767] sudo[4095]: 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.service2224machine # [ 47.223283] sudo[4095]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2225machine # [ 47.230297] sudo[4095]: pam_unix(sudo:session): session closed for user usertest2226machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2227machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2228machine # [ 47.249766] sudo[4101]: 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-owner2229machine # [ 47.257388] sudo[4101]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2230machine # [ 47.262511] sudo[4101]: pam_unix(sudo:session): session closed for user usertest2231machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2232machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12233machine # [ 47.441212] nixos[4110]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2234machine # [ 47.444270] systemd[1]: Stopped target Local File Systems.2235machine # [ 47.445189] systemd[1]: Stopped target Remote File Systems.2236machine # [ 47.650737] systemd[1]: Reload requested from client PID 4110 ('.switch-to-conf') (unit backdoor.service)...2237machine # [ 47.652344] systemd[1]: Reloading...2238machine # [ 47.878441] systemd[1]: Reloading finished in 224 ms.2239machine # [ 47.943270] systemd[623]: Stopped migrated.service.2240machine # [ 47.944474] systemd[623]: Reexecution requested from client PID 4179 ('switch-to-confi')...2241machine # [ 47.946790] systemd[623]: Reexecuting.2242machine # [ 47.965982] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2243machine # [ 48.040755] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2244machine # [ 48.045885] systemd[623]: Reload requested from client PID 4179 ('switch-to-confi')...2245machine # [ 48.047330] systemd[623]: Reloading...2246machine # [ 48.121472] systemd[623]: Reloading finished in 72 ms.2247machine # [ 48.126851] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2248machine # [ 48.132854] systemd[623]: Stopped Run user-specific NixOS activation.2249machine # [ 48.134836] systemd[623]: Stopping Run user-specific NixOS activation...2250machine # [ 48.136266] systemd[623]: Starting Run user-specific NixOS activation...2251machine # [ 48.154888] systemd[623]: Finished Run user-specific NixOS activation.2252machine # [ 48.158691] systemd[1]: Stopped target Reactivate sysinit units.2253machine # [ 48.159752] systemd[1]: Stopping Reactivate sysinit units...2254machine # [ 48.160857] systemd[1]: Reached target Reactivate sysinit units.2255machine # [ 48.164347] systemd[1]: Reached target Local File Systems.2256machine # [ 48.166564] systemd[1]: Reached target Remote File Systems.2257machine # [ 48.169336] systemd[1]: Starting linger-users.service...2258machine # [ 48.175982] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2259machine # [ 48.177297] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2260machine # [ 48.181659] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2261machine # [ 48.185064] systemd[1]: Starting Create SUID/SGID Wrappers...2262machine # [ 48.185863] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2263machine # [ 48.188253] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672264machine # [ 48.191623] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2265machine # [ 48.194383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2266machine # [ 48.195575] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2267machine # [ 48.226871] systemd[1]: linger-users.service: Deactivated successfully.2268machine # [ 48.228938] systemd[1]: Finished linger-users.service.2269machine # [ 48.334054] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2270machine # [ 48.335624] systemd[1]: Finished Create SUID/SGID Wrappers.2271machine # [ 48.592381] nixos[4110]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2272machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds)2273machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2274machine # [ 48.638579] sudo[4247]: 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.service2275machine # [ 48.652809] sudo[4247]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2276machine # [ 48.663976] sudo[4247]: pam_unix(sudo:session): session closed for user usertest2277machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2278machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2279machine # [ 48.686964] sudo[4253]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2280machine # [ 48.694461] sudo[4253]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2281machine # [ 48.699826] sudo[4253]: pam_unix(sudo:session): session closed for user usertest2282machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.04 seconds)2283machine: must succeed: sudo -u usertest cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service ~usertest/.local/share/systemd/user/migrated.service2284machine # [ 48.719363] sudo[4259]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2285machine # [ 48.727424] sudo[4259]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2286machine # [ 48.732862] sudo[4259]: pam_unix(sudo:session): session closed for user usertest2287machine: (finished: must succeed: sudo -u usertest cp /nix/store/p1fvsnwg9qs5r0w7khvj225ygkzx9zsw-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.03 seconds)2288machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2289machine # [ 48.752294] sudo[4265]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2290machine # [ 48.759418] sudo[4265]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2291machine # [ 48.764912] systemd[623]: Reload requested from client PID 4270 ('systemctl')...2292machine # [ 48.766372] systemd[623]: Reloading...2293machine # [ 48.839140] systemd[623]: Reloading finished in 70 ms.2294machine # [ 48.842855] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2295machine # [ 48.849329] sudo[4265]: pam_unix(sudo:session): session closed for user usertest2296machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2297machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2298machine # [ 48.870663] sudo[4275]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2299machine # [ 48.878571] sudo[4275]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2300machine # [ 48.888949] systemd[623]: Starting migrated.service...2301machine # [ 48.903955] systemd[623]: Finished migrated.service.2302machine # [ 48.906462] sudo[4275]: pam_unix(sudo:session): session closed for user usertest2303machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2304machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2305machine # [ 48.926842] sudo[4283]: 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.service2306machine # [ 48.934513] sudo[4283]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2307machine # [ 48.940962] sudo[4283]: pam_unix(sudo:session): session closed for user usertest2308machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)2309machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2310machine # [ 48.960364] sudo[4289]: 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-owner2311machine # [ 48.970313] sudo[4289]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2312machine # [ 48.975873] sudo[4289]: pam_unix(sudo:session): session closed for user usertest2313machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2314machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2315machine # [ 48.994978] sudo[4295]: 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.service2316machine # [ 49.002467] sudo[4295]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2317machine # [ 49.017744] sudo[4295]: pam_unix(sudo:session): session closed for user usertest2318machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2319machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12320machine # [ 49.196602] nixos[4304]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2321machine # [ 49.198969] systemd[1]: Stopped target Remote File Systems.2322machine # [ 49.200694] systemd[1]: Stopped target Local File Systems.2323machine # [ 49.419922] systemd[1]: Reload requested from client PID 4304 ('.switch-to-conf') (unit backdoor.service)...2324machine # [ 49.421517] systemd[1]: Reloading...2325machine # [ 49.646846] systemd[1]: Reloading finished in 223 ms.2326machine # [ 49.711270] systemd[623]: Reexecution requested from client PID 4373 ('switch-to-confi')...2327machine # [ 49.712478] systemd[623]: Reexecuting.2328machine # [ 49.732971] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2329machine # [ 49.811086] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2330machine # [ 49.816786] systemd[623]: Reload requested from client PID 4373 ('switch-to-confi')...2331machine # [ 49.818251] systemd[623]: Reloading...2332machine # [ 49.892388] systemd[623]: Reloading finished in 72 ms.2333machine # [ 49.897876] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2334machine # [ 49.904750] systemd[623]: Stopped Run user-specific NixOS activation.2335machine # [ 49.905668] systemd[623]: Stopping Run user-specific NixOS activation...2336machine # [ 49.907230] systemd[623]: Starting Run user-specific NixOS activation...2337machine # [ 49.926366] systemd[623]: Finished Run user-specific NixOS activation.2338machine # [ 49.927886] systemd[623]: Reload requested from client PID 4373 ('switch-to-confi')...2339machine # [ 49.929476] systemd[623]: Reloading...2340machine # [ 50.001767] systemd[623]: Reloading finished in 70 ms.2341machine # [ 50.006840] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2342machine # [ 50.012222] systemd[623]: Stopped migrated.service.2343machine # [ 50.013053] systemd[623]: Stopping migrated.service...2344machine # [ 50.015306] systemd[623]: Starting migrated.service...2345machine # [ 50.030981] systemd[623]: Finished migrated.service.2346machine # [ 50.036350] systemd[1]: Stopped target Reactivate sysinit units.2347machine # [ 50.037627] systemd[1]: Stopping Reactivate sysinit units...2348machine # [ 50.038747] systemd[1]: Reached target Reactivate sysinit units.2349machine # [ 50.041252] systemd[1]: Reached target Local File Systems.2350machine # [ 50.043579] systemd[1]: Reached target Remote File Systems.2351machine # [ 50.048063] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2352machine # [ 50.049247] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2353machine # [ 50.051834] systemd[1]: Starting linger-users.service...2354machine # [ 50.059440] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2355machine # [ 50.063204] systemd[1]: Starting Create SUID/SGID Wrappers...2356machine # [ 50.065460] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2357machine # [ 50.067440] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672358machine # [ 50.070326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2359machine # [ 50.071778] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2360machine # [ 50.072905] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2361machine # [ 50.106507] systemd[1]: linger-users.service: Deactivated successfully.2362machine # [ 50.108315] systemd[1]: Finished linger-users.service.2363machine # [ 50.215895] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2364machine # [ 50.218064] systemd[1]: Finished Create SUID/SGID Wrappers.2365machine # [ 50.474573] nixos[4304]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2366machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.46 seconds)2367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2368machine # [ 50.521380] sudo[4447]: 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.service2369machine # [ 50.535537] sudo[4447]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2370machine # [ 50.543565] sudo[4447]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2373machine # [ 50.563629] sudo[4453]: 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.service2374machine # [ 50.571481] sudo[4453]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 50.583424] sudo[4453]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2377machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2378machine # [ 50.603655] sudo[4459]: 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-owner2379machine # [ 50.612477] sudo[4459]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2380machine # [ 50.618224] sudo[4459]: pam_unix(sudo:session): session closed for user usertest2381machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2382machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12383machine # [ 50.800524] nixos[4468]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2384machine # [ 50.803580] systemd[1]: Stopped target Local File Systems.2385machine # [ 50.804715] systemd[1]: Stopped target Remote File Systems.2386machine # [ 51.008303] systemd[1]: Reload requested from client PID 4468 ('.switch-to-conf') (unit backdoor.service)...2387machine # [ 51.010142] systemd[1]: Reloading...2388machine # [ 51.300142] systemd[1]: Reloading finished in 287 ms.2389machine # [ 51.365082] systemd[623]: Reexecution requested from client PID 4537 ('switch-to-confi')...2390machine # [ 51.366295] systemd[623]: Reexecuting.2391machine # [ 51.386796] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2392machine # [ 51.462652] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 51.468364] systemd[623]: Reload requested from client PID 4537 ('switch-to-confi')...2394machine # [ 51.469450] systemd[623]: Reloading...2395machine # [ 51.540749] systemd[623]: Reloading finished in 70 ms.2396machine # [ 51.547066] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2397machine # [ 51.553735] systemd[623]: Stopped Run user-specific NixOS activation.2398machine # [ 51.555435] systemd[623]: Stopping Run user-specific NixOS activation...2399machine # [ 51.557669] systemd[623]: Starting Run user-specific NixOS activation...2400machine # [ 51.578182] systemd[623]: Finished Run user-specific NixOS activation.2401machine # [ 51.581061] systemd[1]: Stopped target Reactivate sysinit units.2402machine # [ 51.582542] systemd[1]: Stopping Reactivate sysinit units...2403machine # [ 51.583472] systemd[1]: Reached target Reactivate sysinit units.2404machine # [ 51.585808] systemd[1]: Reached target Remote File Systems.2405machine # [ 51.588116] systemd[1]: Reached target Local File Systems.2406machine # [ 51.594169] systemd[1]: Starting linger-users.service...2407machine # [ 51.597050] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2408machine # [ 51.598324] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2409machine # [ 51.603773] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2410machine # [ 51.606439] systemd[1]: Starting Create SUID/SGID Wrappers...2411machine # [ 51.608497] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2412machine # [ 51.610196] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672413machine # [ 51.612160] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2414machine # [ 51.613675] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2415machine # [ 51.616938] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2416machine # [ 51.654723] systemd[1]: linger-users.service: Deactivated successfully.2417machine # [ 51.656783] systemd[1]: Finished linger-users.service.2418machine # [ 51.756545] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2419machine # [ 51.758167] systemd[1]: Finished Create SUID/SGID Wrappers.2420machine # [ 52.016204] nixos[4468]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2421machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.40 seconds)2422machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2423machine # [ 52.060709] sudo[4605]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2424machine # [ 52.074921] sudo[4605]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2425machine # [ 52.084762] sudo[4605]: pam_unix(sudo:session): session closed for user usertest2426machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.06 seconds)2427machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2428machine # [ 52.104649] sudo[4611]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2429machine # [ 52.111992] sudo[4611]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2430machine # [ 52.117722] systemd[623]: Reload requested from client PID 4616 ('systemctl')...2431machine # [ 52.118870] systemd[623]: Reloading...2432machine # [ 52.192376] systemd[623]: Reloading finished in 71 ms.2433machine # [ 52.197846] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2434machine # [ 52.205408] sudo[4611]: pam_unix(sudo:session): session closed for user usertest2435machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2436machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12437machine # [ 52.382240] nixos[4624]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2438machine # [ 52.384538] systemd[1]: Stopped target Local File Systems.2439machine # [ 52.386305] systemd[1]: Stopped target Remote File Systems.2440machine # [ 52.593801] systemd[1]: Reload requested from client PID 4624 ('.switch-to-conf') (unit backdoor.service)...2441machine # [ 52.595378] systemd[1]: Reloading...2442machine # [ 52.832073] systemd[1]: Reloading finished in 234 ms.2443machine # [ 52.895282] systemd[623]: Stopped migrated.service.2444machine # [ 52.896574] systemd[623]: Reexecution requested from client PID 4693 ('switch-to-confi')...2445machine # [ 52.898938] systemd[623]: Reexecuting.2446machine # [ 52.920083] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2447machine # [ 52.993439] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2448machine # [ 52.998678] systemd[623]: Reload requested from client PID 4693 ('switch-to-confi')...2449machine # [ 52.999825] systemd[623]: Reloading...2450machine # [ 53.072148] systemd[623]: Reloading finished in 70 ms.2451machine # [ 53.077857] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2452machine # [ 53.084250] systemd[623]: Stopped Run user-specific NixOS activation.2453machine # [ 53.085127] systemd[623]: Stopping Run user-specific NixOS activation...2454machine # [ 53.087174] systemd[623]: Starting Run user-specific NixOS activation...2455machine # [ 53.105682] systemd[623]: Finished Run user-specific NixOS activation.2456machine # [ 53.108392] systemd[1]: Stopped target Reactivate sysinit units.2457machine # [ 53.109652] systemd[1]: Stopping Reactivate sysinit units...2458machine # [ 53.110741] systemd[1]: Reached target Reactivate sysinit units.2459machine # [ 53.114036] systemd[1]: Reached target Local File Systems.2460machine # [ 53.116241] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2461machine # [ 53.118349] systemd[1]: Starting Create SUID/SGID Wrappers...2462machine # [ 53.120115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2463machine # [ 53.121685] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672464machine # [ 53.123537] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2465machine # [ 53.124919] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2466machine # [ 53.126107] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2467machine # [ 53.130254] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2468machine # [ 53.131476] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2469machine # [ 53.136140] systemd[1]: Reached target Remote File Systems.2470machine # [ 53.258781] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2471machine # [ 53.260914] systemd[1]: Finished Create SUID/SGID Wrappers.2472machine # [ 53.264144] systemd[1]: Starting linger-users.service...2473machine # [ 53.291238] systemd[1]: linger-users.service: Deactivated successfully.2474machine # [ 53.292683] systemd[1]: Finished linger-users.service.2475machine # [ 53.549673] nixos[4624]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2476machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds)2477machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2478machine # [ 53.597570] sudo[4761]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2479machine # [ 53.611962] sudo[4761]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2480machine # [ 53.617330] sudo[4761]: pam_unix(sudo:session): session closed for user usertest2481machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)2482machine: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service2483machine # [ 53.637121] sudo[4767]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service /home/usertest/.config/systemd/user/migrated.service2484machine # [ 53.644590] sudo[4767]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2485machine # [ 53.650220] sudo[4767]: pam_unix(sudo:session): session closed for user usertest2486machine: (finished: must succeed: sudo -u usertest cp /nix/store/wygahn4h3x3njdbmyxaj758csvkgh499-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)2487machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2488machine # [ 53.670127] sudo[4773]: 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.service2489machine # [ 53.677853] sudo[4773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2490machine # [ 53.683383] sudo[4773]: pam_unix(sudo:session): session closed for user usertest2491machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)2492machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2493machine # [ 53.702453] sudo[4779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2494machine # [ 53.709531] sudo[4779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2495machine # [ 53.715120] systemd[623]: Reload requested from client PID 4784 ('systemctl')...2496machine # [ 53.716493] systemd[623]: Reloading...2497machine # [ 53.789969] systemd[623]: Reloading finished in 72 ms.2498machine # [ 53.794846] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2499machine # [ 53.803061] sudo[4779]: pam_unix(sudo:session): session closed for user usertest2500machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2501machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2502machine # [ 53.823461] sudo[4789]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2503machine # [ 53.830726] sudo[4789]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2504machine # [ 53.837968] systemd[623]: Starting migrated.service...2505machine # [ 53.855937] systemd[623]: Finished migrated.service.2506machine # [ 53.858954] sudo[4789]: pam_unix(sudo:session): session closed for user usertest2507machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2508machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2509machine # [ 53.879444] sudo[4797]: 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.service2510machine # [ 53.886801] sudo[4797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2511machine # [ 53.893740] sudo[4797]: pam_unix(sudo:session): session closed for user usertest2512machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)2513machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2514machine # [ 53.913444] sudo[4803]: 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-owner2515machine # [ 53.920757] sudo[4803]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2516machine # [ 53.926396] sudo[4803]: pam_unix(sudo:session): session closed for user usertest2517machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2518machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2519machine # [ 53.949792] sudo[4809]: 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.service2520machine # [ 53.957238] sudo[4809]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2521machine # [ 53.968521] sudo[4809]: pam_unix(sudo:session): session closed for user usertest2522machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2523machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12524machine # [ 54.145597] nixos[4818]: switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2525machine # [ 54.147870] systemd[1]: Stopped target Local File Systems.2526machine # [ 54.149704] systemd[1]: Stopped target Remote File Systems.2527machine # [ 54.352250] systemd[1]: Reload requested from client PID 4818 ('.switch-to-conf') (unit backdoor.service)...2528machine # [ 54.353707] systemd[1]: Reloading...2529machine # [ 54.583108] systemd[1]: Reloading finished in 227 ms.2530machine # [ 54.644934] systemd[623]: Reexecution requested from client PID 4887 ('switch-to-confi')...2531machine # [ 54.647386] systemd[623]: Reexecuting.2532machine # [ 54.666950] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2533machine # [ 54.739269] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2534machine # [ 54.744445] systemd[623]: Reload requested from client PID 4887 ('switch-to-confi')...2535machine # [ 54.746291] systemd[623]: Reloading...2536machine # [ 54.817258] systemd[623]: Reloading finished in 70 ms.2537machine # [ 54.822835] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2538machine # [ 54.829380] systemd[623]: Stopped Run user-specific NixOS activation.2539machine # [ 54.830433] systemd[623]: Stopping Run user-specific NixOS activation...2540machine # [ 54.832762] systemd[623]: Starting Run user-specific NixOS activation...2541machine # [ 54.851892] systemd[623]: Finished Run user-specific NixOS activation.2542machine # [ 54.853543] systemd[623]: Reload requested from client PID 4887 ('switch-to-confi')...2543machine # [ 54.854980] systemd[623]: Reloading...2544machine # [ 54.926412] systemd[623]: Reloading finished in 70 ms.2545machine # [ 54.931825] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2546machine # [ 54.940382] systemd[1]: Stopped target Reactivate sysinit units.2547machine # [ 54.941740] systemd[1]: Stopping Reactivate sysinit units...2548machine # [ 54.942603] systemd[1]: Reached target Reactivate sysinit units.2549machine # [ 54.944491] systemd[1]: Reached target Remote File Systems.2550machine # [ 54.947406] systemd[1]: Starting linger-users.service...2551machine # [ 54.952310] systemd[1]: Reached target Local File Systems.2552machine # [ 54.953128] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2553machine # [ 54.958388] systemd[1]: Starting Create SUID/SGID Wrappers...2554machine # [ 54.959213] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2555machine # [ 54.960362] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672556machine # [ 54.962326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2557machine # [ 54.963749] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2558machine # [ 54.964881] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2559machine # [ 54.968862] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2560machine # [ 54.970083] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2561machine # [ 55.011157] systemd[1]: linger-users.service: Deactivated successfully.2562machine # [ 55.012678] systemd[1]: Finished linger-users.service.2563machine # [ 55.115223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2564machine # [ 55.116905] systemd[1]: Finished Create SUID/SGID Wrappers.2565machine # [ 55.374917] nixos[4818]: finished switching to system configuration /nix/store/9j4qxxb9bs0qjns9ia9hhz886wwyrh7d-nixos-system-machine-test2566machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.41 seconds)2567machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2568machine # [ 55.423701] sudo[4959]: 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.service2569machine # [ 55.437743] sudo[4959]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2570machine # [ 55.452408] sudo[4959]: pam_unix(sudo:session): session closed for user usertest2571machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2572machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2573machine # [ 55.472480] sudo[4965]: 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-owner2574machine # [ 55.479684] sudo[4965]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2575machine # [ 55.485349] sudo[4965]: pam_unix(sudo:session): session closed for user usertest2576machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2577machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2578machine # [ 55.504569] sudo[4971]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2579machine # [ 55.511563] sudo[4971]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2580machine # [ 55.517221] sudo[4971]: pam_unix(sudo:session): session closed for user usertest2581machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.03 seconds)2582machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2583machine # [ 55.536438] sudo[4977]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2584machine # [ 55.543650] sudo[4977]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2585machine # [ 55.549225] systemd[623]: Reload requested from client PID 4982 ('systemctl')...2586machine # [ 55.550573] systemd[623]: Reloading...2587machine # [ 55.623138] systemd[623]: Reloading finished in 71 ms.2588machine # [ 55.628864] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2589machine # [ 55.636252] sudo[4977]: pam_unix(sudo:session): session closed for user usertest2590machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2591machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2592machine # [ 55.657165] sudo[4987]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2593machine # [ 55.664394] sudo[4987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2594machine # [ 55.672344] systemd[623]: Stopped migrated.service.2595machine # [ 55.674980] sudo[4987]: pam_unix(sudo:session): session closed for user usertest2596machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.04 seconds)2597machine: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&12598machine # [ 55.856545] nixos[4996]: switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2599machine # [ 55.858804] systemd[1]: Stopped target Local File Systems.2600machine # [ 55.860609] systemd[1]: Stopped target Remote File Systems.2601machine # [ 56.058748] systemd[1]: Reload requested from client PID 4996 ('.switch-to-conf') (unit backdoor.service)...2602machine # [ 56.060384] systemd[1]: Reloading...2603machine # [ 56.289957] systemd[1]: Reloading finished in 228 ms.2604machine # [ 56.350561] systemd[623]: Reexecution requested from client PID 5065 ('switch-to-confi')...2605machine # [ 56.353047] systemd[623]: Reexecuting.2606machine # [ 56.373057] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2607machine # [ 56.445572] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2608machine # [ 56.450564] systemd[623]: Reload requested from client PID 5065 ('switch-to-confi')...2609machine # [ 56.452385] systemd[623]: Reloading...2610machine # [ 56.523050] systemd[623]: Reloading finished in 69 ms.2611machine # [ 56.528829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2612machine # [ 56.534844] systemd[623]: Stopped Run user-specific NixOS activation.2613machine # [ 56.535868] systemd[623]: Stopping Run user-specific NixOS activation...2614machine # [ 56.538315] systemd[623]: Starting Run user-specific NixOS activation...2615machine # [ 56.556878] systemd[623]: Finished Run user-specific NixOS activation.2616machine # [ 56.559846] systemd[1]: Stopped target Reactivate sysinit units.2617machine # [ 56.561215] systemd[1]: Stopping Reactivate sysinit units...2618machine # [ 56.562790] systemd[1]: Reached target Reactivate sysinit units.2619machine # [ 56.564979] systemd[1]: Reached target Remote File Systems.2620machine # [ 56.567698] systemd[1]: Starting linger-users.service...2621machine # [ 56.575348] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2622machine # [ 56.576717] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2623machine # [ 56.583307] systemd[1]: Reached target Local File Systems.2624machine # [ 56.585437] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2625machine # [ 56.588384] systemd[1]: Starting Create SUID/SGID Wrappers...2626machine # [ 56.589406] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2627machine # [ 56.590509] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672628machine # [ 56.592384] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2629machine # [ 56.596151] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2630machine # [ 56.597282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2631machine # [ 56.628818] systemd[1]: linger-users.service: Deactivated successfully.2632machine # [ 56.631218] systemd[1]: Finished linger-users.service.2633machine # [ 56.738212] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2634machine # [ 56.739818] systemd[1]: Finished Create SUID/SGID Wrappers.2635machine # [ 56.996799] nixos[4996]: finished switching to system configuration /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test2636machine: (finished: must succeed: /nix/store/2kqs5f85wwkwmb1kmz48db5zg3gl098a-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds)2637(finished: subtest: user services, in 36.88 seconds)2638(finished: run the VM test script, in 57.90 seconds)2639test script finished in 57.99s2640cleanup2641kill QemuMachine (pid 45)2642machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2643machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down2644machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down2645machine # [2026-09-15T03:08:32Z INFO virtiofsd] Client disconnected, shutting down2646(finished: cleanup, in 0.16 seconds)2647additionally exposed symbols:2648 machine, other,2649 vlan1,2650 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