vm-test-run-switch-test-user
checks.x86_64-linux.switchTest-user
· build #61
· 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.ZEc5uRS2r7', 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: c40c0b43-e5c6-4e39-9f94-9d4e148ce33a17machine # 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-10-19T03:00:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-19T03:00:14Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-19T03:00:14Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-19T03:00:14Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-19T03:00:14Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-19T03:00:14Z 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.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @179172517254machine # [ 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/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/init regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-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 466685489 cycles75machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76machine # [ 0.000005] tsc: Detected 2400.008 MHz processor77machine # [ 0.000766] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078machine # [ 0.000794] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79machine # [ 0.000796] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80machine # [ 0.002684] found SMP MP-table at [mem 0x000f5450-0x000f545f]81machine # [ 0.002697] Using GB pages for direct mapping82machine # [ 0.002798] RAMDISK: [mem 0x3e2b5000-0x3ffcffff]83machine # [ 0.002806] ACPI: Early table checksum verification disabled84machine # [ 0.002809] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85machine # [ 0.002813] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86machine # [ 0.002817] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002824] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002827] ACPI: FACS 0x000000003FFE0000 00004089machine # [ 0.002828] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90machine # [ 0.002830] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002831] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002833] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002834] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94machine # [ 0.002835] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95machine # [ 0.002836] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96machine # [ 0.002836] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97machine # [ 0.002837] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98machine # [ 0.002837] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99machine # [ 0.002838] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100machine # [ 0.003068] No NUMA configuration found101machine # [ 0.003069] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102machine # [ 0.003072] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103machine # [ 0.005460] Zone ranges:104machine # [ 0.005461] DMA [mem 0x0000000000001000-0x0000000000ffffff]105machine # [ 0.005463] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106machine # [ 0.005464] Normal empty107machine # [ 0.005465] Device empty108machine # [ 0.005466] Movable zone start for each node109machine # [ 0.005467] Early memory node ranges110machine # [ 0.005467] node 0: [mem 0x0000000000001000-0x000000000009efff]111machine # [ 0.005468] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112machine # [ 0.005469] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113machine # [ 0.005492] On node 0, zone DMA: 1 pages in unavailable ranges114machine # [ 0.005765] On node 0, zone DMA: 97 pages in unavailable ranges115machine # [ 0.024494] On node 0, zone DMA32: 40 pages in unavailable ranges116machine # [ 0.024944] ACPI: PM-Timer IO Port: 0x608117machine # [ 0.024954] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118machine # [ 0.024983] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119machine # [ 0.024985] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120machine # [ 0.024987] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121machine # [ 0.024988] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122machine # [ 0.024989] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123machine # [ 0.024990] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124machine # [ 0.024992] ACPI: Using ACPI (MADT) for SMP configuration information125machine # [ 0.024993] ACPI: HPET id: 0x8086a201 base: 0xfed00000126machine # [ 0.024997] TSC deadline timer available127machine # [ 0.025001] CPU topo: Max. logical packages: 1128machine # [ 0.025002] CPU topo: Max. logical dies: 1129machine # [ 0.025002] CPU topo: Max. dies per package: 1130machine # [ 0.025005] CPU topo: Max. threads per core: 1131machine # [ 0.025006] CPU topo: Num. cores per package: 1132machine # [ 0.025006] CPU topo: Num. threads per package: 1133machine # [ 0.025007] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134machine # [ 0.025023] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135machine # [ 0.025050] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136machine # [ 0.025052] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137machine # [ 0.025053] [mem 0x40000000-0xafffffff] available for PCI devices138machine # [ 0.025055] Booting paravirtualized kernel on KVM139machine # [ 0.025058] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140machine # [ 0.029732] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141machine # [ 0.032020] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142machine # [ 0.032072] kvm-guest: PV spinlocks disabled, single CPU143machine # [ 0.032074] 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/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/init regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-closure-info/registration console=ttyS0,115200n8 console=tty0144machine # [ 0.032169] Unknown kernel command line parameters "regInfo=/nix/.ro-store/kyssj46vp4das5clhh4kkvijqqkjyah0-closure-info/registration", will be passed to user space.145machine # [ 0.032181] random: crng init done146machine # [ 0.032182] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147machine # [ 0.032204] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148machine # [ 0.032764] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149machine # [ 0.032793] Fallback order for Node 0: 0150machine # [ 0.032796] Built 1 zonelists, mobility grouping on. Total pages: 262006151machine # [ 0.032797] Policy zone: DMA32152machine # [ 0.035068] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153machine # [ 0.037751] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154machine # [ 0.040505] allocated 2097152 bytes of page_ext155machine # [ 0.050825] ftrace: allocating 48842 entries in 192 pages156machine # [ 0.050827] ftrace: allocated 192 pages with 2 groups157machine # [ 0.051720] Dynamic Preempt: lazy158machine # [ 0.051897] rcu: Preemptible hierarchical RCU implementation.159machine # [ 0.051897] rcu: RCU event tracing is enabled.160machine # [ 0.051898] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.161machine # [ 0.051900] Trampoline variant of Tasks RCU enabled.162machine # [ 0.051901] Rude variant of Tasks RCU enabled.163machine # [ 0.051901] Tracing variant of Tasks RCU enabled.164machine # [ 0.051902] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165machine # [ 0.051902] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166machine # [ 0.051925] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.051927] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.051928] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.056327] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16170machine # [ 0.056596] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.056603] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172machine # [ 0.056707] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173machine # [ 0.060277] Console: colour VGA+ 80x25174machine # [ 0.060279] printk: legacy console [tty0] enabled175machine # [ 0.089731] printk: legacy console [ttyS0] enabled176machine # [ 0.196269] ACPI: Core revision 20250807177machine # [ 0.197209] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178machine # [ 0.198813] APIC: Switch to symmetric I/O mode setup179machine # [ 0.199847] x2apic enabled180machine # [ 0.200613] APIC: Switched APIC routing to: physical x2apic181machine # [ 0.202554] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182machine # [ 0.203631] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns183machine # [ 0.205405] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008)184machine # [ 0.206492] x86/cpu: User Mode Instruction Prevention (UMIP) activated185machine # [ 0.207524] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186machine # [ 0.208403] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187machine # [ 0.209407] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188machine # [ 0.210402] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189machine # [ 0.211403] Transient Scheduler Attacks: Vulnerable: No microcode190machine # [ 0.212402] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191machine # [ 0.213402] Speculative Return Stack Overflow: Mitigation: Safe RET192machine # [ 0.214402] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193machine # [ 0.216410] Spectre V2 : Enabling IBPB for BPF194machine # [ 0.217403] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195machine # [ 0.219403] active return thunk: srso_alias_return_thunk196machine # [ 0.220329] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197machine # [ 0.221402] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198machine # [ 0.222402] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199machine # [ 0.223402] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200machine # [ 0.224402] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201machine # [ 0.225402] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202machine # [ 0.226402] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203machine # [ 0.227402] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.229219] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.230224] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.231402] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.232402] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.233402] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209machine # [ 0.267967] Freeing SMP alternatives memory: 48K210machine # [ 0.268406] pid_max: default: 32768 minimum: 301211machine # [ 0.269534] LSM: initializing lsm=capability,landlock,yama,bpf,ima212machine # [ 0.270504] landlock: Up and running.213machine # [ 0.271403] Yama: becoming mindful.214machine # [ 0.272251] LSM support for eBPF active215machine # [ 0.273174] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216machine # [ 0.274433] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.276846] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218machine # [ 0.277989] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219machine # [ 0.278411] ... version: 2220machine # [ 0.279165] ... bit width: 48221machine # [ 0.279405] ... generic counters: 6222machine # [ 0.280132] ... generic bitmap: 000000000000003f223machine # [ 0.280405] ... fixed-purpose counters: 0224machine # [ 0.281128] ... fixed-purpose bitmap: 0000000000000000225machine # [ 0.281405] ... value mask: 0000ffffffffffff226machine # [ 0.282358] ... max period: 00007fffffffffff227machine # [ 0.283152] ... global_ctrl mask: 000000000000003f228machine # [ 0.283504] signal: max sigframe size: 3376229machine # [ 0.284347] rcu: Hierarchical SRCU implementation.230machine # [ 0.285042] rcu: Max phase no-delay instances is 400.231machine # [ 0.290082] smp: Bringing up secondary CPUs ...232machine # [ 0.290420] smp: Brought up 1 node, 1 CPU233machine # [ 0.291145] smpboot: Total of 1 processors activated (4800.01 BogoMIPS)234machine # [ 0.291599] Memory: 940292K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100324K reserved, 0K cma-reserved)235machine # [ 0.292633] devtmpfs: initialized236machine # [ 0.293458] x86/mm: Memory block size: 128MB237machine # [ 0.295189] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238machine # [ 0.296412] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239machine # [ 0.297506] pinctrl core: initialized pinctrl subsystem240machine # [ 0.298634] PM: RTC time: 03:00:14, date: 2026-10-19241machine # [ 0.302480] NET: Registered PF_NETLINK/PF_ROUTE protocol family242machine # [ 0.303779] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243machine # [ 0.304423] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244machine # [ 0.305558] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245machine # [ 0.306415] audit: initializing netlink subsys (disabled)246machine # [ 0.307646] thermal_sys: Registered thermal governor 'fair_share'247machine # [ 0.307649] thermal_sys: Registered thermal governor 'bang_bang'248machine # [ 0.308407] thermal_sys: Registered thermal governor 'step_wise'249machine # [ 0.309408] audit: type=2000 audit(1792378815.231:1): state=initialized audit_enabled=0 res=1250machine # [ 0.311407] thermal_sys: Registered thermal governor 'user_space'251machine # [ 0.311408] thermal_sys: Registered thermal governor 'power_allocator'252machine # [ 0.312420] cpuidle: using governor menu253machine # [ 0.314687] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254machine # [ 0.315752] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255machine # [ 0.316408] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256machine # [ 0.317417] PCI: Using configuration type 1 for base access257machine # [ 0.318621] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258machine # [ 0.323491] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259machine # [ 0.324406] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260machine # [ 0.329405] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261machine # [ 0.330404] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262machine # [ 0.340775] ACPI: Added _OSI(Module Device)263machine # [ 0.341406] ACPI: Added _OSI(Processor Device)264machine # [ 0.342183] ACPI: Added _OSI(Processor Aggregator Device)265machine # [ 0.350590] ACPI: 1 ACPI AML tables successfully acquired and loaded266machine # [ 0.354819] ACPI: Interpreter enabled267machine # [ 0.355421] ACPI: PM: (supports S0 S3 S4 S5)268machine # [ 0.356157] ACPI: Using IOAPIC for interrupt routing269machine # [ 0.358488] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270machine # [ 0.359406] PCI: Using E820 reservations for host bridge windows271machine # [ 0.362547] ACPI: Enabled 2 GPEs in block 00 to 3F272machine # [ 0.368843] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273machine # [ 0.369411] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274machine # [ 0.370482] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275machine # [ 0.371529] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276machine # [ 0.372759] PCI host bridge to bus 0000:00277machine # [ 0.373409] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278machine # [ 0.374406] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279machine # [ 0.375405] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280machine # [ 0.376405] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281machine # [ 0.377405] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282machine # [ 0.378405] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283machine # [ 0.379405] pci_bus 0000:00: root bus resource [bus 00-ff]284machine # [ 0.380434] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285machine # [ 0.381844] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286machine # [ 0.384537] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287machine # [ 0.385418] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288machine # [ 0.386473] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289machine # [ 0.387875] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290machine # [ 0.389144] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291machine # [ 0.391423] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292machine # [ 0.392306] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293machine # [ 0.393187] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294machine # [ 0.394279] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295machine # [ 0.396196] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296machine # [ 0.398415] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297machine # [ 0.399329] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298machine # [ 0.400188] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299machine # [ 0.401857] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.404412] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.405411] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302machine # [ 0.406427] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303machine # [ 0.407981] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.409440] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305machine # [ 0.410362] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306machine # [ 0.411231] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307machine # [ 0.412315] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.413775] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.415418] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310machine # [ 0.416426] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311machine # [ 0.418031] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312machine # [ 0.420263] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313machine # [ 0.421219] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314machine # [ 0.422980] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315machine # [ 0.424445] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316machine # [ 0.425453] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317machine # [ 0.426947] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318machine # [ 0.428418] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319machine # [ 0.429404] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320machine # [ 0.430943] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321machine # [ 0.433274] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322machine # [ 0.434076] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323machine # [ 0.434428] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324machine # [ 0.435977] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325machine # [ 0.437438] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326machine # [ 0.438341] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327machine # [ 0.439251] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328machine # [ 0.440892] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329machine # [ 0.442115] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330machine # [ 0.442611] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331machine # [ 0.444098] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332machine # [ 0.444639] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333machine # [ 0.446071] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334machine # [ 0.446723] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335machine # [ 0.449078] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336machine # [ 0.450421] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337machine # [ 0.451703] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338machine # [ 0.452669] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339machine # [ 0.454422] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340machine # [ 0.455397] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341machine # [ 0.456566] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342machine # [ 0.458076] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343machine # [ 0.461470] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344machine # [ 0.462510] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345machine # [ 0.463505] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346machine # [ 0.464500] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347machine # [ 0.465688] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348machine # [ 0.466513] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349machine # [ 0.467504] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350machine # [ 0.468505] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351machine # [ 0.469445] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352machine # [ 0.470418] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353machine # [ 0.471421] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354machine # [ 0.472420] ACPI: PCI: Interrupt link GSID configured for IRQ 19355machine # [ 0.473422] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356machine # [ 0.474416] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357machine # [ 0.475416] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358machine # [ 0.476417] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359machine # [ 0.478469] iommu: Default domain type: Translated360machine # [ 0.479349] iommu: DMA domain TLB invalidation policy: lazy mode361machine # [ 0.480487] ACPI: bus type USB registered362machine # [ 0.481278] usbcore: registered new interface driver usbfs363machine # [ 0.482141] usbcore: registered new interface driver hub364machine # [ 0.482412] usbcore: registered new device driver usb365machine # [ 0.484163] NetLabel: Initializing366machine # [ 0.484412] NetLabel: domain hash size = 128367machine # [ 0.485264] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368machine # [ 0.486233] NetLabel: unlabeled traffic allowed by default369machine # [ 0.487179] PCI: Using ACPI for IRQ routing370machine # [ 0.531704] pci 0000:00:01.0: vgaarb: setting as boot VGA device371machine # [ 0.532401] pci 0000:00:01.0: vgaarb: bridge control possible372machine # [ 0.532401] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373machine # [ 0.532408] vgaarb: loaded374machine # [ 0.533234] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375machine # [ 0.534020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376machine # [ 0.538494] clocksource: Switched to clocksource kvm-clock377machine # [ 0.542244] VFS: Disk quotas dquot_6.6.0378machine # [ 0.542953] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379machine # [ 0.544358] pnp: PnP ACPI init380machine # [ 0.545210] ACPI: IRQ 4 override to edge(!), high(!)381machine # [ 0.546210] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382machine # [ 0.547831] pnp: PnP ACPI: found 5 devices383machine # [ 0.555477] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384machine # [ 0.556982] clocksource: Switched to clocksource acpi_pm385machine # [ 0.557998] NET: Registered PF_INET protocol family386machine # [ 0.559080] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387machine # [ 0.576429] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388machine # [ 0.578038] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389machine # [ 0.579430] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390machine # [ 0.580833] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391machine # [ 0.582169] TCP: Hash tables configured (established 8192 bind 8192)392machine # [ 0.583390] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393machine # [ 0.584744] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394machine # [ 0.585937] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.587198] NET: Registered PF_UNIX/PF_LOCAL protocol family396machine # [ 0.588227] NET: Registered PF_XDP protocol family397machine # [ 0.589113] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398machine # [ 0.590187] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399machine # [ 0.591261] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400machine # [ 0.592445] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401machine # [ 0.593614] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402machine # [ 0.594881] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403machine # [ 0.596760] ACPI: \_SB_.GSIA: Enabled at IRQ 16404machine # [ 0.599487] ACPI: \_SB_.GSIB: Enabled at IRQ 17405machine # [ 0.601856] ACPI: \_SB_.GSIC: Enabled at IRQ 18406machine # [ 0.603916] ACPI: \_SB_.GSID: Enabled at IRQ 19407machine # [ 0.605717] PCI: CLS 0 bytes, default 64408machine # [ 0.606779] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns409machine # [ 0.608603] Trying to unpack rootfs image as initramfs...410machine # [ 0.654257] Initialise system trusted keyrings411machine # [ 0.655238] workingset: timestamp_bits=40 max_order=18 bucket_order=0412machine # [ 0.674624] Key type asymmetric registered413machine # [ 0.679836] Asymmetric key parser 'x509' registered414machine # [ 0.680719] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415machine # [ 0.683935] io scheduler mq-deadline registered416machine # [ 0.684747] io scheduler kyber registered417machine # [ 0.688378] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418machine # [ 0.689736] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419machine # [ 0.694977] Linux agpgart interface v0.103420machine # [ 0.695775] ACPI: bus type drm_connector registered421machine # [ 0.699077] usbcore: registered new interface driver usbserial_generic422machine # [ 0.700222] usbserial: USB Serial support registered for generic423machine # [ 0.702833] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424machine # [ 0.702833] Please enable it if your BIOS has the CPPC option.425machine # [ 0.705165] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426machine # [ 0.709951] drop_monitor: Initializing network drop monitor service427machine # [ 0.711193] NET: Registered PF_INET6 protocol family428machine # [ 0.714296] Segment Routing with IPv6429machine # [ 0.716848] In-situ OAM (IOAM) with IPv6430machine # [ 0.717949] IPI shorthand broadcast: enabled431machine # [ 0.726288] sched_clock: Marking stable (582015167, 143798592)->(802241122, -76427363)432machine # [ 0.732074] registered taskstats version 1433machine # [ 0.733134] Loading compiled-in X.509 certificates434machine # [ 0.751821] Demotion targets for Node 0: null435machine # [ 0.752730] Key type .fscrypt registered436machine # [ 0.755818] Key type fscrypt-provisioning registered437machine # [ 0.756841] ima: No TPM chip found, activating TPM-bypass!438machine # [ 0.757782] ima: Allocated hash algorithm: sha1439machine # [ 0.759854] ima: No architecture policies found440machine # [ 0.762826] PM: Magic number: 14:728:7441machine # [ 0.764490] RAS: Correctable Errors collector initialized.442machine # [ 0.773587] clk: Disabling unused clocks443machine # [ 0.776833] PM: genpd: Disabling unused power domains444machine # [ 0.950078] Freeing initrd memory: 29804K445machine # [ 0.953503] Freeing unused decrypted memory: 2028K446machine # [ 0.956359] Freeing unused kernel image (initmem) memory: 3660K447machine # [ 0.957476] Write protecting the kernel read-only data: 32768k448machine # [ 0.958924] Freeing unused kernel image (text/rodata gap) memory: 296K449machine # [ 0.960481] Freeing unused kernel image (rodata/data gap) memory: 684K450machine # [ 1.011421] x86/mm: Checked W+X mappings: passed, no W+X pages found.451machine # [ 1.012598] Run /init as init process452machine # [ 1.023717] systemd[1]: Inserted module 'autofs4'453machine # [ 1.044255] fuse: init (API version 7.45)454machine # [ 1.051562] ACPI: \_SB_.GSIG: Enabled at IRQ 22455machine # [ 1.054153] ACPI: \_SB_.GSIH: Enabled at IRQ 23456machine # [ 1.057229] ACPI: \_SB_.GSIE: Enabled at IRQ 20457machine # [ 1.059234] ACPI: \_SB_.GSIF: Enabled at IRQ 21458machine # [ 1.064728] virtiofs virtio5: discovered new tag: nix-store459machine # [ 1.066295] virtiofs virtio5: virtio_fs_setup_dax: No cache capability460machine # [ 1.073150] virtiofs virtio6: discovered new tag: shared461machine # [ 1.074884] virtiofs virtio6: virtio_fs_setup_dax: No cache capability462machine # [ 1.078089] virtiofs virtio7: discovered new tag: xchg463machine # [ 1.079570] virtiofs virtio7: virtio_fs_setup_dax: No cache capability464machine # [ 1.098512] systemd[1]: Successfully made /usr/ read-only.465machine # [ 1.100294] systemd[1]: systemd 263-7f4df15 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 +EXPERIMENTAL-ENVVAR)466machine # [ 1.105708] systemd[1]: Detected virtualization kvm.467machine # [ 1.106627] systemd[1]: Detected architecture x86-64.468machine # [ 1.107559] systemd[1]: Running in initrd.469machine # [ 1.108537] systemd[1]: Initializing machine ID from random generator.470machine # [ 1.109746] systemd[1]: Hostname set to <machine>.471machine # [ 1.180340] systemd[1]: bpf-restrict-fs: LSM BPF program attached472machine # [ 1.216237] systemd[1]: Queued start job for default target Initrd Default Target.473machine # [ 1.220058] systemd[1]: Created slice Slice /system/modprobe.474machine # [ 1.221257] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.475machine # [ 1.222703] systemd[1]: Expecting device /dev/disk/by-label/nixos...476machine # [ 1.223861] systemd[1]: Reached target Path Units.477machine # [ 1.224763] systemd[1]: Reached target Slice Units.478machine # [ 1.225692] systemd[1]: Reached target Swaps.479machine # [ 1.226531] systemd[1]: Reached target Timer Units.480machine # [ 1.227545] systemd[1]: Listening on D-Bus System Message Bus Socket.481machine # [ 1.228876] systemd[1]: Listening on Journal Socket (/dev/log).482machine # [ 1.230088] systemd[1]: Listening on Journal Sockets.483machine # [ 1.231094] systemd[1]: Listening on udev Kernel Socket.484machine # [ 1.232120] systemd[1]: Reached target Socket Units.485machine # [ 1.233906] systemd[1]: Starting Create List of Static Device Nodes...486machine # [ 1.237892] systemd[1]: Starting Load Kernel Module configfs...487machine # [ 1.246884] systemd[1]: Starting Journal Service...488machine # [ 1.256935] systemd[1]: Starting Load Kernel Modules...489machine # [ 1.272942] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os490machine # [ 1.277520] systemd[1]: Starting Coldplug All udev Devices...491machine # [ 1.291180] systemd[1]: Finished Create List of Static Device Nodes.492machine # [ 1.294484] systemd[1]: modprobe@configfs.service: Deactivated successfully.493machine # [ 1.303277] systemd[1]: Finished Load Kernel Module configfs.494machine # [ 1.309282] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config495machine # [ 1.314446] systemd-journald[65]: Collecting audit messages is disabled.496machine # [ 1.321091] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...497machine # [ 1.329926] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.498machine # [ 1.340972] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev499machine # [ 1.362913] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.500machine # [ 1.372018] systemd[1]: Starting Create Static Device Nodes in /dev...501machine # [ 1.383345] systemd[1]: Finished Load Kernel Modules.502machine # [ 1.392018] systemd[1]: Starting Apply Kernel Variables...503machine # [ 1.410921] systemd[1]: Finished Create Static Device Nodes in /dev.504machine # [ 1.417014] systemd[1]: Reached target Preparation for Local File Systems.505machine # [ 1.421913] systemd[1]: Reached target Local File Systems.506machine # [ 1.429345] systemd[1]: Starting Rule-based Manager for Device Events and Files...507machine # [ 1.288142] systemd-modules-load[66]: Inserted module 'dm_mod'508machine # [ 1.289458] systemd-modules-load[66]: Inserted module 'virtio_balloon'509machine # [ 1.291272] systemd-modules-load[66]: Inserted module 'virtio_gpu'510machine # [ 1.441105] systemd[1]: Started Journal Service.511machine # [ 1.304205] systemd[1]: Finished Apply Kernel Variables.512machine # [ 1.315467] systemd[1]: Starting Create System Files and Directories...513machine # [ 1.342396] systemd[1]: Finished Create System Files and Directories.514machine # [ 1.351430] systemd-udevd[73]: Using default interface naming scheme 'v261'.515machine # [ 1.375069] systemd[1]: Started Rule-based Manager for Device Events and Files.516machine # [ 1.431098] systemd[1]: Finished Coldplug All udev Devices.517machine # [ 1.432259] systemd[1]: Reached target System Initialization.518machine # [ 1.433205] systemd[1]: Reached target Basic System.519machine # [ 1.720878] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12520machine # [ 1.729580] serio: i8042 KBD port at 0x60,0x64 irq 1521machine # [ 1.737909] virtio_blk virtio2: 1/0/0 default/read/poll queues522machine # [ 1.740822] serio: i8042 AUX port at 0x60,0x64 irq 12523machine # [ 1.743145] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)524machine # [ 1.765050] ehci-pci 0000:00:1d.7: EHCI Host Controller525machine # [ 1.765955] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1526machine # [ 1.767162] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000527machine # [ 1.774126] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00528machine # [ 1.776160] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18529machine # [ 1.777301] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 1.780818] usb usb1: Product: EHCI Host Controller531machine # [ 1.781518] usb usb1: Manufacturer: Linux 6.18.56 ehci_hcd532machine # [ 1.783591] usb usb1: SerialNumber: 0000:00:1d.7533machine # [ 1.784998] hub 1-0:1.0: USB hub found534machine # [ 1.786292] hub 1-0:1.0: 6 ports detected535machine # [ 1.790836] uhci_hcd 0000:00:1d.0: UHCI Host Controller536machine # [ 1.791599] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2537machine # [ 1.802170] uhci_hcd 0000:00:1d.0: detected 2 ports538machine # [ 1.809772] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180539machine # [ 1.816055] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18540machine # [ 1.817175] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1541machine # [ 1.832510] SCSI subsystem initialized542machine # [ 1.690502] systemd[1]: Starting Virtual Console Setup...543machine # [ 1.835866] usb usb2: Product: UHCI Host Controller544machine # [ 1.836577] usb usb2: Manufacturer: Linux 6.18.56 uhci_hcd545machine # [ 1.702717] (udev-worker)[85]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.546machine # [ 1.706830] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.547machine # [ 1.852834] usb usb2: SerialNumber: 0000:00:1d.0548machine # [ 1.860331] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0549machine # [ 1.867898] hub 2-0:1.0: USB hub found550machine # [ 1.870407] hub 2-0:1.0: 2 ports detected551machine # [ 1.733294] systemd-vconsole-setup[97]: Configuration of first virtual console was skipped, ignoring remaining ones.552machine # [ 1.737579] systemd[1]: Finished Virtual Console Setup.553machine # [ 1.896396] uhci_hcd 0000:00:1d.1: UHCI Host Controller554machine # [ 1.897184] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3555machine # [ 1.755312] systemd[1]: Found device /dev/disk/by-label/nixos.556machine # [ 1.757214] systemd[1]: Reached target Initrd Root Device.557machine # [ 1.759464] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...558machine # [ 1.910017] uhci_hcd 0000:00:1d.1: detected 2 ports559machine # [ 1.911200] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0560machine # [ 1.915333] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18561machine # [ 1.916532] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1562machine # [ 1.926413] usb usb3: Product: UHCI Host Controller563machine # [ 1.927422] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd564machine # [ 1.928349] usb usb3: SerialNumber: 0000:00:1d.1565machine # [ 1.930902] hub 3-0:1.0: USB hub found566machine # [ 1.932138] hub 3-0:1.0: 2 ports detected567machine # [ 1.937274] uhci_hcd 0000:00:1d.2: UHCI Host Controller568machine # [ 1.939944] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4569machine # [ 1.943897] uhci_hcd 0000:00:1d.2: detected 2 ports570machine # [ 1.803316] systemd-fsck[103]: nixos: clean, 12/65536 files, 13019/262144 blocks571machine # [ 1.948182] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0572machine # [ 1.950150] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18573machine # [ 1.809805] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.574machine # [ 1.960007] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1575machine # [ 1.961191] usb usb4: Product: UHCI Host Controller576machine # [ 1.962887] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd577machine # [ 1.963643] usb usb4: SerialNumber: 0000:00:1d.2578machine # [ 1.966176] hub 4-0:1.0: USB hub found579machine # [ 1.968870] hub 4-0:1.0: 2 ports detected580machine # [ 1.984530] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode581machine # [ 1.995624] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)582machine # [ 1.997025] ahci 0000:00:1f.2: flags: 64bit ncq only583machine # [ 2.003991] scsi host0: ahci584machine # [ 2.005465] scsi host1: ahci585machine # [ 2.006646] scsi host2: ahci586machine # [ 2.008226] scsi host3: ahci587machine # [ 2.009549] scsi host4: ahci588machine # [ 2.010656] scsi host5: ahci589machine # [ 2.011994] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1590machine # [ 2.013284] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1591machine # [ 2.014432] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1592machine # [ 2.015601] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1593machine # [ 2.016749] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1594machine # [ 2.017907] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1595machine # [ 2.025856] usb 1-1: new high-speed USB device number 2 using ehci-pci596machine # [ 2.155092] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00597machine # [ 2.157915] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10598machine # [ 2.161080] usb 1-1: Product: QEMU USB Tablet599machine # [ 2.162853] usb 1-1: Manufacturer: QEMU600machine # [ 2.164438] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1601machine # [ 2.196206] hid: raw HID events driver (C) Jiri Kosina602machine # [ 2.146720] systemd[1]: Mounting /sysroot...603machine # [ 2.327971] ata6: SATA link down (SStatus 0 SControl 300)604machine # [ 2.330106] ata5: SATA link down (SStatus 0 SControl 300)605machine # [ 2.331785] ata1: SATA link down (SStatus 0 SControl 300)606machine # [ 2.333509] ata4: SATA link down (SStatus 0 SControl 300)607machine # [ 2.335212] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)608machine # [ 2.336948] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100609machine # [ 2.342597] ata3.00: applying bridge limits610machine # [ 2.344244] ata3.00: configured for UDMA/100611machine # [ 2.345863] ata2: SATA link down (SStatus 0 SControl 300)612machine # [ 2.348032] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5613machine # [ 2.387826] usbcore: registered new interface driver usbhid614machine # [ 2.388602] usbhid: USB HID core driver615machine # [ 2.414373] 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/input2616machine # [ 2.422877] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0617machine # [ 2.434886] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray618machine # [ 2.457028] cdrom: Uniform CD-ROM driver Revision: 3.20619machine # [ 2.469355] EXT4-fs (vda): mounted filesystem c40c0b43-e5c6-4e39-9f94-9d4e148ce33a r/w with ordered data mode. Quota mode: none.620machine # [ 2.329575] systemd[1]: Mounted /sysroot.621machine # [ 2.332273] systemd[1]: Reached target Initrd Root File System.622machine # [ 2.335153] systemd[1]: Starting Mountpoints Configured in the Real Root...623machine # [ 2.346136] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload.624machine # [ 2.351041] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)...625machine # [ 2.352557] systemd[1]: Reloading...626machine # [ 2.421717] systemd[1]: Reloading finished in 71 ms.627machine # [ 2.430080] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace...628machine # [ 2.434170] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace...629machine # [ 2.437351] systemd[1]: initrd-parse-etc.service: Deactivated successfully.630machine # [ 2.438724] systemd[1]: Finished Mountpoints Configured in the Real Root.631machine # [ 2.440252] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.632machine # [ 3.146748] systemd[1]: Mounting /sysroot/nix/.ro-store...633machine # [ 3.149736] systemd[1]: Mounting /sysroot/nix/.rw-store...634machine # [ 3.154757] systemd[1]: Mounting /sysroot/run...635machine # [ 3.156720] systemd[1]: Mounting /sysroot/tmp/shared...636machine # [ 3.162150] systemd[1]: Mounting /sysroot/tmp/xchg...637machine # [ 3.185915] systemd[1]: Mounted /sysroot/nix/.rw-store.638machine # [ 3.188314] systemd[1]: Mounted /sysroot/run.639machine # [ 3.191902] systemd[1]: Mounted /sysroot/nix/.ro-store.640machine # [ 3.195807] systemd[1]: Mounted /sysroot/tmp/shared.641machine # [ 3.196758] systemd[1]: Mounted /sysroot/tmp/xchg.642machine # [ 3.200419] systemd[1]: Starting rw-sysroot-nix-store.service...643machine # [ 3.210356] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.644machine # [ 3.211910] systemd[1]: Finished rw-sysroot-nix-store.service.645machine # [ 3.213890] systemd[1]: Mounting /sysroot/nix/store...646machine # [ 3.235379] systemd[1]: Mounted /sysroot/nix/store.647machine # [ 3.236472] systemd[1]: Reached target Initrd File Systems.648machine # [ 3.238090] systemd[1]: Starting Find NixOS closure...649machine # [ 3.240601] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...650machine # [ 3.259199] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.651machine # [ 3.266056] systemd[1]: Finished Find NixOS closure.652machine # [ 3.267384] systemd[1]: Reached target Initrd Default Target.653machine # [ 3.269124] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...654machine # [ 3.283250] systemd[1]: Stopped target Initrd Default Target.655machine # [ 3.284144] systemd[1]: Stopped target Basic System.656machine # [ 3.284881] systemd[1]: Stopped target Initrd Root Device.657machine # [ 3.285682] systemd[1]: Stopped target Path Units.658machine # [ 3.286352] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.659machine # [ 3.287389] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.660machine # [ 3.288753] systemd[1]: Stopped target Slice Units.661machine # [ 3.290237] systemd[1]: Stopped target Socket Units.662machine # [ 3.291032] systemd[1]: Stopped target System Initialization.663machine # [ 3.291806] systemd[1]: Stopped target Swaps.664machine # [ 3.292677] systemd[1]: Stopped target Timer Units.665machine # [ 3.293591] systemd[1]: dbus.socket: Deactivated successfully.666machine # [ 3.295159] systemd[1]: Closed D-Bus System Message Bus Socket.667machine # [ 3.296026] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.668machine # [ 3.297097] systemd[1]: Stopped Find NixOS closure.669machine # [ 3.298191] systemd[1]: Starting rw-sysroot-nix-store.service...670machine # [ 3.299263] systemd[1]: systemd-sysctl.service: Deactivated successfully.671machine # [ 3.301244] systemd[1]: Stopped Apply Kernel Variables.672machine # [ 3.302251] systemd[1]: systemd-modules-load.service: Deactivated successfully.673machine # [ 3.303495] systemd[1]: Stopped Load Kernel Modules.674machine # [ 3.305039] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.675machine # [ 3.306077] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.676machine # [ 3.307224] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.677machine # [ 3.308766] systemd[1]: Stopped Create System Files and Directories.678machine # [ 3.309619] systemd[1]: Stopped target Local File Systems.679machine # [ 3.310704] systemd[1]: Stopped target Preparation for Local File Systems.680machine # [ 3.312166] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.681machine # [ 3.313537] systemd[1]: Stopped Coldplug All udev Devices.682machine # [ 3.315809] systemd[1]: Stopping Rule-based Manager for Device Events and Files...683machine # [ 3.316969] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.684machine # [ 3.318109] systemd[1]: Stopped Virtual Console Setup.685machine # [ 3.325311] systemd[1]: systemd-udevd.service: Deactivated successfully.686machine # [ 3.330292] systemd[1]: Stopped Rule-based Manager for Device Events and Files.687machine # [ 3.331340] systemd[1]: initrd-cleanup.service: Deactivated successfully.688machine # [ 3.333171] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.689machine # [ 3.338814] systemd[1]: Starting Cleanup udev Database...690machine # [ 3.339682] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.691machine # [ 3.340795] systemd[1]: Stopped Create Static Device Nodes in /dev.692machine # [ 3.342967] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.693machine # [ 3.344122] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.694machine # [ 3.345188] systemd[1]: kmod-static-nodes.service: Deactivated successfully.695machine # [ 3.346209] systemd[1]: Stopped Create List of Static Device Nodes.696machine # [ 3.347121] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.697machine # [ 3.349121] systemd[1]: Finished rw-sysroot-nix-store.service.698machine # [ 3.363506] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.699machine # [ 3.366067] systemd[1]: Finished Cleanup udev Database.700machine # [ 3.366823] systemd[1]: Reached target Switch Root.701machine # [ 3.368329] systemd[1]: Starting NixOS Activation...702machine # [ 3.437796] initrd-nixos-activation-start[189]: booting system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test703machine # [ 3.465467] initrd-nixos-activation-start[189]: running activation script...704machine # [ 3.643198] initrd-nixos-activation-start[212]: setting up /etc...705machine # [ 3.741495] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.706machine # [ 3.743162] systemd[1]: Finished NixOS Activation.707machine # [ 3.744660] systemd[1]: Starting Switch Root...708machine # [ 3.758203] systemd[1]: Switching root.709machine # [ 3.922302] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).710machine # [ 3.981368] NET: Registered PF_VSOCK protocol family711machine # [ 4.005283] systemd[1]: systemd 263-7f4df15 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 +EXPERIMENTAL-ENVVAR)712machine # [ 4.009410] systemd[1]: Detected virtualization kvm.713machine # [ 4.010093] systemd[1]: Detected architecture x86-64.714machine # [ 4.010845] systemd[1]: Detected first boot.715machine # [ 4.012480] systemd[1]: Initializing machine ID from random generator.716machine # [ 4.102687] systemd[1]: bpf-restrict-fs: LSM BPF program attached717machine # [ 4.213289] systemd[1]: Applying preset policy.718machine # [ 4.383091] systemd[1]: Populated /etc with preset unit settings.719machine # [ 4.557328] systemd[1]: initrd-switch-root.service: Deactivated successfully.720machine # [ 4.558663] systemd[1]: Stopped initrd-switch-root.service.721machine # [ 4.561171] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.722machine # [ 4.563288] systemd[1]: Created slice Slice /system/getty.723machine # [ 4.564628] systemd[1]: Created slice User and Session Slice.724machine # [ 4.565575] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.725machine # [ 4.566851] systemd[1]: Started Forward Password Requests to Wall Directory Watch.726machine # [ 4.567947] systemd[1]: Expecting device /dev/hvc0...727machine # [ 4.568692] systemd[1]: Expecting device /dev/ttyS0...728machine # [ 4.569472] systemd[1]: Reached target Local Encrypted Volumes.729machine # [ 4.570307] systemd[1]: Stopped target initrd-fs.target.730machine # [ 4.571042] systemd[1]: Stopped target initrd-root-fs.target.731machine # [ 4.571870] systemd[1]: Stopped target initrd-switch-root.target.732machine # [ 4.572722] systemd[1]: Reached target Virtual Machines and Containers.733machine # [ 4.573640] systemd[1]: Reached target Path Units.734machine # [ 4.574329] systemd[1]: Reached target Remote File Systems.735machine # [ 4.575136] systemd[1]: Reached target Slice Units.736machine # [ 4.575874] systemd[1]: Reached target Swaps.737machine # [ 4.577506] systemd[1]: Listening on Query the User Interactively for a Password.738machine # [ 4.591532] systemd[1]: Listening on Process Core Dump Socket.739machine # [ 4.593168] systemd[1]: Listening on Credential Encryption/Decryption.740machine # [ 4.594828] systemd[1]: Listening on Factory Reset Management.741machine # [ 4.595724] systemd[1]: Listening on Hostname Service Socket.742machine # [ 4.598564] systemd[1]: Starting Journal Log Access Socket...743machine # [ 4.600049] systemd[1]: Listening on Journal Audit Socket.744machine # [ 4.602717] systemd[1]: Listening on Console Output Muting Service Socket.745machine # [ 4.603867] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.746machine # [ 4.605014] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os747machine # [ 4.606298] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki748machine # [ 4.611991] systemd[1]: Listening on Disk Repartitioning Service Socket.749machine # [ 4.613163] systemd[1]: Listening on udev Varlink Socket.750machine # [ 4.615637] systemd[1]: Mounting Huge Pages File System...751machine # [ 4.618150] systemd[1]: Mounting POSIX Message Queue File System...752machine # [ 4.625984] systemd[1]: Mounting Kernel Debug File System...753machine # [ 4.633754] systemd[1]: Mounting Kernel Trace File System...754machine # [ 4.643930] systemd[1]: Starting Create List of Static Device Nodes...755machine # [ 4.653843] systemd[1]: Starting Load Kernel Module configfs...756machine # [ 4.658464] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm757machine # [ 4.670183] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore758machine # [ 4.684246] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse759machine # [ 4.694861] systemd[1]: Mounting FUSE Control File System...760machine # [ 4.695760] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67761machine # [ 4.713348] systemd[1]: Starting Journal Service...762machine # [ 4.718656] systemd[1]: Starting Load Kernel Modules...763machine # [ 4.731343] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...764machine # [ 4.743450] systemd[1]: Starting Remount Root and Kernel File Systems...765machine # [ 4.747941] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os766machine # [ 4.758076] systemd[1]: Starting Coldplug All udev Devices...767machine # [ 4.766729] systemd[1]: Listening on Journal Log Access Socket.768machine # [ 4.769424] systemd-journald[283]: Collecting audit messages is enabled.769machine # [ 4.774913] systemd[1]: Mounted Huge Pages File System.770machine # [ 4.782012] systemd[1]: Mounted POSIX Message Queue File System.771machine # [ 4.786173] systemd[1]: Mounted Kernel Debug File System.772machine # [ 4.790075] systemd[1]: Mounted Kernel Trace File System.773machine # [ 4.647740] systemd[1]: Queued start job for default target Multi-User System.774machine # [ 4.650295] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.775machine # [ 4.795576] systemd[1]: Started Journal Service.776machine # [ 4.652775] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0.777machine # [ 4.654362] systemd[1]: systemd-journald.service: Deactivated successfully.778machine # [ 4.663748] systemd[1]: Finished Create List of Static Device Nodes.779machine # [ 4.666484] systemd[1]: modprobe@configfs.service: Deactivated successfully.780machine # [ 4.672343] systemd[1]: Finished Load Kernel Module configfs.781machine # [ 4.818355] loop: module loaded782machine # [ 4.677692] systemd-modules-load[284]: Inserted module 'loop'783machine # [ 4.832960] EXT4-fs (vda): re-mounted c40c0b43-e5c6-4e39-9f94-9d4e148ce33a.784machine # [ 4.691588] systemd[1]: Mounted FUSE Control File System.785machine # [ 4.694100] systemd[1]: Finished Load Kernel Modules.786machine # [ 4.700628] systemd[1]: Mounting Kernel Configuration File System...787machine # [ 4.706130] systemd[1]: Starting Firewall...788machine # [ 4.712261] systemd[1]: Starting Apply Kernel Variables...789machine # [ 4.720580] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...790machine # [ 4.725517] systemd[1]: Finished Remount Root and Kernel File Systems.791machine # [ 4.731087] systemd[1]: Listening on Disk Image Download Service Socket.792machine # [ 4.738260] systemd-oomd[286]: No swap; memory pressure usage will be degraded793machine # [ 4.749062] systemd[1]: Starting Flush Journal to Persistent Storage...794machine # [ 4.749952] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore795machine # [ 4.763075] systemd[1]: Starting Load/Save OS Random Seed...796machine # [ 4.763981] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os797machine # [ 4.779809] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.798machine # [ 4.979108] systemd-journald[283]: Received client request to flush runtime journal.799machine # [ 4.914720] systemd[1]: Mounted Kernel Configuration File System.800machine # [ 4.916364] systemd[1]: Finished Load/Save OS Random Seed.801machine # [ 4.921268] systemd[1]: Reached target First Boot Complete.802machine # [ 4.922165] systemd[1]: Finished Apply Kernel Variables.803machine # [ 4.925394] systemd[1]: Finished Flush Journal to Persistent Storage.804machine # [ 4.942102] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.805machine # [ 4.944804] systemd[1]: Starting Create Static Device Nodes in /dev...806machine # [ 5.024525] systemd[1]: Finished Coldplug All udev Devices.807machine # [ 5.043416] systemd[1]: Finished Create Static Device Nodes in /dev.808machine # [ 5.044576] systemd[1]: Reached target Preparation for Local File Systems.809machine # [ 5.047302] systemd[1]: Starting Rule-based Manager for Device Events and Files...810machine # [ 5.092432] systemd-udevd[323]: Using default interface naming scheme 'v261'.811machine # [ 5.135498] systemd[1]: Started Rule-based Manager for Device Events and Files.812machine # [ 5.199881] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse813machine # [ 5.267385] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.814machine # [ 5.286514] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.815machine # [ 5.325354] (udev-worker)[349]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.816machine # [ 5.327751] (udev-worker)[349]: Network interface NamePolicy= disabled on kernel command line.817machine # [ 5.417792] systemd[1]: Mounting /run/wrappers...818machine # [ 5.422082] systemd[1]: Condition check resulted in Virtio network device being skipped.819machine # [ 5.423126] systemd[1]: Condition check resulted in Virtio network device being skipped.820machine # [ 5.424164] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore821machine # [ 5.425457] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67822machine # [ 5.427438] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore823machine # [ 5.428986] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os824machine # [ 5.432333] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os825machine # [ 5.471950] systemd[1]: Mounted /run/wrappers.826machine # [ 5.473474] systemd[1]: Reached target Local File Systems.827machine # [ 5.477341] systemd[1]: Listening on Boot Loader Control Service Socket.828machine # [ 5.481378] systemd[1]: Starting Create SUID/SGID Wrappers...829machine # [ 5.482250] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.830machine # [ 5.487077] systemd[1]: Starting Save Transient machine-id to Disk...831machine # [ 5.492635] systemd[1]: Starting Create System Files and Directories...832machine # [ 5.581265] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.833machine # [ 5.586795] systemd[1]: Finished Save Transient machine-id to Disk.834machine # [ 5.620428] systemd[1]: Finished Create System Files and Directories.835machine # [ 5.627209] systemd[1]: Starting Rebuild Journal Catalog...836machine # [ 5.634599] systemd[1]: Starting Record System Boot/Shutdown in UTMP...837machine # [ 5.795863] mousedev: PS/2 mouse device common for all mice838machine # [ 5.801737] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console839machine # [ 5.724447] systemd[1]: Finished Record System Boot/Shutdown in UTMP.840machine # [ 5.734763] systemd[1]: Finished Rebuild Journal Catalog.841machine # [ 5.741862] systemd[1]: Starting Update is Completed...842machine # [ 5.787645] systemd[1]: Finished Update is Completed.843machine # [ 5.809077] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3844machine # [ 5.821024] ACPI: button: Power Button [PWRF]845machine # [ 5.985053] rtc_cmos PNP0B00:00: RTC can wake from S4846machine # [ 6.006143] rtc_cmos PNP0B00:00: registered as rtc0847machine # [ 5.972390] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.848machine # [ 5.974734] systemd[1]: Finished Create SUID/SGID Wrappers.849machine # [ 5.976094] systemd[1]: Reached target System Initialization.850machine # [ 5.978263] systemd[1]: Started Discard unused filesystem blocks once a week.851machine # [ 5.979239] systemd[1]: Started Daily Cleanup of Temporary Directories.852machine # [ 5.981168] systemd[1]: Reached target Timer Units.853machine # [ 5.981911] systemd[1]: Listening on D-Bus System Message Bus Socket.854machine # [ 5.983983] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.855machine # [ 5.986163] systemd[1]: Reached target Socket Units.856machine # [ 5.987073] systemd[1]: Reached target Basic System.857machine # [ 5.990483] systemd[1]: Started backdoor.service.858machine # [ 5.994073] systemd[1]: Starting Import lastlog data into lastlog2 database...859machine # [ 6.000166] systemd[1]: Starting Name Service Cache Daemon (nsncd)...860machine # [ 6.006212] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:00:20 UTC (1792378820)861machine # [ 6.006323] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs862machine # [ 6.007070] systemd-journald[283]: Time jumped backwards, rotating.863machine # [ 6.011210] systemd[1]: Starting Post-Boot Actions...864machine # [ 6.023478] systemd[1]: Started Reset console on configuration changes.865machine # [ 6.042143] systemd[1]: Starting resolvconf update...866machine # [ 6.053896] systemd[1]: Finished Firewall.867machine # [ 6.020251] parport_pc 00:02: reported by Plug and Play ACPI868machine # [ 6.020336] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]869machine # [ 6.032675] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized870machine # [ 6.081603] systemd[1]: Starting D-Bus System Message Bus...871machine # connecting to host...872machine # [ 6.129338] nsncd[493]: Oct 19 03:00:20.766 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"873machine # [ 6.137110] systemd[1]: Started Name Service Cache Daemon (nsncd).874machine # [ 6.149058] systemd[1]: Reached target Host and Network Name Lookups.875machine # [ 6.153177] systemd[1]: Reached target User and Group Name Lookups.876machine # [ 6.166199] systemd[1]: Starting User Login Management...877machine # [ 6.170619] systemd[1]: Finished Post-Boot Actions.878machine # [ 6.068122] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4879machine # [ 6.084478] Console: switching to colour dummy device 80x25880machine: Guest shell says: b'Spawning backdoor root shell...\n'881machine: connected to guest root shell882machine: (connecting took 6.87 seconds)883machine: (finished: waiting for the VM to finish booting, in 7.22 seconds)884machine # [ 6.195802] systemd[1]: Finished Import lastlog data into lastlog2 database.885machine # [ 6.254107] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt886machine # [ 6.246526] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...887machine # [ 6.254228] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD888machine # [ 6.395749] [drm] Found bochs VGA, ID 0xb0c5.889machine # [ 6.395751] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.890machine # [ 6.254355] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'891machine # [ 6.255378] dbus-broker-launch[502]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"892machine # [ 6.437887] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic893machine # [ 6.438577] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0894machine # [ 6.449558] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6895machine # [ 6.457442] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5896machine # [ 6.344243] dbus-broker-launch[502]: Ready897machine # [ 6.356934] systemd-logind[521]: New seat seat0.898machine # [ 6.377078] systemd-logind[521]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)899machine # [ 6.383166] systemd-logind[521]: Watching system buttons on /dev/input/event2 (Power Button)900machine # [ 6.414629] systemd[1]: Started D-Bus System Message Bus.901machine # [ 6.418069] systemd[1]: Started User Login Management.902machine # [ 6.549439] Console: switching to colour frame buffer device 160x50903machine # [ 6.448085] systemd[1]: Stopped target Host and Network Name Lookups.904machine # [ 6.450176] systemd[1]: Stopping Host and Network Name Lookups...905machine # [ 6.451083] systemd[1]: Stopped target User and Group Name Lookups.906machine # [ 6.451904] systemd[1]: Stopping User and Group Name Lookups...907machine # [ 6.459135] systemd[1]: Starting linger-users.service...908machine # [ 6.463416] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...909machine # [ 6.608349] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device910machine # [ 6.619205] ppdev: user-space parallel port driver911machine # [ 6.476869] systemd[1]: Starting Virtual Console Setup...912machine # [ 6.481292] systemd[1]: nscd.service: Deactivated successfully.913machine # [ 6.499176] systemd[1]: Stopped Name Service Cache Daemon (nsncd).914machine # [ 6.549819] systemd[1]: Starting Name Service Cache Daemon (nsncd)...915machine # [ 6.719677] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)916machine # [ 6.578150] systemd[1]: Finished resolvconf update.917machine # [ 6.601605] systemd[1]: Created slice Slice /user/1001.918machine # [ 6.602387] systemd[1]: Reached target Preparation for Network.919machine # [ 6.603193] systemd-logind[521]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)920machine # [ 6.609592] systemd[1]: Starting DHCP Client...921machine # [ 6.761400] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)922machine # [ 6.618442] systemd[1]: Starting Address configuration of eth1...923machine # [ 6.638824] systemd[1]: Starting Extra networking commands....924machine # [ 6.639861] systemd[1]: Starting User Runtime Directory /run/user/1001...925machine # [ 6.660365] systemd[1]: linger-users.service: Deactivated successfully.926machine # [ 6.663121] systemd[1]: Finished linger-users.service.927machine # [ 6.691351] systemd[1]: Started Name Service Cache Daemon (nsncd).928machine # [ 6.692520] nsncd[594]: Oct 19 03:00:21.325 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"929machine # [ 6.699204] systemd[1]: Reached target Host and Network Name Lookups.930machine # [ 6.703351] systemd[1]: Reached target User and Group Name Lookups.931machine # [ 6.704304] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.932machine # [ 6.706411] systemd[1]: Stopped Virtual Console Setup.933machine # [ 6.708234] systemd[1]: Starting Virtual Console Setup...934machine # [ 6.735096] systemd[1]: Finished User Runtime Directory /run/user/1001.935machine # [ 6.737439] systemd[1]: Starting User Manager for UID 1001...936machine # [ 6.827110] (systemd)[621]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)937machine # [ 6.840733] systemd-logind[521]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.938machine # [ 6.887316] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy939machine # [ 6.989692] network-addresses-eth1-start[600]: adding address 192.168.1.1/24... done940machine # [ 6.991210] network-addresses-eth1-start[600]: adding address 2001:db8:1::1/64... done941machine # [ 6.995422] systemd[1]: Finished Address configuration of eth1.942machine # [ 7.189976] kvm_amd: TSC scaling supported943machine # [ 7.190429] kvm_amd: Nested Virtualization enabled944machine # [ 7.193767] kvm_amd: Nested Paging enabled945machine # [ 7.194531] kvm_amd: LBR virtualization supported946machine # [ 7.195022] kvm_amd: Virtual VMLOAD VMSAVE supported947machine # [ 7.195497] kvm_amd: Virtual GIF supported948machine # [ 7.199371] kvm_amd: Virtual NMI enabled949machine # [ 7.078852] systemd[621]: Queued start job for default target Main User Target.950machine # [ 7.086700] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy951machine # [ 7.088322] systemd[621]: Created slice User Application Slice.952machine # [ 7.089719] systemd[621]: Started Daily Cleanup of User's Temporary Directories.953machine # [ 7.090753] systemd[621]: Reached target Paths.954machine # [ 7.095249] systemd[621]: Reached target Timers.955machine # [ 7.095927] systemd[621]: Starting D-Bus User Message Bus Socket...956machine # [ 7.100193] systemd[621]: Starting Create User Files and Directories...957machine # [ 7.278394] EDAC MC: Ver: 3.0.0958machine # [ 7.142252] systemd[621]: Finished Create User Files and Directories.959machine # [ 7.153961] dhcpcd[629]: dhcpcd-10.3.2 starting960machine # [ 7.165505] dhcpcd[693]: dev: loaded udev961machine # [ 7.171217] systemd[621]: Listening on D-Bus User Message Bus Socket.962machine # [ 7.173524] systemd[621]: Reached target Sockets.963machine # [ 7.174851] systemd[621]: Reached target Basic System.964machine # [ 7.177424] systemd[1]: Started User Manager for UID 1001.965machine # [ 7.178432] systemd[621]: Starting Run user-specific NixOS activation...966machine # [ 7.180251] systemd[1]: Finished Extra networking commands..967machine # [ 7.182279] systemd[1]: Reached target Network.968machine # [ 7.186183] systemd[1]: Starting Permit User Sessions...969machine # [ 7.340557] 8021q: 802.1Q VLAN Support v1.8970machine # [ 7.343197] 8021q: adding VLAN 0 to HW filter on device eth1971machine # [ 7.227178] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.972machine # [ 7.246621] systemd[621]: Finished Run user-specific NixOS activation.973machine # [ 7.247733] systemd[621]: Reached target Main User Target.974machine # [ 7.249150] systemd[621]: Startup finished in 379ms.975machine # [ 7.262381] systemd[1]: Finished Permit User Sessions.976machine # [ 7.265700] systemd[1]: Started Getty on tty1.977machine # [ 7.267216] systemd[1]: Reached target Login Prompts.978machine # [ 7.448524] cfg80211: Loading compiled-in X.509 certificates for regulatory database979machine # [ 7.460097] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'980machine # [ 7.461000] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'981machine # [ 7.462862] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2982machine # [ 7.463733] cfg80211: failed to load regulatory.db983machine # [ 7.491321] 8021q: adding VLAN 0 to HW filter on device eth0984machine # [ 7.348620] dhcpcd[693]: eth0: waiting for carrier985machine # [ 7.349723] dhcpcd[693]: eth0: carrier acquired986machine # [ 7.355914] dhcpcd[693]: DUID 00:01:00:01:32:68:44:45:52:54:00:12:34:56987machine # [ 7.356896] dhcpcd[693]: eth0: IAID 00:12:34:56988machine # [ 7.357669] dhcpcd[693]: eth0: adding address fe80::5054:ff:fe12:3456989machine # [ 7.370085] systemd-vconsole-setup[617]: Configuration of first virtual console was skipped, ignoring remaining ones.990machine # [ 7.374276] systemd[1]: Finished Virtual Console Setup.991machine # [ 8.235942] dhcpcd[693]: eth0: soliciting a DHCP lease992machine # [ 8.397133] NET: Registered PF_PACKET protocol family993machine # [ 8.259156] dhcpcd[693]: eth0: offered 10.0.2.15 from 10.0.2.2994machine # [ 8.263151] dhcpcd[693]: eth0: probing address 10.0.2.15/24995machine # [ 9.257195] dhcpcd[693]: eth0: soliciting an IPv6 router996machine # [ 9.259364] dhcpcd[693]: eth0: Router Advertisement from fe80::2997machine # [ 9.261401] dhcpcd[693]: eth0: adding address fec0::5054:ff:fe12:3456/64998machine # [ 9.263636] dhcpcd[693]: eth0: adding route to fec0::/64999machine # [ 9.265470] dhcpcd[693]: eth0: adding default route via fe80::21000machine # [ 13.442803] dhcpcd[693]: eth0: leased 10.0.2.15 for 86400 seconds1001machine # [ 13.446373] dhcpcd[693]: eth0: adding route to 10.0.2.0/241002machine # [ 13.449241] dhcpcd[693]: eth0: adding default route via 10.0.2.21003machine # [ 13.538704] systemd[1]: Started DHCP Client.1004machine # [ 13.540428] systemd[1]: Reached target Multi-User System.1005machine # [ 13.541420] systemd[1]: Startup finished in 872ms (kernel) + 2.944s (initrd) + 9.723s (userspace) = 13.540s.1006machine: (finished: waiting for unit multi-user.target, in 15.03 seconds)1007machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test1008machine # Checking switch inhibitors... done1009machine # [ 14.220447] nixos[840]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1010machine # [ 14.222639] systemd[1]: Stopped target Local File Systems.1011machine # [ 14.224216] systemd[1]: Stopped target Remote File Systems.1012machine # activating the configuration...1013machine # setting up /etc...1014machine # [ 14.444344] systemd[1]: Reload requested from client PID 840 ('.switch-to-conf') (unit backdoor.service)...1015machine # [ 14.446402] systemd[1]: Reloading...1016machine # [ 14.666375] systemd[1]: Reloading finished in 217 ms.1017machine # reloading user units for usertest...1018machine # [ 14.695947] systemd[621]: Created slice User Core Session Slice.1019machine # [ 14.698360] systemd[621]: Starting D-Bus User Message Bus...1020machine # [ 14.719897] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.import1.service'1021machine # [ 14.722612] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1022machine # [ 14.725341] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1023machine # [ 14.728090] dbus-broker-launch[910]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1024machine # [ 14.730737] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1025machine # [ 14.732321] dbus-broker-launch[910]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1026machine # [ 14.734315] systemd[621]: Started D-Bus User Message Bus.1027machine # [ 14.738191] dbus-broker-launch[910]: Ready1028machine # [ 14.775576] systemd[621]: Reexecution requested from client PID 909 ('switch-to-confi')...1029machine # [ 14.777961] systemd[621]: Reexecuting.1030machine # [ 14.797958] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1031machine # [ 14.870507] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1032machine # [ 14.875601] systemd[621]: Reload requested from client PID 909 ('switch-to-confi')...1033machine # [ 14.876960] systemd[621]: Reloading...1034machine # [ 14.948911] systemd[621]: Reloading finished in 70 ms.1035machine # [ 14.953818] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1036machine # restarting the following user units: nixos-activation.service1037machine # [ 14.961066] systemd[621]: Stopped Run user-specific NixOS activation.1038machine # [ 14.962045] systemd[621]: Stopping Run user-specific NixOS activation...1039machine # [ 14.964050] systemd[621]: Starting Run user-specific NixOS activation...1040machine # [ 14.985157] systemd[621]: Finished Run user-specific NixOS activation.1041machine # restarting sysinit-reactivation.target1042machine # [ 14.993280] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1043machine # [ 15.059174] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1044machine # [ 15.061298] systemd[1]: Reached target Reactivate sysinit units.1045machine # [ 15.064540] systemd[1]: Reached target Remote File Systems.1046machine # [ 15.067528] systemd[1]: Starting linger-users.service...1047machine # [ 15.072725] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 15.074035] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1049machine # [ 15.078094] systemd[1]: Reached target Local File Systems.1050machine # [ 15.085324] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1051machine # [ 15.089938] systemd[1]: Starting Create SUID/SGID Wrappers...1052machine # [ 15.090844] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1053machine # [ 15.092066] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671054machine # [ 15.094174] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1055machine # [ 15.095676] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1056machine # [ 15.096853] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1057machine # [ 15.131581] systemd[1]: linger-users.service: Deactivated successfully.1058machine # [ 15.133383] systemd[1]: Finished linger-users.service.1059machine # [ 15.240202] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1060machine # [ 15.241787] systemd[1]: Finished Create SUID/SGID Wrappers.1061machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1062machine # [ 15.502344] nixos[840]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1063machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test, in 1.51 seconds)1064machine: must succeed: rm /etc/NIXOS1065machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)1066machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test1067machine # this should succeed (config: /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test, action: test)1068machine # Checking switch inhibitors... done1069machine # [ 15.754728] nixos[987]: switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test1070machine # [ 15.757025] systemd[1]: Stopped target Remote File Systems.1071machine # activating the configuration...1072machine # [ 15.759131] systemd[1]: Stopped target Local File Systems.1073machine # removing user ‘usertest’1074machine # setting up /etc...1075machine # [ 15.976496] systemd[1]: Reload requested from client PID 987 ('.switch-to-conf') (unit backdoor.service)...1076machine # [ 15.978181] systemd[1]: Reloading...1077machine # [ 16.218151] systemd[1]: Reloading finished in 238 ms.1078machine # reloading user units for usertest...1079machine # [ 16.284983] systemd[621]: Reexecution requested from client PID 1058 ('switch-to-confi')...1080machine # [ 16.287723] systemd[621]: Reexecuting.1081machine # [ 16.309053] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1082machine # [ 16.383876] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1083machine # [ 16.389032] systemd[621]: Reload requested from client PID 1058 ('switch-to-confi')...1084machine # [ 16.390251] systemd[621]: Reloading...1085machine # [ 16.462192] systemd[621]: Reloading finished in 70 ms.1086machine # [ 16.467970] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1087machine # restarting the following user units: nixos-activation.service1088machine # [ 16.476394] systemd[621]: Stopped Run user-specific NixOS activation.1089machine # [ 16.477315] systemd[621]: Stopping Run user-specific NixOS activation...1090machine # [ 16.479939] systemd[621]: Starting Run user-specific NixOS activation...1091machine # [ 16.502345] systemd[621]: Finished Run user-specific NixOS activation.1092machine # restarting sysinit-reactivation.target1093machine # [ 16.506523] systemd[1]: Stopped target Reactivate sysinit units.1094machine # [ 16.507618] systemd[1]: Stopping Reactivate sysinit units...1095machine # [ 16.508588] systemd[1]: Reached target Reactivate sysinit units.1096machine # reloading the following units: dbus-broker.service1097machine # [ 16.511848] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.1098machine # [ 16.513437] systemd[1]: Reloading D-Bus System Message Bus...1099machine # [ 16.519044] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...1100machine # [ 16.520784] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'1101machine # [ 16.521848] dbus-broker-launch[502]: Invalid user-name in /nix/store/2h842v43dq0mx3dmyabal4yr4l01qpk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1102machine # [ 16.530363] systemd[1]: Reloaded D-Bus System Message Bus.1103machine # restarting the following units: network-addresses-eth1.service1104machine # [ 16.535139] systemd[1]: Stopping Address configuration of eth1...1105machine # [ 16.554207] network-addresses-eth1-pre-stop[1070]: deleting address 192.168.1.1/24... done1106machine # [ 16.559332] network-addresses-eth1-pre-stop[1070]: deleting address 2001:db8:1::1/64... done1107machine # [ 16.564381] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1108machine # [ 16.565892] systemd[1]: Stopped Address configuration of eth1.1109machine # [ 16.569340] systemd[1]: Starting Address configuration of eth1...1110machine # [ 16.597690] network-addresses-eth1-start[1075]: adding address 192.168.1.2/24... done1111machine # [ 16.603059] network-addresses-eth1-start[1075]: adding address 2001:db8:1::2/64... done1112machine # [ 16.610168] systemd[1]: Finished Address configuration of eth1.1113machine # [ 16.612545] systemd[1]: Reached target Local File Systems.1114machine # [ 16.614898] systemd[1]: Reached target Remote File Systems.1115machine # [ 16.617674] systemd[1]: Starting linger-users.service...1116machine # [ 16.619474] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1117machine # [ 16.621198] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1118machine # [ 16.634484] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1119machine # [ 16.637899] systemd[1]: Starting Create SUID/SGID Wrappers...1120machine # [ 16.639942] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1121machine # [ 16.641900] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671122machine # [ 16.644687] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1123machine # [ 16.646186] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 16.648170] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1125machine # [ 16.666882] linger-users-start[1089]: id: ‘usertest’: no such user1126machine # [ 16.668391] linger-users-start[1086]: Removing linger for missing user usertest1127machine # [ 16.674906] systemd[1]: linger-users.service: Deactivated successfully.1128machine # [ 16.677378] systemd[1]: Finished linger-users.service.1129machine # [ 16.813146] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1130machine # [ 16.814736] systemd[1]: Finished Create SUID/SGID Wrappers.1131machine # [ 17.069247] nixos[987]: finished switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test1132machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test, in 1.53 seconds)1133machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&11134machine # [ 17.236071] nixos[1154]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1135machine # [ 17.238319] systemd[1]: Stopped target Local File Systems.1136machine # [ 17.239807] systemd[1]: Stopped target Remote File Systems.1137machine # [ 17.452199] systemd[1]: Reload requested from client PID 1154 ('.switch-to-conf') (unit backdoor.service)...1138machine # [ 17.453810] systemd[1]: Reloading...1139machine # [ 17.684037] systemd[1]: Reloading finished in 228 ms.1140machine # [ 17.743973] systemd[621]: Reexecution requested from client PID 1223 ('switch-to-confi')...1141machine # [ 17.746400] systemd[621]: Reexecuting.1142machine # [ 17.766979] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1143machine # [ 17.840369] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1144machine # [ 17.845300] systemd[621]: Reload requested from client PID 1223 ('switch-to-confi')...1145machine # [ 17.847588] systemd[621]: Reloading...1146machine # [ 17.917627] systemd[621]: Reloading finished in 70 ms.1147machine # [ 17.923874] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1148machine # [ 17.930151] systemd[621]: Stopped Run user-specific NixOS activation.1149machine # [ 17.931037] systemd[621]: Stopping Run user-specific NixOS activation...1150machine # [ 17.933146] systemd[621]: Starting Run user-specific NixOS activation...1151machine # [ 17.952058] systemd[621]: Finished Run user-specific NixOS activation.1152machine # [ 17.955531] systemd[1]: Stopped target Reactivate sysinit units.1153machine # [ 17.956571] systemd[1]: Stopping Reactivate sysinit units...1154machine # [ 17.957826] systemd[1]: Reached target Reactivate sysinit units.1155machine # [ 17.961134] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.1156machine # [ 17.962279] systemd[1]: Reloading D-Bus System Message Bus...1157machine # [ 17.964916] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...1158machine # [ 17.966628] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'1159machine # [ 17.967646] dbus-broker-launch[502]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1160machine # [ 17.975150] systemd[1]: Reloaded D-Bus System Message Bus.1161machine # [ 17.979067] systemd[1]: Stopping Address configuration of eth1...1162machine # [ 17.996721] network-addresses-eth1-pre-stop[1235]: deleting address 192.168.1.2/24... done1163machine # [ 18.001640] network-addresses-eth1-pre-stop[1235]: deleting address 2001:db8:1::2/64... done1164machine # [ 18.006379] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1165machine # [ 18.008153] systemd[1]: Stopped Address configuration of eth1.1166machine # [ 18.012439] systemd[1]: Starting Address configuration of eth1...1167machine # [ 18.039269] network-addresses-eth1-start[1240]: adding address 192.168.1.1/24... done1168machine # [ 18.044685] network-addresses-eth1-start[1240]: adding address 2001:db8:1::1/64... done1169machine # [ 18.051651] systemd[1]: Finished Address configuration of eth1.1170machine # [ 18.056625] systemd[1]: Reached target Remote File Systems.1171machine # [ 18.060642] systemd[1]: Starting linger-users.service...1172machine # [ 18.062368] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1173machine # [ 18.064101] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1174machine # [ 18.070200] systemd[1]: Reached target Local File Systems.1175machine # [ 18.071181] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1176machine # [ 18.076324] systemd[1]: Starting Create SUID/SGID Wrappers...1177machine # [ 18.077164] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1178machine # [ 18.078315] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671179machine # [ 18.080297] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1180machine # [ 18.081705] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 18.082877] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1182machine # [ 18.113668] systemd[1]: linger-users.service: Deactivated successfully.1183machine # [ 18.115122] systemd[1]: Finished linger-users.service.1184machine # [ 18.232191] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1185machine # [ 18.234102] systemd[1]: Finished Create SUID/SGID Wrappers.1186machine # [ 18.491752] nixos[1154]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1187machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1188subtest: user services1189machine: waiting for unit user@1001.service1190machine: (finished: waiting for unit user@1001.service, in 0.07 seconds)1191machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11192machine # [ 18.750294] nixos[1313]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1193machine # [ 18.753888] systemd[1]: Stopped target Local File Systems.1194machine # [ 18.754930] systemd[1]: Stopped target Remote File Systems.1195machine # [ 18.983327] systemd[1]: Reload requested from client PID 1313 ('.switch-to-conf') (unit backdoor.service)...1196machine # [ 18.985167] systemd[1]: Reloading...1197machine # [ 19.221476] systemd[1]: Reloading finished in 235 ms.1198machine # [ 19.288323] systemd[621]: Reexecution requested from client PID 1382 ('switch-to-confi')...1199machine # [ 19.291068] systemd[621]: Reexecuting.1200machine # [ 19.313221] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 19.395488] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1202machine # [ 19.400813] systemd[621]: Reload requested from client PID 1382 ('switch-to-confi')...1203machine # [ 19.402751] systemd[621]: Reloading...1204machine # [ 19.477274] systemd[621]: Reloading finished in 73 ms.1205machine # [ 19.483881] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 19.490975] systemd[621]: Starting usertest.service...1207machine # [ 19.507173] systemd[621]: Finished usertest.service.1208machine # [ 19.509431] systemd[621]: Stopped Run user-specific NixOS activation.1209machine # [ 19.511131] systemd[621]: Stopping Run user-specific NixOS activation...1210machine # [ 19.512756] systemd[621]: Starting Run user-specific NixOS activation...1211machine # [ 19.532155] systemd[621]: Finished Run user-specific NixOS activation.1212machine # [ 19.535470] systemd[1]: Stopped target Reactivate sysinit units.1213machine # [ 19.536818] systemd[1]: Stopping Reactivate sysinit units...1214machine # [ 19.538124] systemd[1]: Reached target Reactivate sysinit units.1215machine # [ 19.541594] systemd[1]: Reached target Remote File Systems.1216machine # [ 19.544520] systemd[1]: Starting linger-users.service...1217machine # [ 19.546976] systemd[1]: Reached target Local File Systems.1218machine # [ 19.550077] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1219machine # [ 19.551689] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1220machine # [ 19.560555] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1221machine # [ 19.565233] systemd[1]: Starting Create SUID/SGID Wrappers...1222machine # [ 19.566898] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1223machine # [ 19.568087] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671224machine # [ 19.570118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1225machine # [ 19.571613] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1226machine # [ 19.572856] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1227machine # [ 19.608884] systemd[1]: linger-users.service: Deactivated successfully.1228machine # [ 19.610817] systemd[1]: Finished linger-users.service.1229machine # [ 19.721157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1230machine # [ 19.723073] systemd[1]: Finished Create SUID/SGID Wrappers.1231machine # [ 19.981800] nixos[1313]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1232machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1233machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1234machine # [ 20.036584] sudo[1452]: 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.service1235machine # [ 20.049952] sudo[1452]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1236machine # [ 20.058046] sudo[1452]: pam_unix(sudo:session): session closed for user usertest1237machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1238machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11239machine # [ 20.267787] nixos[1461]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1240machine # [ 20.270456] systemd[1]: Stopped target Remote File Systems.1241machine # [ 20.272477] systemd[1]: Stopped target Local File Systems.1242machine # [ 20.483650] systemd[1]: Reload requested from client PID 1461 ('.switch-to-conf') (unit backdoor.service)...1243machine # [ 20.485363] systemd[1]: Reloading...1244machine # [ 20.727225] systemd[1]: Reloading finished in 239 ms.1245machine # [ 20.792751] systemd[621]: Reexecution requested from client PID 1531 ('switch-to-confi')...1246machine # [ 20.795513] systemd[621]: Reexecuting.1247machine # [ 20.817366] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1248machine # [ 20.892984] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 20.898532] systemd[621]: Reload requested from client PID 1531 ('switch-to-confi')...1250machine # [ 20.899984] systemd[621]: Reloading...1251machine # [ 20.975755] systemd[621]: Reloading finished in 74 ms.1252machine # [ 20.981972] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 20.990072] systemd[621]: Stopped Run user-specific NixOS activation.1254machine # [ 20.991040] systemd[621]: Stopping Run user-specific NixOS activation...1255machine # [ 20.993257] systemd[621]: Starting Run user-specific NixOS activation...1256machine # [ 21.014790] systemd[621]: Finished Run user-specific NixOS activation.1257machine # [ 21.018380] systemd[1]: Stopped target Reactivate sysinit units.1258machine # [ 21.019615] systemd[1]: Stopping Reactivate sysinit units...1259machine # [ 21.020564] systemd[1]: Reached target Reactivate sysinit units.1260machine # [ 21.024132] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1261machine # [ 21.026318] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1262machine # [ 21.029770] systemd[1]: Reached target Remote File Systems.1263machine # [ 21.032824] systemd[1]: Starting linger-users.service...1264machine # [ 21.038110] systemd[1]: Reached target Local File Systems.1265machine # [ 21.038898] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1266machine # [ 21.044237] systemd[1]: Starting Create SUID/SGID Wrappers...1267machine # [ 21.045147] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1268machine # [ 21.047374] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671269machine # [ 21.049434] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1270machine # [ 21.050949] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1271machine # [ 21.054474] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1272machine # [ 21.098329] systemd[1]: linger-users.service: Deactivated successfully.1273machine # [ 21.100666] systemd[1]: Finished linger-users.service.1274machine # [ 21.211650] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1275machine # [ 21.213692] systemd[1]: Finished Create SUID/SGID Wrappers.1276machine # [ 21.470869] nixos[1461]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1277machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1278machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11279machine # [ 21.715249] nixos[1602]: switching to system configuration /nix/store/l034yn0wgck1g94n9i8jwya6rkr5sncg-nixos-system-machine-test1280machine # [ 21.717951] systemd[1]: Stopped target Remote File Systems.1281machine # [ 21.719895] systemd[1]: Stopped target Local File Systems.1282machine # [ 21.963667] systemd[1]: Reload requested from client PID 1602 ('.switch-to-conf') (unit backdoor.service)...1283machine # [ 21.965458] systemd[1]: Reloading...1284machine # [ 22.265918] systemd[1]: Reloading finished in 298 ms.1285machine # [ 22.344210] systemd[621]: Stopped usertest.service.1286machine # [ 22.347106] systemd[621]: Reexecution requested from client PID 1671 ('switch-to-confi')...1287machine # [ 22.348326] systemd[621]: Reexecuting.1288machine # [ 22.370560] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1289machine # [ 22.450875] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1290machine # [ 22.455992] systemd[621]: Reload requested from client PID 1671 ('switch-to-confi')...1291machine # [ 22.457663] systemd[621]: Reloading...1292machine # [ 22.529756] systemd[621]: Reloading finished in 71 ms.1293machine # [ 22.535821] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1294machine # [ 22.544283] systemd[621]: Starting usertest.service...1295machine # [ 22.558171] systemd[621]: Finished usertest.service.1296machine # [ 22.561063] systemd[621]: Stopped Run user-specific NixOS activation.1297machine # [ 22.561911] systemd[621]: Stopping Run user-specific NixOS activation...1298machine # [ 22.563651] systemd[621]: Starting Run user-specific NixOS activation...1299machine # [ 22.581280] systemd[621]: Finished Run user-specific NixOS activation.1300machine # [ 22.584560] systemd[1]: Stopped target Reactivate sysinit units.1301machine # [ 22.585650] systemd[1]: Stopping Reactivate sysinit units...1302machine # [ 22.586962] systemd[1]: Reached target Reactivate sysinit units.1303machine # [ 22.592061] systemd[1]: Reached target Local File Systems.1304machine # [ 22.594364] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1305machine # [ 22.596541] systemd[1]: Starting Create SUID/SGID Wrappers...1306machine # [ 22.598329] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1307machine # [ 22.599860] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671308machine # [ 22.602583] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1309machine # [ 22.605398] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1310machine # [ 22.608260] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1311machine # [ 22.610498] systemd[1]: Reached target Remote File Systems.1312machine # [ 22.613433] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1313machine # [ 22.614796] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1314machine # [ 22.746549] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1315machine # [ 22.748400] systemd[1]: Finished Create SUID/SGID Wrappers.1316machine # [ 22.752574] systemd[1]: Starting linger-users.service...1317machine # [ 22.779294] systemd[1]: linger-users.service: Deactivated successfully.1318machine # [ 22.780934] systemd[1]: Finished linger-users.service.1319machine # [ 23.035630] nixos[1602]: finished switching to system configuration /nix/store/l034yn0wgck1g94n9i8jwya6rkr5sncg-nixos-system-machine-test1320machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 1.56 seconds)1321machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1322machine # [ 23.058795] sudo[1741]: 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.service1323machine # [ 23.066646] sudo[1741]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1324machine # [ 23.074150] sudo[1741]: pam_unix(sudo:session): session closed for user usertest1325machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.04 seconds)1326machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11327machine # [ 23.234085] nixos[1750]: switching to system configuration /nix/store/gny6bp03gn7p2jrgfcchc1mp8f40i3y6-nixos-system-machine-test1328machine # [ 23.236528] systemd[1]: Stopped target Remote File Systems.1329machine # [ 23.237994] systemd[1]: Stopped target Local File Systems.1330machine # [ 23.492375] systemd[1]: Reload requested from client PID 1750 ('.switch-to-conf') (unit backdoor.service)...1331machine # [ 23.493989] systemd[1]: Reloading...1332machine # [ 23.721971] systemd[1]: Reloading finished in 226 ms.1333machine # [ 23.788906] systemd[621]: Reexecution requested from client PID 1820 ('switch-to-confi')...1334machine # [ 23.791373] systemd[621]: Reexecuting.1335machine # [ 23.812159] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1336machine # [ 23.891252] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1337machine # [ 23.896058] systemd[621]: Reload requested from client PID 1820 ('switch-to-confi')...1338machine # [ 23.897771] systemd[621]: Reloading...1339machine # [ 23.971682] systemd[621]: Reloading finished in 73 ms.1340machine # [ 23.976822] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1341machine # [ 23.980464] systemd[621]: Stopped usertest.service.1342machine # [ 23.982063] systemd[621]: Stopping usertest.service...1343machine # [ 23.983538] systemd[621]: Starting usertest.service...1344machine # [ 23.998174] systemd[621]: Finished usertest.service.1345machine # [ 24.003185] systemd[621]: Stopped Run user-specific NixOS activation.1346machine # [ 24.004236] systemd[621]: Stopping Run user-specific NixOS activation...1347machine # [ 24.006224] systemd[621]: Starting Run user-specific NixOS activation...1348machine # [ 24.025317] systemd[621]: Finished Run user-specific NixOS activation.1349machine # [ 24.028541] systemd[1]: Stopped target Reactivate sysinit units.1350machine # [ 24.030169] systemd[1]: Stopping Reactivate sysinit units...1351machine # [ 24.030938] systemd[1]: Reached target Reactivate sysinit units.1352machine # [ 24.032816] systemd[1]: Reached target Remote File Systems.1353machine # [ 24.034603] systemd[1]: Reached target Local File Systems.1354machine # [ 24.036569] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1355machine # [ 24.038216] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1356machine # [ 24.043909] systemd[1]: Starting linger-users.service...1357machine # [ 24.053687] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1358machine # [ 24.058479] systemd[1]: Starting Create SUID/SGID Wrappers...1359machine # [ 24.059538] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1360machine # [ 24.061182] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671361machine # [ 24.063706] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1362machine # [ 24.065631] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 24.068082] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 24.101353] systemd[1]: linger-users.service: Deactivated successfully.1365machine # [ 24.103069] systemd[1]: Finished linger-users.service.1366machine # [ 24.211099] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1367machine # [ 24.212755] systemd[1]: Finished Create SUID/SGID Wrappers.1368machine # [ 24.469737] nixos[1750]: finished switching to system configuration /nix/store/gny6bp03gn7p2jrgfcchc1mp8f40i3y6-nixos-system-machine-test1369machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 1.40 seconds)1370machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1371machine # [ 24.517512] sudo[1890]: 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.service1372machine # [ 24.531944] sudo[1890]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1373machine # [ 24.541985] sudo[1890]: pam_unix(sudo:session): session closed for user usertest1374machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1375machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11376machine # [ 24.723162] nixos[1899]: switching to system configuration /nix/store/1vlx1hnlbmnxkhbdq2vycr40slmf75c2-nixos-system-machine-test1377machine # [ 24.725665] systemd[1]: Stopped target Local File Systems.1378machine # [ 24.727254] systemd[1]: Stopped target Remote File Systems.1379machine # [ 24.949582] systemd[1]: Reload requested from client PID 1899 ('.switch-to-conf') (unit backdoor.service)...1380machine # [ 24.951158] systemd[1]: Reloading...1381machine # [ 25.180102] systemd[1]: Reloading finished in 226 ms.1382machine # [ 25.242666] systemd[621]: Reexecution requested from client PID 1968 ('switch-to-confi')...1383machine # [ 25.245120] systemd[621]: Reexecuting.1384machine # [ 25.265258] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1385machine # [ 25.345243] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1386machine # [ 25.349399] systemd[621]: Reload requested from client PID 1968 ('switch-to-confi')...1387machine # [ 25.351116] systemd[621]: Reloading...1388machine # [ 25.422887] systemd[621]: Reloading finished in 70 ms.1389machine # [ 25.428822] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1390machine # [ 25.434085] systemd[621]: Reloading usertest.service...1391machine # [ 25.449785] systemd[621]: Reloaded usertest.service.1392machine # [ 25.455071] systemd[621]: Stopped Run user-specific NixOS activation.1393machine # [ 25.455935] systemd[621]: Stopping Run user-specific NixOS activation...1394machine # [ 25.457730] systemd[621]: Starting Run user-specific NixOS activation...1395machine # [ 25.475578] systemd[621]: Finished Run user-specific NixOS activation.1396machine # [ 25.478533] systemd[1]: Stopped target Reactivate sysinit units.1397machine # [ 25.479898] systemd[1]: Stopping Reactivate sysinit units...1398machine # [ 25.481161] systemd[1]: Reached target Reactivate sysinit units.1399machine # [ 25.483535] systemd[1]: Reached target Remote File Systems.1400machine # [ 25.485877] systemd[1]: Reached target Local File Systems.1401machine # [ 25.491139] systemd[1]: Starting linger-users.service...1402machine # [ 25.496747] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1403machine # [ 25.501905] systemd[1]: Starting Create SUID/SGID Wrappers...1404machine # [ 25.502855] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1405machine # [ 25.504039] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671406machine # [ 25.506831] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1407machine # [ 25.509527] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1408machine # [ 25.510671] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1409machine # [ 25.514134] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1410machine # [ 25.515329] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1411machine # [ 25.551286] systemd[1]: linger-users.service: Deactivated successfully.1412machine # [ 25.552474] systemd[1]: Finished linger-users.service.1413machine # [ 25.662702] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1414machine # [ 25.664474] systemd[1]: Finished Create SUID/SGID Wrappers.1415machine # [ 25.922404] nixos[1899]: finished switching to system configuration /nix/store/1vlx1hnlbmnxkhbdq2vycr40slmf75c2-nixos-system-machine-test1416machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 1.39 seconds)1417machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1418machine # [ 25.971412] sudo[2038]: 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.service1419machine # [ 25.985779] sudo[2038]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1420machine # [ 25.993402] sudo[2038]: pam_unix(sudo:session): session closed for user usertest1421machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1422machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11423machine # [ 26.136562] nixos[2047]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1424machine # [ 26.138671] systemd[1]: Stopped target Local File Systems.1425machine # [ 26.140264] systemd[1]: Stopped target Remote File Systems.1426machine # [ 26.328520] systemd[1]: Reload requested from client PID 2047 ('.switch-to-conf') (unit backdoor.service)...1427machine # [ 26.330160] systemd[1]: Reloading...1428machine # [ 26.541988] systemd[1]: Reloading finished in 210 ms.1429machine # [ 26.619277] systemd[621]: Stopped usertest.service.1430machine # [ 26.621244] systemd[621]: Reexecution requested from client PID 2116 ('switch-to-confi')...1431machine # [ 26.622802] systemd[621]: Reexecuting.1432machine # [ 26.642259] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1433machine # [ 26.716470] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1434machine # [ 26.721937] systemd[621]: Reload requested from client PID 2116 ('switch-to-confi')...1435machine # [ 26.723454] systemd[621]: Reloading...1436machine # [ 26.791181] systemd[621]: Reloading finished in 66 ms.1437machine # [ 26.796869] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1438machine # [ 26.803750] systemd[621]: Starting usertest.service...1439machine # [ 26.822301] systemd[621]: Finished usertest.service.1440machine # [ 26.824349] systemd[621]: Stopped Run user-specific NixOS activation.1441machine # [ 26.826545] systemd[621]: Stopping Run user-specific NixOS activation...1442machine # [ 26.829065] systemd[621]: Starting Run user-specific NixOS activation...1443machine # [ 26.847979] systemd[621]: Finished Run user-specific NixOS activation.1444machine # [ 26.850832] systemd[1]: Stopped target Reactivate sysinit units.1445machine # [ 26.852208] systemd[1]: Stopping Reactivate sysinit units...1446machine # [ 26.852945] systemd[1]: Reached target Reactivate sysinit units.1447machine # [ 26.857571] systemd[1]: Reached target Remote File Systems.1448machine # [ 26.859062] systemd[1]: Reached target Local File Systems.1449machine # [ 26.861710] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1450machine # [ 26.863650] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1451machine # [ 26.867275] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1452machine # [ 26.870763] systemd[1]: Starting Create SUID/SGID Wrappers...1453machine # [ 26.872404] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1454machine # [ 26.874323] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671455machine # [ 26.876304] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1456machine # [ 26.877769] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1457machine # [ 26.881135] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 27.007540] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1459machine # [ 27.009627] systemd[1]: Finished Create SUID/SGID Wrappers.1460machine # [ 27.013335] systemd[1]: Starting linger-users.service...1461machine # [ 27.038248] systemd[1]: linger-users.service: Deactivated successfully.1462machine # [ 27.039742] systemd[1]: Finished linger-users.service.1463machine # [ 27.296963] nixos[2047]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1464machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.31 seconds)1465machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1466machine # [ 27.331826] sudo[2187]: 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.service1467machine # [ 27.338885] sudo[2187]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1468machine # [ 27.345942] sudo[2187]: pam_unix(sudo:session): session closed for user usertest1469machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.04 seconds)1470machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11471machine # [ 27.525230] nixos[2196]: switching to system configuration /nix/store/4zpkjzx3gmx0w3jbivmwlmdifqqvwkql-nixos-system-machine-test1472machine # [ 27.527933] systemd[1]: Stopped target Remote File Systems.1473machine # [ 27.529641] systemd[1]: Stopped target Local File Systems.1474machine # [ 27.775061] systemd[1]: Reload requested from client PID 2196 ('.switch-to-conf') (unit backdoor.service)...1475machine # [ 27.776712] systemd[1]: Reloading...1476machine # [ 28.007419] systemd[1]: Reloading finished in 229 ms.1477machine # [ 28.077195] systemd[621]: Reexecution requested from client PID 2265 ('switch-to-confi')...1478machine # [ 28.078543] systemd[621]: Reexecuting.1479machine # [ 28.095088] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1480machine # [ 28.169454] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1481machine # [ 28.174584] systemd[621]: Reload requested from client PID 2265 ('switch-to-confi')...1482machine # [ 28.176984] systemd[621]: Reloading...1483machine # [ 28.250794] systemd[621]: Reloading finished in 74 ms.1484machine # [ 28.255823] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 28.261466] systemd[621]: Reloading usertest.service...1486machine # [ 28.275786] systemd[621]: Reloaded usertest.service.1487machine # [ 28.280405] systemd[621]: Stopped Run user-specific NixOS activation.1488machine # [ 28.282065] systemd[621]: Stopping Run user-specific NixOS activation...1489machine # [ 28.283610] systemd[621]: Starting Run user-specific NixOS activation...1490machine # [ 28.302352] systemd[621]: Finished Run user-specific NixOS activation.1491machine # [ 28.305683] systemd[1]: Stopped target Reactivate sysinit units.1492machine # [ 28.306658] systemd[1]: Stopping Reactivate sysinit units...1493machine # [ 28.308033] systemd[1]: Reached target Reactivate sysinit units.1494machine # [ 28.309290] systemd[1]: Reached target Remote File Systems.1495machine # [ 28.311783] systemd[1]: Reached target Local File Systems.1496machine # [ 28.316792] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1497machine # [ 28.320803] systemd[1]: Starting Create SUID/SGID Wrappers...1498machine # [ 28.322069] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1499machine # [ 28.323232] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671500machine # [ 28.325184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1501machine # [ 28.326744] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1502machine # [ 28.332362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1503machine # [ 28.333762] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1504machine # [ 28.335047] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1505machine # [ 28.462030] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1506machine # [ 28.463638] systemd[1]: Finished Create SUID/SGID Wrappers.1507machine # [ 28.467711] systemd[1]: Starting linger-users.service...1508machine # [ 28.493437] systemd[1]: linger-users.service: Deactivated successfully.1509machine # [ 28.494944] systemd[1]: Finished linger-users.service.1510machine # [ 28.752597] nixos[2196]: finished switching to system configuration /nix/store/4zpkjzx3gmx0w3jbivmwlmdifqqvwkql-nixos-system-machine-test1511machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 1.41 seconds)1512machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1513machine # [ 28.798673] sudo[2335]: 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.service1514machine # [ 28.813543] sudo[2335]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1515machine # [ 28.823903] sudo[2335]: pam_unix(sudo:session): session closed for user usertest1516machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1517machine: must fail: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11518machine # [ 29.005656] nixos[2344]: switching to system configuration /nix/store/pck2mjyaxl416rrddxx4p96h8lyzkj6n-nixos-system-machine-test1519machine # [ 29.008946] systemd[1]: Stopped target Remote File Systems.1520machine # [ 29.010081] systemd[1]: Stopped target Local File Systems.1521machine # [ 29.230737] systemd[1]: Reload requested from client PID 2344 ('.switch-to-conf') (unit backdoor.service)...1522machine # [ 29.232459] systemd[1]: Reloading...1523machine # [ 29.457939] systemd[1]: Reloading finished in 223 ms.1524machine # [ 29.526922] systemd[621]: Stopped usertest.service.1525machine # [ 29.528191] systemd[621]: Reexecution requested from client PID 2413 ('switch-to-confi')...1526machine # [ 29.530722] systemd[621]: Reexecuting.1527machine # [ 29.551096] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1528machine # [ 29.624694] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1529machine # [ 29.629686] systemd[621]: Reload requested from client PID 2413 ('switch-to-confi')...1530machine # [ 29.630937] systemd[621]: Reloading...1531machine # [ 29.703813] systemd[621]: Reloading finished in 71 ms.1532machine # [ 29.707816] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1533machine # [ 29.714281] systemd[621]: Starting usertest.service...1534machine # [ 29.739254] systemd[621]: usertest.service: Main process exited, code=exited, status=1/FAILURE1535machine # [ 29.740665] systemd[621]: usertest.service: Failed with result 'exit-code'.1536machine # [ 29.743373] systemd[621]: Failed to start usertest.service.1537machine # [ 29.748070] systemd[621]: Stopped Run user-specific NixOS activation.1538machine # [ 29.748964] systemd[621]: Stopping Run user-specific NixOS activation...1539machine # [ 29.751936] systemd[621]: Starting Run user-specific NixOS activation...1540machine # [ 29.786945] systemd[621]: Finished Run user-specific NixOS activation.1541machine # [ 29.791705] systemd[1]: Stopped target Reactivate sysinit units.1542machine # [ 29.792980] systemd[1]: Stopping Reactivate sysinit units...1543machine # [ 29.794310] systemd[1]: Reached target Reactivate sysinit units.1544machine # [ 29.798954] systemd[1]: Reached target Local File Systems.1545machine # [ 29.801187] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1546machine # [ 29.804207] systemd[1]: Starting Create SUID/SGID Wrappers...1547machine # [ 29.805024] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1548machine # [ 29.809088] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671549machine # [ 29.811865] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1550machine # [ 29.813279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1551machine # [ 29.816716] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1552machine # [ 29.818146] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1553machine # [ 29.819357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1554machine # [ 29.825138] systemd[1]: Reached target Remote File Systems.1555machine # [ 29.953874] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1556machine # [ 29.955713] systemd[1]: Finished Create SUID/SGID Wrappers.1557machine # [ 29.959859] systemd[1]: Starting linger-users.service...1558machine # [ 29.986410] systemd[1]: linger-users.service: Deactivated successfully.1559machine # [ 29.987905] systemd[1]: Finished linger-users.service.1560machine # [ 30.245554] nixos[2344]: switching to system configuration /nix/store/pck2mjyaxl416rrddxx4p96h8lyzkj6n-nixos-system-machine-test failed (status 4)1561machine: (finished: must fail: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1562machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11563machine # [ 30.456519] nixos[2487]: switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1564machine # [ 30.459231] systemd[1]: Stopped target Local File Systems.1565machine # [ 30.461110] systemd[1]: Stopped target Remote File Systems.1566machine # [ 30.669654] systemd[1]: Reload requested from client PID 2487 ('.switch-to-conf') (unit backdoor.service)...1567machine # [ 30.671366] systemd[1]: Reloading...1568machine # [ 30.907556] systemd[1]: Reloading finished in 234 ms.1569machine # [ 30.970566] systemd[621]: Reexecution requested from client PID 2556 ('switch-to-confi')...1570machine # [ 30.972293] systemd[621]: Reexecuting.1571machine # [ 30.992960] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1572machine # [ 31.065649] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1573machine # [ 31.071037] systemd[621]: Reload requested from client PID 2556 ('switch-to-confi')...1574machine # [ 31.072256] systemd[621]: Reloading...1575machine # [ 31.144457] systemd[621]: Reloading finished in 70 ms.1576machine # [ 31.149814] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1577machine # [ 31.156032] systemd[621]: Starting usertest.service...1578machine # [ 31.170930] systemd[621]: Finished usertest.service.1579machine # [ 31.173972] systemd[621]: Stopped Run user-specific NixOS activation.1580machine # [ 31.174865] systemd[621]: Stopping Run user-specific NixOS activation...1581machine # [ 31.176230] systemd[621]: Starting Run user-specific NixOS activation...1582machine # [ 31.194456] systemd[621]: Finished Run user-specific NixOS activation.1583machine # [ 31.197444] systemd[1]: Stopped target Reactivate sysinit units.1584machine # [ 31.198762] systemd[1]: Stopping Reactivate sysinit units...1585machine # [ 31.199760] systemd[1]: Reached target Reactivate sysinit units.1586machine # [ 31.202499] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1587machine # [ 31.204265] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1588machine # [ 31.207107] systemd[1]: Reached target Remote File Systems.1589machine # [ 31.209245] systemd[1]: Starting linger-users.service...1590machine # [ 31.211744] systemd[1]: Reached target Local File Systems.1591machine # [ 31.219849] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1592machine # [ 31.224078] systemd[1]: Starting Create SUID/SGID Wrappers...1593machine # [ 31.224959] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1594machine # [ 31.226162] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671595machine # [ 31.228219] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1596machine # [ 31.229690] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1597machine # [ 31.234847] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1598machine # [ 31.265632] systemd[1]: linger-users.service: Deactivated successfully.1599machine # [ 31.267350] systemd[1]: Finished linger-users.service.1600machine # [ 31.376846] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1601machine # [ 31.378544] systemd[1]: Finished Create SUID/SGID Wrappers.1602machine # [ 31.635673] nixos[2487]: finished switching to system configuration /nix/store/b1acdf5alvi1qinbv4avbq1ypqs7y4xl-nixos-system-machine-test1603machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 1.39 seconds)1604machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1605machine # [ 31.683265] sudo[2626]: 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.service1606machine # [ 31.697546] sudo[2626]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1607machine # [ 31.708567] sudo[2626]: pam_unix(sudo:session): session closed for user usertest1608machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1609machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&11610machine # [ 31.888202] nixos[2635]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1611machine # [ 31.891041] systemd[1]: Stopped target Local File Systems.1612machine # [ 31.892201] systemd[1]: Stopped target Remote File Systems.1613machine # [ 32.095132] systemd[1]: Reload requested from client PID 2635 ('.switch-to-conf') (unit backdoor.service)...1614machine # [ 32.096881] systemd[1]: Reloading...1615machine # [ 32.327095] systemd[1]: Reloading finished in 228 ms.1616machine # [ 32.388511] systemd[621]: Stopped usertest.service.1617machine # [ 32.389813] systemd[621]: Reexecution requested from client PID 2704 ('switch-to-confi')...1618machine # [ 32.392240] systemd[621]: Reexecuting.1619machine # [ 32.413066] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1620machine # [ 32.487352] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1621machine # [ 32.492535] systemd[621]: Reload requested from client PID 2704 ('switch-to-confi')...1622machine # [ 32.493791] systemd[621]: Reloading...1623machine # [ 32.564667] systemd[621]: Reloading finished in 69 ms.1624machine # [ 32.568817] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1625machine # [ 32.574972] systemd[621]: Stopped Run user-specific NixOS activation.1626machine # [ 32.576402] systemd[621]: Stopping Run user-specific NixOS activation...1627machine # [ 32.578831] systemd[621]: Starting Run user-specific NixOS activation...1628machine # [ 32.598538] systemd[621]: Finished Run user-specific NixOS activation.1629machine # [ 32.601554] systemd[1]: Stopped target Reactivate sysinit units.1630machine # [ 32.602793] systemd[1]: Stopping Reactivate sysinit units...1631machine # [ 32.603785] systemd[1]: Reached target Reactivate sysinit units.1632machine # [ 32.608562] systemd[1]: Reached target Remote File Systems.1633machine # [ 32.612980] systemd[1]: Reached target Local File Systems.1634machine # [ 32.615071] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1635machine # [ 32.617285] systemd[1]: Starting Create SUID/SGID Wrappers...1636machine # [ 32.619119] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1637machine # [ 32.620758] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671638machine # [ 32.622775] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1639machine # [ 32.624198] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1640machine # [ 32.625439] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1641machine # [ 32.629991] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1642machine # [ 32.631588] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1643machine # [ 32.761988] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1644machine # [ 32.764081] systemd[1]: Finished Create SUID/SGID Wrappers.1645machine # [ 32.767690] systemd[1]: Starting linger-users.service...1646machine # [ 32.794865] systemd[1]: linger-users.service: Deactivated successfully.1647machine # [ 32.796371] systemd[1]: Finished linger-users.service.1648machine # [ 33.052790] nixos[2635]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1649machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds)1650machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1651machine # [ 33.090327] 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.service1652machine # [ 33.097594] sudo[2773]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1653machine # [ 33.105400] sudo[2773]: pam_unix(sudo:session): session closed for user usertest1654machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.05 seconds)1655machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1656machine # [ 33.123971] sudo[2779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1657machine # [ 33.130846] sudo[2779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1658machine # [ 33.136420] sudo[2779]: pam_unix(sudo:session): session closed for user usertest1659machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)1660machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service1661machine # [ 33.154270] sudo[2785]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service1662machine # [ 33.161373] sudo[2785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1663machine # [ 33.167209] sudo[2785]: pam_unix(sudo:session): session closed for user usertest1664machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)1665machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1666machine # [ 33.185406] sudo[2791]: 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.service1667machine # [ 33.192463] sudo[2791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1668machine # [ 33.197526] sudo[2791]: pam_unix(sudo:session): session closed for user usertest1669machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1670machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1671machine # [ 33.215324] sudo[2797]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1672machine # [ 33.223417] sudo[2797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1673machine # [ 33.228688] systemd[621]: Reload requested from client PID 2802 ('systemctl')...1674machine # [ 33.229841] systemd[621]: Reloading...1675machine # [ 33.302798] systemd[621]: Reloading finished in 71 ms.1676machine # [ 33.307813] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1677machine # [ 33.315799] sudo[2797]: pam_unix(sudo:session): session closed for user usertest1678machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)1679machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1680machine # [ 33.336277] sudo[2807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1681machine # [ 33.343527] sudo[2807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1682machine # [ 33.352983] systemd[621]: Starting migrated.service...1683machine # [ 33.371024] systemd[621]: Finished migrated.service.1684machine # [ 33.374068] sudo[2807]: pam_unix(sudo:session): session closed for user usertest1685machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)1686machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1687machine # [ 33.395750] sudo[2815]: 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.service1688machine # [ 33.403312] sudo[2815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1689machine # [ 33.410213] sudo[2815]: pam_unix(sudo:session): session closed for user usertest1690machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)1691machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1692machine # [ 33.429924] sudo[2821]: 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-owner1693machine # [ 33.437494] sudo[2821]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1694machine # [ 33.443165] sudo[2821]: pam_unix(sudo:session): session closed for user usertest1695machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1696machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1697machine # [ 33.462704] sudo[2827]: 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.service1698machine # [ 33.470421] sudo[2827]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1699machine # [ 33.481797] sudo[2827]: pam_unix(sudo:session): session closed for user usertest1700machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1701machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11702machine # [ 33.657811] nixos[2836]: switching to system configuration /nix/store/1278my32hhny82vdmqzcv00g2qfqrsh2-nixos-system-machine-test1703machine # [ 33.661250] systemd[1]: Stopped target Remote File Systems.1704machine # [ 33.662258] systemd[1]: Stopped target Local File Systems.1705machine # [ 33.876092] systemd[1]: Reload requested from client PID 2836 ('.switch-to-conf') (unit backdoor.service)...1706machine # [ 33.877759] systemd[1]: Reloading...1707machine # [ 34.106887] systemd[1]: Reloading finished in 227 ms.1708machine # [ 34.175264] systemd[621]: Reexecution requested from client PID 2905 ('switch-to-confi')...1709machine # [ 34.176556] systemd[621]: Reexecuting.1710machine # [ 34.196976] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1711machine # [ 34.270886] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1712machine # [ 34.275975] systemd[621]: Reload requested from client PID 2905 ('switch-to-confi')...1713machine # [ 34.277795] systemd[621]: Reloading...1714machine # [ 34.349493] systemd[621]: Reloading finished in 69 ms.1715machine # [ 34.353874] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1716machine # [ 34.361115] systemd[621]: Stopped Run user-specific NixOS activation.1717machine # [ 34.361978] systemd[621]: Stopping Run user-specific NixOS activation...1718machine # [ 34.364107] systemd[621]: Starting Run user-specific NixOS activation...1719machine # [ 34.400307] systemd[621]: Reload requested from client PID 2917 ('systemctl') (unit nixos-activation.service)...1720machine # [ 34.402037] systemd[621]: Reloading...1721machine # [ 34.478697] systemd[621]: Reloading finished in 75 ms.1722machine # [ 34.483987] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1723machine # [ 34.498304] systemd[621]: Stopped migrated.service.1724machine # [ 34.505308] systemd[621]: Finished Run user-specific NixOS activation.1725machine # [ 34.507482] systemd[621]: Reload requested from client PID 2905 ('switch-to-confi')...1726machine # [ 34.508896] systemd[621]: Reloading...1727machine # [ 34.580269] systemd[621]: Reloading finished in 70 ms.1728machine # [ 34.585829] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1729machine # [ 34.593691] systemd[621]: Starting migrated.service...1730machine # [ 34.609692] systemd[621]: Finished migrated.service.1731machine # [ 34.612585] systemd[1]: Stopped target Reactivate sysinit units.1732machine # [ 34.613892] systemd[1]: Stopping Reactivate sysinit units...1733machine # [ 34.615516] systemd[1]: Reached target Reactivate sysinit units.1734machine # [ 34.618799] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1735machine # [ 34.620494] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1736machine # [ 34.623687] systemd[1]: Reached target Remote File Systems.1737machine # [ 34.626579] systemd[1]: Starting linger-users.service...1738machine # [ 34.631287] systemd[1]: Reached target Local File Systems.1739machine # [ 34.632113] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1740machine # [ 34.636711] systemd[1]: Starting Create SUID/SGID Wrappers...1741machine # [ 34.637512] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1742machine # [ 34.638663] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671743machine # [ 34.640683] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1744machine # [ 34.642096] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1745machine # [ 34.643289] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1746machine # [ 34.684670] systemd[1]: linger-users.service: Deactivated successfully.1747machine # [ 34.686507] systemd[1]: Finished linger-users.service.1748machine # [ 34.785780] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1749machine # [ 34.787746] systemd[1]: Finished Create SUID/SGID Wrappers.1750machine # [ 35.046410] nixos[2836]: finished switching to system configuration /nix/store/1278my32hhny82vdmqzcv00g2qfqrsh2-nixos-system-machine-test1751machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 1.57 seconds)1752machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1753machine # [ 35.073629] sudo[2987]: 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.service1754machine # [ 35.081543] sudo[2987]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1755machine # [ 35.088370] sudo[2987]: pam_unix(sudo:session): session closed for user usertest1756machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)1757machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1758machine # [ 35.107975] sudo[2993]: 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.service1759machine # [ 35.115517] sudo[2993]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1760machine # [ 35.127128] sudo[2993]: pam_unix(sudo:session): session closed for user usertest1761machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1762machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1763machine # [ 35.147178] sudo[2999]: 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-owner1764machine # [ 35.154408] sudo[2999]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1765machine # [ 35.159781] sudo[2999]: pam_unix(sudo:session): session closed for user usertest1766machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1767machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&11768machine # [ 35.336811] nixos[3008]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1769machine # [ 35.339336] systemd[1]: Stopped target Remote File Systems.1770machine # [ 35.340985] systemd[1]: Stopped target Local File Systems.1771machine # [ 35.548546] systemd[1]: Reload requested from client PID 3008 ('.switch-to-conf') (unit backdoor.service)...1772machine # [ 35.550165] systemd[1]: Reloading...1773machine # [ 35.781573] systemd[1]: Reloading finished in 229 ms.1774machine # [ 35.848563] systemd[621]: Stopped migrated.service.1775machine # [ 35.849709] systemd[621]: Reexecution requested from client PID 3077 ('switch-to-confi')...1776machine # [ 35.852182] systemd[621]: Reexecuting.1777machine # [ 35.873069] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1778machine # [ 35.947676] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1779machine # [ 35.953226] systemd[621]: Reload requested from client PID 3077 ('switch-to-confi')...1780machine # [ 35.954590] systemd[621]: Reloading...1781machine # [ 36.024755] systemd[621]: Reloading finished in 69 ms.1782machine # [ 36.030856] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1783machine # [ 36.037297] systemd[621]: Stopped Run user-specific NixOS activation.1784machine # [ 36.039089] systemd[621]: Stopping Run user-specific NixOS activation...1785machine # [ 36.040908] systemd[621]: Starting Run user-specific NixOS activation...1786machine # [ 36.060495] systemd[621]: Finished Run user-specific NixOS activation.1787machine # [ 36.063714] systemd[1]: Stopped target Reactivate sysinit units.1788machine # [ 36.064743] systemd[1]: Stopping Reactivate sysinit units...1789machine # [ 36.065895] systemd[1]: Reached target Reactivate sysinit units.1790machine # [ 36.070930] systemd[1]: Reached target Local File Systems.1791machine # [ 36.073338] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1792machine # [ 36.075453] systemd[1]: Starting Create SUID/SGID Wrappers...1793machine # [ 36.077335] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1794machine # [ 36.079426] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671795machine # [ 36.081864] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1796machine # [ 36.085329] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1797machine # [ 36.086546] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1798machine # [ 36.090306] systemd[1]: Reached target Remote File Systems.1799machine # [ 36.093200] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1800machine # [ 36.094419] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1801machine # [ 36.258969] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1802machine # [ 36.261252] systemd[1]: Finished Create SUID/SGID Wrappers.1803machine # [ 36.265603] systemd[1]: Starting linger-users.service...1804machine # [ 36.297480] systemd[1]: linger-users.service: Deactivated successfully.1805machine # [ 36.299244] systemd[1]: Finished linger-users.service.1806machine # [ 36.556900] nixos[3008]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1807machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.40 seconds)1808machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1809machine # [ 36.603463] sudo[3146]: 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.service1810machine # [ 36.618462] sudo[3146]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1811machine # [ 36.626140] sudo[3146]: pam_unix(sudo:session): session closed for user usertest1812machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1813machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1814machine # [ 36.646972] sudo[3152]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1815machine # [ 36.654709] sudo[3152]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1816machine # [ 36.660632] sudo[3152]: pam_unix(sudo:session): session closed for user usertest1817machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)1818machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service1819machine # [ 36.682308] sudo[3158]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service1820machine # [ 36.690514] sudo[3158]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1821machine # [ 36.696251] sudo[3158]: pam_unix(sudo:session): session closed for user usertest1822machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds)1823machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1824machine # [ 36.716205] sudo[3164]: 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.service1825machine # [ 36.723668] sudo[3164]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1826machine # [ 36.729201] sudo[3164]: pam_unix(sudo:session): session closed for user usertest1827machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1828machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1829machine # [ 36.748605] sudo[3170]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1830machine # [ 36.756355] sudo[3170]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1831machine # [ 36.761654] systemd[621]: Reload requested from client PID 3175 ('systemctl')...1832machine # [ 36.762792] systemd[621]: Reloading...1833machine # [ 36.835950] systemd[621]: Reloading finished in 71 ms.1834machine # [ 36.841829] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1835machine # [ 36.849272] sudo[3170]: pam_unix(sudo:session): session closed for user usertest1836machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)1837machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1838machine # [ 36.869599] sudo[3180]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1839machine # [ 36.877309] sudo[3180]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1840machine # [ 36.886262] systemd[621]: Starting migrated.service...1841machine # [ 36.902361] systemd[621]: Finished migrated.service.1842machine # [ 36.905113] sudo[3180]: pam_unix(sudo:session): session closed for user usertest1843machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)1844machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1845machine # [ 36.925321] sudo[3188]: 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.service1846machine # [ 36.932807] sudo[3188]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1847machine # [ 36.939785] sudo[3188]: pam_unix(sudo:session): session closed for user usertest1848machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)1849machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1850machine # [ 36.959722] sudo[3194]: 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-owner1851machine # [ 36.966946] sudo[3194]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1852machine # [ 36.972478] sudo[3194]: pam_unix(sudo:session): session closed for user usertest1853machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1854machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1855machine # [ 36.991841] sudo[3200]: 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.service1856machine # [ 36.999248] sudo[3200]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1857machine # [ 37.010118] sudo[3200]: pam_unix(sudo:session): session closed for user usertest1858machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1859machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11860machine # [ 37.189097] nixos[3209]: switching to system configuration /nix/store/w391wzbi5fafv0qjb12ajpl26p7ygd50-nixos-system-machine-test1861machine # [ 37.191459] systemd[1]: Stopped target Local File Systems.1862machine # [ 37.193453] systemd[1]: Stopped target Remote File Systems.1863machine # [ 37.415987] systemd[1]: Reload requested from client PID 3209 ('.switch-to-conf') (unit backdoor.service)...1864machine # [ 37.417716] systemd[1]: Reloading...1865machine # [ 37.646072] systemd[1]: Reloading finished in 226 ms.1866machine # [ 37.710080] systemd[621]: Reexecution requested from client PID 3278 ('switch-to-confi')...1867machine # [ 37.712655] systemd[621]: Reexecuting.1868machine # [ 37.733113] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1869machine # [ 37.807533] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1870machine # [ 37.812770] systemd[621]: Reload requested from client PID 3278 ('switch-to-confi')...1871machine # [ 37.814579] systemd[621]: Reloading...1872machine # [ 37.885751] systemd[621]: Reloading finished in 70 ms.1873machine # [ 37.891113] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1874machine # [ 37.898167] systemd[621]: Stopped Run user-specific NixOS activation.1875machine # [ 37.899060] systemd[621]: Stopping Run user-specific NixOS activation...1876machine # [ 37.901304] systemd[621]: Starting Run user-specific NixOS activation...1877machine # [ 37.926128] systemd[621]: Reload requested from client PID 3290 ('systemctl') (unit nixos-activation.service)...1878machine # [ 37.927797] systemd[621]: Reloading...1879machine # [ 38.003476] systemd[621]: Reloading finished in 74 ms.1880machine # [ 38.009065] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1881machine # [ 38.020105] systemd[621]: Finished Run user-specific NixOS activation.1882machine # [ 38.022368] systemd[621]: Reload requested from client PID 3278 ('switch-to-confi')...1883machine # [ 38.023842] systemd[621]: Reloading...1884machine # [ 38.094768] systemd[621]: Reloading finished in 69 ms.1885machine # [ 38.100919] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1886machine # [ 38.107127] systemd[621]: Stopped migrated.service.1887machine # [ 38.107899] systemd[621]: Stopping migrated.service...1888machine # [ 38.109988] systemd[621]: Starting migrated.service...1889machine # [ 38.126545] systemd[621]: Finished migrated.service.1890machine # [ 38.132107] systemd[1]: Stopped target Reactivate sysinit units.1891machine # [ 38.133706] systemd[1]: Stopping Reactivate sysinit units...1892machine # [ 38.134602] systemd[1]: Reached target Reactivate sysinit units.1893machine # [ 38.137917] systemd[1]: Reached target Local File Systems.1894machine # [ 38.139941] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1895machine # [ 38.142916] systemd[1]: Starting Create SUID/SGID Wrappers...1896machine # [ 38.143773] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1897machine # [ 38.144930] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671898machine # [ 38.146873] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1899machine # [ 38.148387] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1900machine # [ 38.152459] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1901machine # [ 38.153964] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 38.155521] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1903machine # [ 38.159100] systemd[1]: Reached target Remote File Systems.1904machine # [ 38.290300] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1905machine # [ 38.291876] systemd[1]: Finished Create SUID/SGID Wrappers.1906machine # [ 38.295881] systemd[1]: Starting linger-users.service...1907machine # [ 38.324755] systemd[1]: linger-users.service: Deactivated successfully.1908machine # [ 38.326297] systemd[1]: Finished linger-users.service.1909machine # [ 38.580747] nixos[3209]: finished switching to system configuration /nix/store/w391wzbi5fafv0qjb12ajpl26p7ygd50-nixos-system-machine-test1910machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 1.57 seconds)1911machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1912machine # [ 38.605921] sudo[3359]: 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.service1913machine # [ 38.613771] sudo[3359]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1914machine # [ 38.621139] sudo[3359]: pam_unix(sudo:session): session closed for user usertest1915machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)1916machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1917machine # [ 38.640948] sudo[3365]: 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.service1918machine # [ 38.648743] sudo[3365]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1919machine # [ 38.660850] sudo[3365]: pam_unix(sudo:session): session closed for user usertest1920machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)1921machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1922machine # [ 38.681668] sudo[3371]: 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-owner1923machine # [ 38.689310] sudo[3371]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1924machine # [ 38.695264] sudo[3371]: pam_unix(sudo:session): session closed for user usertest1925machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)1926machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&11927machine # [ 38.875755] nixos[3380]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1928machine # [ 38.877945] systemd[1]: Stopped target Local File Systems.1929machine # [ 38.879694] systemd[1]: Stopped target Remote File Systems.1930machine # [ 39.083774] systemd[1]: Reload requested from client PID 3380 ('.switch-to-conf') (unit backdoor.service)...1931machine # [ 39.085433] systemd[1]: Reloading...1932machine # [ 39.316607] systemd[1]: Reloading finished in 229 ms.1933machine # [ 39.414474] systemd[621]: Stopped migrated.service.1934machine # [ 39.415870] systemd[621]: Reexecution requested from client PID 3449 ('switch-to-confi')...1935machine # [ 39.418257] systemd[621]: Reexecuting.1936machine # [ 39.439072] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1937machine # [ 39.512716] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1938machine # [ 39.517700] systemd[621]: Reload requested from client PID 3449 ('switch-to-confi')...1939machine # [ 39.518892] systemd[621]: Reloading...1940machine # [ 39.593623] systemd[621]: Reloading finished in 73 ms.1941machine # [ 39.598824] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1942machine # [ 39.604694] systemd[621]: Stopped Run user-specific NixOS activation.1943machine # [ 39.606128] systemd[621]: Stopping Run user-specific NixOS activation...1944machine # [ 39.608186] systemd[621]: Starting Run user-specific NixOS activation...1945machine # [ 39.627644] systemd[621]: Finished Run user-specific NixOS activation.1946machine # [ 39.631079] systemd[1]: Stopped target Reactivate sysinit units.1947machine # [ 39.631919] systemd[1]: Stopping Reactivate sysinit units...1948machine # [ 39.632959] systemd[1]: Reached target Reactivate sysinit units.1949machine # [ 39.635609] systemd[1]: Reached target Remote File Systems.1950machine # [ 39.638214] systemd[1]: Starting linger-users.service...1951machine # [ 39.645075] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1952machine # [ 39.646292] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1953machine # [ 39.651778] systemd[1]: Reached target Local File Systems.1954machine # [ 39.653297] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1955machine # [ 39.657359] systemd[1]: Starting Create SUID/SGID Wrappers...1956machine # [ 39.659293] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1957machine # [ 39.660480] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671958machine # [ 39.662509] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1959machine # [ 39.664044] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1960machine # [ 39.665248] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1961machine # [ 39.701946] systemd[1]: linger-users.service: Deactivated successfully.1962machine # [ 39.703154] systemd[1]: Finished linger-users.service.1963machine # [ 39.821758] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1964machine # [ 39.824655] systemd[1]: Finished Create SUID/SGID Wrappers.1965machine # [ 40.081443] nixos[3380]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test1966machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.39 seconds)1967machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1968machine # [ 40.128993] sudo[3518]: 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.service1969machine # [ 40.143434] sudo[3518]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1970machine # [ 40.152059] sudo[3518]: pam_unix(sudo:session): session closed for user usertest1971machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)1972machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1973machine # [ 40.174317] sudo[3524]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1974machine # [ 40.183265] sudo[3524]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1975machine # [ 40.189125] sudo[3524]: pam_unix(sudo:session): session closed for user usertest1976machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.04 seconds)1977machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service1978machine # [ 40.210187] sudo[3530]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service1979machine # [ 40.218431] sudo[3530]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1980machine # [ 40.224878] sudo[3530]: pam_unix(sudo:session): session closed for user usertest1981machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.04 seconds)1982machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1983machine # [ 40.246366] sudo[3536]: 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.service1984machine # [ 40.254350] sudo[3536]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1985machine # [ 40.260123] sudo[3536]: pam_unix(sudo:session): session closed for user usertest1986machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)1987machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1988machine # [ 40.280191] sudo[3542]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1989machine # [ 40.287780] sudo[3542]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1990machine # [ 40.293640] systemd[621]: Reload requested from client PID 3547 ('systemctl')...1991machine # [ 40.295539] systemd[621]: Reloading...1992machine # [ 40.372439] systemd[621]: Reloading finished in 75 ms.1993machine # [ 40.377899] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1994machine # [ 40.385530] sudo[3542]: pam_unix(sudo:session): session closed for user usertest1995machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.13 seconds)1996machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1997machine # [ 40.407337] sudo[3552]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1998machine # [ 40.414796] sudo[3552]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1999machine # [ 40.424273] systemd[621]: Starting migrated.service...2000machine # [ 40.439297] systemd[621]: Finished migrated.service.2001machine # [ 40.442154] sudo[3552]: pam_unix(sudo:session): session closed for user usertest2002machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2003machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2004machine # [ 40.463830] sudo[3560]: 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.service2005machine # [ 40.471563] sudo[3560]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2006machine # [ 40.478622] sudo[3560]: pam_unix(sudo:session): session closed for user usertest2007machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)2008machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2009machine # [ 40.498598] sudo[3566]: 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-owner2010machine # [ 40.506487] sudo[3566]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2011machine # [ 40.511852] sudo[3566]: pam_unix(sudo:session): session closed for user usertest2012machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2013machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2014machine # [ 40.536244] sudo[3572]: 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.service2015machine # [ 40.545983] sudo[3572]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2016machine # [ 40.557794] sudo[3572]: pam_unix(sudo:session): session closed for user usertest2017machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.05 seconds)2018machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&12019machine # [ 40.741358] nixos[3581]: switching to system configuration /nix/store/g8pkh9x0w8hfl3ylrfzm8nilylf98gmw-nixos-system-machine-test2020machine # [ 40.743782] systemd[1]: Stopped target Remote File Systems.2021machine # [ 40.745693] systemd[1]: Stopped target Local File Systems.2022machine # [ 40.969974] systemd[1]: Reload requested from client PID 3581 ('.switch-to-conf') (unit backdoor.service)...2023machine # [ 40.971601] systemd[1]: Reloading...2024machine # [ 41.202086] systemd[1]: Reloading finished in 229 ms.2025machine # [ 41.268086] systemd[621]: Reexecution requested from client PID 3650 ('switch-to-confi')...2026machine # [ 41.270409] systemd[621]: Reexecuting.2027machine # [ 41.291149] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2028machine # [ 41.365364] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2029machine # [ 41.370768] systemd[621]: Reload requested from client PID 3650 ('switch-to-confi')...2030machine # [ 41.372105] systemd[621]: Reloading...2031machine # [ 41.437887] systemd[621]: Reloading finished in 64 ms.2032machine # [ 41.442820] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2033machine # [ 41.448743] systemd[621]: Stopped Run user-specific NixOS activation.2034machine # [ 41.449757] systemd[621]: Stopping Run user-specific NixOS activation...2035machine # [ 41.452311] systemd[621]: Starting Run user-specific NixOS activation...2036machine # [ 41.475698] systemd[621]: Reload requested from client PID 3662 ('systemctl') (unit nixos-activation.service)...2037machine # [ 41.477743] systemd[621]: Reloading...2038machine # [ 41.552197] systemd[621]: Reloading finished in 73 ms.2039machine # [ 41.558022] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2040machine # [ 41.568317] systemd[621]: Finished Run user-specific NixOS activation.2041machine # [ 41.570564] systemd[621]: Reload requested from client PID 3650 ('switch-to-confi')...2042machine # [ 41.571977] systemd[621]: Reloading...2043machine # [ 41.643895] systemd[621]: Reloading finished in 70 ms.2044machine # [ 41.649870] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2045machine # [ 41.656891] systemd[621]: Reloading migrated.service...2046machine # [ 41.673174] systemd[621]: Reloaded migrated.service.2047machine # [ 41.678671] systemd[1]: Stopped target Reactivate sysinit units.2048machine # [ 41.679896] systemd[1]: Stopping Reactivate sysinit units...2049machine # [ 41.680958] systemd[1]: Reached target Reactivate sysinit units.2050machine # [ 41.687298] systemd[1]: Reached target Local File Systems.2051machine # [ 41.689172] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2052machine # [ 41.692202] systemd[1]: Starting Create SUID/SGID Wrappers...2053machine # [ 41.694060] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2054machine # [ 41.695269] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672055machine # [ 41.697290] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2056machine # [ 41.698795] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2057machine # [ 41.699986] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2058machine # [ 41.703556] systemd[1]: Reached target Remote File Systems.2059machine # [ 41.706523] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2060machine # [ 41.708906] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2061machine # [ 41.836325] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2062machine # [ 41.839128] systemd[1]: Finished Create SUID/SGID Wrappers.2063machine # [ 41.842501] systemd[1]: Starting linger-users.service...2064machine # [ 41.869454] systemd[1]: linger-users.service: Deactivated successfully.2065machine # [ 41.870961] systemd[1]: Finished linger-users.service.2066machine # [ 42.127700] nixos[3581]: finished switching to system configuration /nix/store/g8pkh9x0w8hfl3ylrfzm8nilylf98gmw-nixos-system-machine-test2067machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 1.58 seconds)2068machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2069machine # [ 42.177773] sudo[3731]: 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.service2070machine # [ 42.192521] sudo[3731]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2071machine # [ 42.200214] sudo[3731]: pam_unix(sudo:session): session closed for user usertest2072machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2073machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2074machine # [ 42.220937] sudo[3737]: 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-owner2075machine # [ 42.228384] sudo[3737]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2076machine # [ 42.235570] sudo[3737]: pam_unix(sudo:session): session closed for user usertest2077machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2078machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&12079machine # [ 42.425647] nixos[3746]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2080machine # [ 42.427800] systemd[1]: Stopped target Remote File Systems.2081machine # [ 42.429648] systemd[1]: Stopped target Local File Systems.2082machine # [ 42.641971] systemd[1]: Reload requested from client PID 3746 ('.switch-to-conf') (unit backdoor.service)...2083machine # [ 42.643619] systemd[1]: Reloading...2084machine # [ 42.876217] systemd[1]: Reloading finished in 230 ms.2085machine # [ 42.938904] systemd[621]: Stopped migrated.service.2086machine # [ 42.940333] systemd[621]: Reexecution requested from client PID 3815 ('switch-to-confi')...2087machine # [ 42.942800] systemd[621]: Reexecuting.2088machine # [ 42.964205] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2089machine # [ 43.036496] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2090machine # [ 43.042552] systemd[621]: Reload requested from client PID 3815 ('switch-to-confi')...2091machine # [ 43.043850] systemd[621]: Reloading...2092machine # [ 43.114628] systemd[621]: Reloading finished in 69 ms.2093machine # [ 43.119821] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2094machine # [ 43.126958] systemd[621]: Stopped Run user-specific NixOS activation.2095machine # [ 43.127875] systemd[621]: Stopping Run user-specific NixOS activation...2096machine # [ 43.129593] systemd[621]: Starting Run user-specific NixOS activation...2097machine # [ 43.151830] systemd[621]: Finished Run user-specific NixOS activation.2098machine # [ 43.154983] systemd[1]: Stopped target Reactivate sysinit units.2099machine # [ 43.156577] systemd[1]: Stopping Reactivate sysinit units...2100machine # [ 43.157519] systemd[1]: Reached target Reactivate sysinit units.2101machine # [ 43.160876] systemd[1]: Reached target Local File Systems.2102machine # [ 43.163353] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2103machine # [ 43.165571] systemd[1]: Starting Create SUID/SGID Wrappers...2104machine # [ 43.167417] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2105machine # [ 43.168635] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672106machine # [ 43.170726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2107machine # [ 43.174392] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2108machine # [ 43.176267] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2109machine # [ 43.178571] systemd[1]: Reached target Remote File Systems.2110machine # [ 43.180941] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2111machine # [ 43.182181] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2112machine # [ 43.314767] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2113machine # [ 43.316626] systemd[1]: Finished Create SUID/SGID Wrappers.2114machine # [ 43.320566] systemd[1]: Starting linger-users.service...2115machine # [ 43.346633] systemd[1]: linger-users.service: Deactivated successfully.2116machine # [ 43.348108] systemd[1]: Finished linger-users.service.2117machine # [ 43.605735] nixos[3746]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2118machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.38 seconds)2119machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2120machine # [ 43.652649] sudo[3883]: 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.service2121machine # [ 43.666787] sudo[3883]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2122machine # [ 43.678058] sudo[3883]: pam_unix(sudo:session): session closed for user usertest2123machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2124machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2125machine # [ 43.698304] sudo[3889]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2126machine # [ 43.705624] sudo[3889]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2127machine # [ 43.711369] sudo[3889]: pam_unix(sudo:session): session closed for user usertest2128machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.03 seconds)2129machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service2130machine # [ 43.731345] sudo[3895]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service2131machine # [ 43.738978] sudo[3895]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2132machine # [ 43.744710] sudo[3895]: pam_unix(sudo:session): session closed for user usertest2133machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)2134machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2135machine # [ 43.764312] sudo[3901]: 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.service2136machine # [ 43.771974] sudo[3901]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2137machine # [ 43.777634] sudo[3901]: pam_unix(sudo:session): session closed for user usertest2138machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)2139machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2140machine # [ 43.801319] sudo[3907]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2141machine # [ 43.809471] sudo[3907]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2142machine # [ 43.815112] systemd[621]: Reload requested from client PID 3912 ('systemctl')...2143machine # [ 43.816304] systemd[621]: Reloading...2144machine # [ 43.893259] systemd[621]: Reloading finished in 74 ms.2145machine # [ 43.897828] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2146machine # [ 43.904772] sudo[3907]: pam_unix(sudo:session): session closed for user usertest2147machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.13 seconds)2148machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2149machine # [ 43.926957] sudo[3917]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2150machine # [ 43.934564] sudo[3917]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2151machine # [ 43.943918] systemd[621]: Starting migrated.service...2152machine # [ 43.959292] systemd[621]: Finished migrated.service.2153machine # [ 43.962146] sudo[3917]: pam_unix(sudo:session): session closed for user usertest2154machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2155machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2156machine # [ 43.982456] sudo[3925]: 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.service2157machine # [ 43.990301] sudo[3925]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2158machine # [ 43.996650] sudo[3925]: pam_unix(sudo:session): session closed for user usertest2159machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)2160machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2161machine # [ 44.016574] sudo[3931]: 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-owner2162machine # [ 44.023967] sudo[3931]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2163machine # [ 44.029688] sudo[3931]: pam_unix(sudo:session): session closed for user usertest2164machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2165machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2166machine # [ 44.049258] sudo[3937]: 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.service2167machine # [ 44.056701] sudo[3937]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2168machine # [ 44.067520] sudo[3937]: pam_unix(sudo:session): session closed for user usertest2169machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2170machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12171machine # [ 44.257061] nixos[3946]: switching to system configuration /nix/store/0b8x0kinzbhfn9b0zk2hsyzw4q0bnh60-nixos-system-machine-test2172machine # [ 44.259446] systemd[1]: Stopped target Local File Systems.2173machine # [ 44.261240] systemd[1]: Stopped target Remote File Systems.2174machine # [ 44.484235] systemd[1]: Reload requested from client PID 3946 ('.switch-to-conf') (unit backdoor.service)...2175machine # [ 44.485869] systemd[1]: Reloading...2176machine # [ 44.717938] systemd[1]: Reloading finished in 230 ms.2177machine # [ 44.786508] systemd[621]: Reexecution requested from client PID 4015 ('switch-to-confi')...2178machine # [ 44.789123] systemd[621]: Reexecuting.2179machine # [ 44.810054] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2180machine # [ 44.884359] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2181machine # [ 44.889528] systemd[621]: Reload requested from client PID 4015 ('switch-to-confi')...2182machine # [ 44.890892] systemd[621]: Reloading...2183machine # [ 44.961513] systemd[621]: Reloading finished in 69 ms.2184machine # [ 44.966806] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2185machine # [ 44.973441] systemd[621]: Stopped Run user-specific NixOS activation.2186machine # [ 44.974339] systemd[621]: Stopping Run user-specific NixOS activation...2187machine # [ 44.976477] systemd[621]: Starting Run user-specific NixOS activation...2188machine # [ 44.999497] systemd[621]: Reload requested from client PID 4027 ('systemctl') (unit nixos-activation.service)...2189machine # [ 45.001279] systemd[621]: Reloading...2190machine # [ 45.074379] systemd[621]: Reloading finished in 71 ms.2191machine # [ 45.079990] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2192machine # [ 45.090204] systemd[621]: Finished Run user-specific NixOS activation.2193machine # [ 45.092368] systemd[621]: Reload requested from client PID 4015 ('switch-to-confi')...2194machine # [ 45.093664] systemd[621]: Reloading...2195machine # [ 45.165335] systemd[621]: Reloading finished in 70 ms.2196machine # [ 45.170847] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2197machine # [ 45.179719] systemd[1]: Stopped target Reactivate sysinit units.2198machine # [ 45.180897] systemd[1]: Stopping Reactivate sysinit units...2199machine # [ 45.181941] systemd[1]: Reached target Reactivate sysinit units.2200machine # [ 45.184033] systemd[1]: Reached target Local File Systems.2201machine # [ 45.186114] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2202machine # [ 45.188464] systemd[1]: Starting Create SUID/SGID Wrappers...2203machine # [ 45.190362] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2204machine # [ 45.192279] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672205machine # [ 45.194251] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2206machine # [ 45.195733] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2207machine # [ 45.196940] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2208machine # [ 45.201439] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2209machine # [ 45.202620] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2210machine # [ 45.208485] systemd[1]: Reached target Remote File Systems.2211machine # [ 45.335151] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2212machine # [ 45.336928] systemd[1]: Finished Create SUID/SGID Wrappers.2213machine # [ 45.340530] systemd[1]: Starting linger-users.service...2214machine # [ 45.368198] systemd[1]: linger-users.service: Deactivated successfully.2215machine # [ 45.369726] systemd[1]: Finished linger-users.service.2216machine # [ 45.627436] nixos[3946]: finished switching to system configuration /nix/store/0b8x0kinzbhfn9b0zk2hsyzw4q0bnh60-nixos-system-machine-test2217machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 1.57 seconds)2218machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2219machine # [ 45.672821] sudo[4094]: 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.service2220machine # [ 45.688763] sudo[4094]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2221machine # [ 45.699754] sudo[4094]: pam_unix(sudo:session): session closed for user usertest2222machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2223machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2224machine # [ 45.720601] sudo[4100]: 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-owner2225machine # [ 45.727828] sudo[4100]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2226machine # [ 45.733491] sudo[4100]: pam_unix(sudo:session): session closed for user usertest2227machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2228machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&12229machine # [ 45.915662] nixos[4109]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2230machine # [ 45.917851] systemd[1]: Stopped target Remote File Systems.2231machine # [ 45.919523] systemd[1]: Stopped target Local File Systems.2232machine # [ 46.126505] systemd[1]: Reload requested from client PID 4109 ('.switch-to-conf') (unit backdoor.service)...2233machine # [ 46.128153] systemd[1]: Reloading...2234machine # [ 46.366317] systemd[1]: Reloading finished in 235 ms.2235machine # [ 46.430555] systemd[621]: Stopped migrated.service.2236machine # [ 46.431982] systemd[621]: Reexecution requested from client PID 4178 ('switch-to-confi')...2237machine # [ 46.433936] systemd[621]: Reexecuting.2238machine # [ 46.454980] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2239machine # [ 46.528341] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2240machine # [ 46.533404] systemd[621]: Reload requested from client PID 4178 ('switch-to-confi')...2241machine # [ 46.535695] systemd[621]: Reloading...2242machine # [ 46.604358] systemd[621]: Reloading finished in 69 ms.2243machine # [ 46.609097] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2244machine # [ 46.616300] systemd[621]: Stopped Run user-specific NixOS activation.2245machine # [ 46.618117] systemd[621]: Stopping Run user-specific NixOS activation...2246machine # [ 46.619674] systemd[621]: Starting Run user-specific NixOS activation...2247machine # [ 46.640135] systemd[621]: Finished Run user-specific NixOS activation.2248machine # [ 46.643060] systemd[1]: Stopped target Reactivate sysinit units.2249machine # [ 46.644630] systemd[1]: Stopping Reactivate sysinit units...2250machine # [ 46.645558] systemd[1]: Reached target Reactivate sysinit units.2251machine # [ 46.648057] systemd[1]: Reached target Remote File Systems.2252machine # [ 46.650532] systemd[1]: Starting linger-users.service...2253machine # [ 46.652182] systemd[1]: Reached target Local File Systems.2254machine # [ 46.657415] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2255machine # [ 46.659050] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2256machine # [ 46.665255] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2257machine # [ 46.668546] systemd[1]: Starting Create SUID/SGID Wrappers...2258machine # [ 46.670304] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2259machine # [ 46.672145] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672260machine # [ 46.674155] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2261machine # [ 46.675679] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2262machine # [ 46.676909] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2263machine # [ 46.713089] systemd[1]: linger-users.service: Deactivated successfully.2264machine # [ 46.715554] systemd[1]: Finished linger-users.service.2265machine # [ 46.820220] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2266machine # [ 46.822123] systemd[1]: Finished Create SUID/SGID Wrappers.2267machine # [ 47.080372] nixos[4109]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2268machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds)2269machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2270machine # [ 47.126577] sudo[4246]: 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.service2271machine # [ 47.141585] sudo[4246]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2272machine # [ 47.150085] sudo[4246]: pam_unix(sudo:session): session closed for user usertest2273machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2274machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2275machine # [ 47.170729] sudo[4252]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2276machine # [ 47.177949] sudo[4252]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2277machine # [ 47.183568] sudo[4252]: pam_unix(sudo:session): session closed for user usertest2278machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.03 seconds)2279machine: must succeed: sudo -u usertest cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service ~usertest/.local/share/systemd/user/migrated.service2280machine # [ 47.203437] sudo[4258]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2281machine # [ 47.211275] sudo[4258]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2282machine # [ 47.217131] sudo[4258]: pam_unix(sudo:session): session closed for user usertest2283machine: (finished: must succeed: sudo -u usertest cp /nix/store/di777lvvzjra3wvdfd1d5vn7idzslns8-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.03 seconds)2284machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2285machine # [ 47.236441] sudo[4264]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2286machine # [ 47.243760] sudo[4264]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2287machine # [ 47.249513] systemd[621]: Reload requested from client PID 4269 ('systemctl')...2288machine # [ 47.250918] systemd[621]: Reloading...2289machine # [ 47.323666] systemd[621]: Reloading finished in 71 ms.2290machine # [ 47.328806] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2291machine # [ 47.336369] sudo[4264]: pam_unix(sudo:session): session closed for user usertest2292machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2293machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2294machine # [ 47.357415] sudo[4274]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2295machine # [ 47.364962] sudo[4274]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2296machine # [ 47.375436] systemd[621]: Starting migrated.service...2297machine # [ 47.391057] systemd[621]: Finished migrated.service.2298machine # [ 47.393928] sudo[4274]: pam_unix(sudo:session): session closed for user usertest2299machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2300machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2301machine # [ 47.413799] sudo[4282]: 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.service2302machine # [ 47.423470] sudo[4282]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2303machine # [ 47.430540] sudo[4282]: pam_unix(sudo:session): session closed for user usertest2304machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.04 seconds)2305machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2306machine # [ 47.449944] sudo[4288]: 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-owner2307machine # [ 47.457310] sudo[4288]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2308machine # [ 47.462892] sudo[4288]: pam_unix(sudo:session): session closed for user usertest2309machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2310machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2311machine # [ 47.481983] sudo[4294]: 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.service2312machine # [ 47.489540] sudo[4294]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2313machine # [ 47.504354] sudo[4294]: pam_unix(sudo:session): session closed for user usertest2314machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2315machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12316machine # [ 47.684377] nixos[4303]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2317machine # [ 47.687720] systemd[1]: Stopped target Remote File Systems.2318machine # [ 47.688787] systemd[1]: Stopped target Local File Systems.2319machine # [ 47.909904] systemd[1]: Reload requested from client PID 4303 ('.switch-to-conf') (unit backdoor.service)...2320machine # [ 47.911587] systemd[1]: Reloading...2321machine # [ 48.141076] systemd[1]: Reloading finished in 227 ms.2322machine # [ 48.206107] systemd[621]: Reexecution requested from client PID 4372 ('switch-to-confi')...2323machine # [ 48.207327] systemd[621]: Reexecuting.2324machine # [ 48.226965] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2325machine # [ 48.293101] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2326machine # [ 48.298440] systemd[621]: Reload requested from client PID 4372 ('switch-to-confi')...2327machine # [ 48.300241] systemd[621]: Reloading...2328machine # [ 48.373088] systemd[621]: Reloading finished in 71 ms.2329machine # [ 48.376832] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2330machine # [ 48.383309] systemd[621]: Stopped Run user-specific NixOS activation.2331machine # [ 48.385098] systemd[621]: Stopping Run user-specific NixOS activation...2332machine # [ 48.387341] systemd[621]: Starting Run user-specific NixOS activation...2333machine # [ 48.406649] systemd[621]: Finished Run user-specific NixOS activation.2334machine # [ 48.408978] systemd[621]: Reload requested from client PID 4372 ('switch-to-confi')...2335machine # [ 48.411103] systemd[621]: Reloading...2336machine # [ 48.483129] systemd[621]: Reloading finished in 72 ms.2337machine # [ 48.488864] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2338machine # [ 48.494508] systemd[621]: Stopped migrated.service.2339machine # [ 48.495342] systemd[621]: Stopping migrated.service...2340machine # [ 48.497664] systemd[621]: Starting migrated.service...2341machine # [ 48.513451] systemd[621]: Finished migrated.service.2342machine # [ 48.518857] systemd[1]: Stopped target Reactivate sysinit units.2343machine # [ 48.520310] systemd[1]: Stopping Reactivate sysinit units...2344machine # [ 48.521605] systemd[1]: Reached target Reactivate sysinit units.2345machine # [ 48.527511] systemd[1]: Reached target Local File Systems.2346machine # [ 48.529173] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2347machine # [ 48.532167] systemd[1]: Starting Create SUID/SGID Wrappers...2348machine # [ 48.534326] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2349machine # [ 48.535688] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672350machine # [ 48.537726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2351machine # [ 48.539225] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2352machine # [ 48.540440] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2353machine # [ 48.547202] systemd[1]: Reached target Remote File Systems.2354machine # [ 48.550632] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2355machine # [ 48.552565] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2356machine # [ 48.675582] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2357machine # [ 48.677268] systemd[1]: Finished Create SUID/SGID Wrappers.2358machine # [ 48.680993] systemd[1]: Starting linger-users.service...2359machine # [ 48.709152] systemd[1]: linger-users.service: Deactivated successfully.2360machine # [ 48.711269] systemd[1]: Finished linger-users.service.2361machine # [ 48.968350] nixos[4303]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2362machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.47 seconds)2363machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2364machine # [ 49.013595] sudo[4446]: 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.service2365machine # [ 49.027597] sudo[4446]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2366machine # [ 49.039140] sudo[4446]: pam_unix(sudo:session): session closed for user usertest2367machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2368machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2369machine # [ 49.059166] sudo[4452]: 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.service2370machine # [ 49.066703] sudo[4452]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2371machine # [ 49.079391] sudo[4452]: pam_unix(sudo:session): session closed for user usertest2372machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2373machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2374machine # [ 49.099278] sudo[4458]: 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-owner2375machine # [ 49.106895] sudo[4458]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2376machine # [ 49.112415] sudo[4458]: pam_unix(sudo:session): session closed for user usertest2377machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2378machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12379machine # [ 49.294321] nixos[4467]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2380machine # [ 49.297495] systemd[1]: Stopped target Remote File Systems.2381machine # [ 49.298574] systemd[1]: Stopped target Local File Systems.2382machine # [ 49.501519] systemd[1]: Reload requested from client PID 4467 ('.switch-to-conf') (unit backdoor.service)...2383machine # [ 49.503266] systemd[1]: Reloading...2384machine # [ 49.735565] systemd[1]: Reloading finished in 230 ms.2385machine # [ 49.807280] systemd[621]: Reexecution requested from client PID 4536 ('switch-to-confi')...2386machine # [ 49.808610] systemd[621]: Reexecuting.2387machine # [ 49.830190] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2388machine # [ 49.901083] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2389machine # [ 49.906372] systemd[621]: Reload requested from client PID 4536 ('switch-to-confi')...2390machine # [ 49.908197] systemd[621]: Reloading...2391machine # [ 49.980061] systemd[621]: Reloading finished in 70 ms.2392machine # [ 49.985867] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2393machine # [ 49.992052] systemd[621]: Stopped Run user-specific NixOS activation.2394machine # [ 49.993387] systemd[621]: Stopping Run user-specific NixOS activation...2395machine # [ 49.995179] systemd[621]: Starting Run user-specific NixOS activation...2396machine # [ 50.014095] systemd[621]: Finished Run user-specific NixOS activation.2397machine # [ 50.017057] systemd[1]: Stopped target Reactivate sysinit units.2398machine # [ 50.018627] systemd[1]: Stopping Reactivate sysinit units...2399machine # [ 50.019790] systemd[1]: Reached target Reactivate sysinit units.2400machine # [ 50.022416] systemd[1]: Reached target Remote File Systems.2401machine # [ 50.025591] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2402machine # [ 50.027303] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2403machine # [ 50.029411] systemd[1]: Reached target Local File Systems.2404machine # [ 50.033177] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2405machine # [ 50.037318] systemd[1]: Starting Create SUID/SGID Wrappers...2406machine # [ 50.038172] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2407machine # [ 50.040264] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672408machine # [ 50.043234] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2409machine # [ 50.045383] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2410machine # [ 50.046592] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2411machine # [ 50.171882] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2412machine # [ 50.174631] systemd[1]: Finished Create SUID/SGID Wrappers.2413machine # [ 50.177717] systemd[1]: Starting linger-users.service...2414machine # [ 50.204383] systemd[1]: linger-users.service: Deactivated successfully.2415machine # [ 50.206079] systemd[1]: Finished linger-users.service.2416machine # [ 50.463372] nixos[4467]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2417machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.36 seconds)2418machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2419machine # [ 50.511316] sudo[4604]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2420machine # [ 50.525914] sudo[4604]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2421machine # [ 50.535501] sudo[4604]: pam_unix(sudo:session): session closed for user usertest2422machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.07 seconds)2423machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2424machine # [ 50.555294] sudo[4610]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2425machine # [ 50.562909] sudo[4610]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2426machine # [ 50.568409] systemd[621]: Reload requested from client PID 4615 ('systemctl')...2427machine # [ 50.569595] systemd[621]: Reloading...2428machine # [ 50.642212] systemd[621]: Reloading finished in 70 ms.2429machine # [ 50.647826] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2430machine # [ 50.656545] sudo[4610]: pam_unix(sudo:session): session closed for user usertest2431machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2432machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&12433machine # [ 50.833900] nixos[4623]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2434machine # [ 50.836424] systemd[1]: Stopped target Remote File Systems.2435machine # [ 50.837816] systemd[1]: Stopped target Local File Systems.2436machine # [ 51.037673] systemd[1]: Reload requested from client PID 4623 ('.switch-to-conf') (unit backdoor.service)...2437machine # [ 51.039326] systemd[1]: Reloading...2438machine # [ 51.264829] systemd[1]: Reloading finished in 223 ms.2439machine # [ 51.324955] systemd[621]: Stopped migrated.service.2440machine # [ 51.327294] systemd[621]: Reexecution requested from client PID 4692 ('switch-to-confi')...2441machine # [ 51.328793] systemd[621]: Reexecuting.2442machine # [ 51.348037] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2443machine # [ 51.418292] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2444machine # [ 51.423917] systemd[621]: Reload requested from client PID 4692 ('switch-to-confi')...2445machine # [ 51.425553] systemd[621]: Reloading...2446machine # [ 51.498195] systemd[621]: Reloading finished in 71 ms.2447machine # [ 51.503808] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2448machine # [ 51.511066] systemd[621]: Stopped Run user-specific NixOS activation.2449machine # [ 51.511956] systemd[621]: Stopping Run user-specific NixOS activation...2450machine # [ 51.513773] systemd[621]: Starting Run user-specific NixOS activation...2451machine # [ 51.533246] systemd[621]: Finished Run user-specific NixOS activation.2452machine # [ 51.536722] systemd[1]: Stopped target Reactivate sysinit units.2453machine # [ 51.537710] systemd[1]: Stopping Reactivate sysinit units...2454machine # [ 51.538806] systemd[1]: Reached target Reactivate sysinit units.2455machine # [ 51.539948] systemd[1]: Reached target Local File Systems.2456machine # [ 51.543239] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2457machine # [ 51.546574] systemd[1]: Starting Create SUID/SGID Wrappers...2458machine # [ 51.548171] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2459machine # [ 51.549385] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672460machine # [ 51.551343] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2461machine # [ 51.552796] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2462machine # [ 51.556515] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2463machine # [ 51.559903] systemd[1]: Reached target Remote File Systems.2464machine # [ 51.569427] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2465machine # [ 51.570789] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2466machine # [ 51.688961] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2467machine # [ 51.690577] systemd[1]: Finished Create SUID/SGID Wrappers.2468machine # [ 51.694285] systemd[1]: Starting linger-users.service...2469machine # [ 51.721394] systemd[1]: linger-users.service: Deactivated successfully.2470machine # [ 51.722809] systemd[1]: Finished linger-users.service.2471machine # [ 51.980193] nixos[4623]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2472machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds)2473machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2474machine # [ 52.027572] sudo[4760]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2475machine # [ 52.041515] sudo[4760]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2476machine # [ 52.048076] sudo[4760]: pam_unix(sudo:session): session closed for user usertest2477machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)2478machine: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service2479machine # [ 52.067790] sudo[4766]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service /home/usertest/.config/systemd/user/migrated.service2480machine # [ 52.075543] sudo[4766]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2481machine # [ 52.081413] sudo[4766]: pam_unix(sudo:session): session closed for user usertest2482machine: (finished: must succeed: sudo -u usertest cp /nix/store/8dimk3fw4hz62png5xv882x5lln9rvpa-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.03 seconds)2483machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2484machine # [ 52.101259] sudo[4772]: 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.service2485machine # [ 52.108851] sudo[4772]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2486machine # [ 52.114502] sudo[4772]: pam_unix(sudo:session): session closed for user usertest2487machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.03 seconds)2488machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2489machine # [ 52.133988] sudo[4778]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2490machine # [ 52.141305] sudo[4778]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2491machine # [ 52.146995] systemd[621]: Reload requested from client PID 4783 ('systemctl')...2492machine # [ 52.148475] systemd[621]: Reloading...2493machine # [ 52.221442] systemd[621]: Reloading finished in 71 ms.2494machine # [ 52.226812] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2495machine # [ 52.234267] sudo[4778]: pam_unix(sudo:session): session closed for user usertest2496machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2497machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2498machine # [ 52.254850] sudo[4788]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2499machine # [ 52.262299] sudo[4788]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2500machine # [ 52.271296] systemd[621]: Starting migrated.service...2501machine # [ 52.287950] systemd[621]: Finished migrated.service.2502machine # [ 52.290794] sudo[4788]: pam_unix(sudo:session): session closed for user usertest2503machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.06 seconds)2504machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2505machine # [ 52.310642] sudo[4796]: 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.service2506machine # [ 52.317979] sudo[4796]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2507machine # [ 52.324772] sudo[4796]: pam_unix(sudo:session): session closed for user usertest2508machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.03 seconds)2509machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2510machine # [ 52.344431] sudo[4802]: 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-owner2511machine # [ 52.351822] sudo[4802]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2512machine # [ 52.357386] sudo[4802]: pam_unix(sudo:session): session closed for user usertest2513machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2514machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2515machine # [ 52.376792] sudo[4808]: 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.service2516machine # [ 52.384329] sudo[4808]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2517machine # [ 52.395445] sudo[4808]: pam_unix(sudo:session): session closed for user usertest2518machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.04 seconds)2519machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12520machine # [ 52.572865] nixos[4817]: switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2521machine # [ 52.576496] systemd[1]: Stopped target Local File Systems.2522machine # [ 52.577618] systemd[1]: Stopped target Remote File Systems.2523machine # [ 52.776928] systemd[1]: Reload requested from client PID 4817 ('.switch-to-conf') (unit backdoor.service)...2524machine # [ 52.778644] systemd[1]: Reloading...2525machine # [ 53.029778] systemd[1]: Reloading finished in 249 ms.2526machine # [ 53.088863] systemd[621]: Reexecution requested from client PID 4886 ('switch-to-confi')...2527machine # [ 53.091355] systemd[621]: Reexecuting.2528machine # [ 53.111919] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2529machine # [ 53.186260] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2530machine # [ 53.191436] systemd[621]: Reload requested from client PID 4886 ('switch-to-confi')...2531machine # [ 53.192690] systemd[621]: Reloading...2532machine # [ 53.265659] systemd[621]: Reloading finished in 71 ms.2533machine # [ 53.270814] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2534machine # [ 53.276937] systemd[621]: Stopped Run user-specific NixOS activation.2535machine # [ 53.278058] systemd[621]: Stopping Run user-specific NixOS activation...2536machine # [ 53.280332] systemd[621]: Starting Run user-specific NixOS activation...2537machine # [ 53.298983] systemd[621]: Finished Run user-specific NixOS activation.2538machine # [ 53.300600] systemd[621]: Reload requested from client PID 4886 ('switch-to-confi')...2539machine # [ 53.302325] systemd[621]: Reloading...2540machine # [ 53.372730] systemd[621]: Reloading finished in 69 ms.2541machine # [ 53.377830] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2542machine # [ 53.386559] systemd[1]: Stopped target Reactivate sysinit units.2543machine # [ 53.387589] systemd[1]: Stopping Reactivate sysinit units...2544machine # [ 53.388379] systemd[1]: Reached target Reactivate sysinit units.2545machine # [ 53.391605] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2546machine # [ 53.393203] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2547machine # [ 53.395341] systemd[1]: Reached target Remote File Systems.2548machine # [ 53.396872] systemd[1]: Reached target Local File Systems.2549machine # [ 53.405078] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2550machine # [ 53.407341] systemd[1]: Starting Create SUID/SGID Wrappers...2551machine # [ 53.409199] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2552machine # [ 53.411237] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672553machine # [ 53.413964] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2554machine # [ 53.415963] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2555machine # [ 53.418101] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2556machine # [ 53.541755] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2557machine # [ 53.543934] systemd[1]: Finished Create SUID/SGID Wrappers.2558machine # [ 53.547062] systemd[1]: Starting linger-users.service...2559machine # [ 53.574308] systemd[1]: linger-users.service: Deactivated successfully.2560machine # [ 53.575751] systemd[1]: Finished linger-users.service.2561machine # [ 53.833401] nixos[4817]: finished switching to system configuration /nix/store/k8izmwrszg78l61baf9pfmhzx35qk9h9-nixos-system-machine-test2562machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 1.44 seconds)2563machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2564machine # [ 53.878880] sudo[4958]: 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.service2565machine # [ 53.893987] sudo[4958]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2566machine # [ 53.905738] sudo[4958]: pam_unix(sudo:session): session closed for user usertest2567machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2568machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2569machine # [ 53.926375] sudo[4964]: 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-owner2570machine # [ 53.933943] sudo[4964]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2571machine # [ 53.939533] sudo[4964]: pam_unix(sudo:session): session closed for user usertest2572machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.03 seconds)2573machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2574machine # [ 53.959166] sudo[4970]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2575machine # [ 53.966461] sudo[4970]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2576machine # [ 53.971767] sudo[4970]: pam_unix(sudo:session): session closed for user usertest2577machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.03 seconds)2578machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2579machine # [ 53.991317] sudo[4976]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2580machine # [ 53.998650] sudo[4976]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2581machine # [ 54.004375] systemd[621]: Reload requested from client PID 4981 ('systemctl')...2582machine # [ 54.005502] systemd[621]: Reloading...2583machine # [ 54.077762] systemd[621]: Reloading finished in 70 ms.2584machine # [ 54.081816] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2585machine # [ 54.089290] sudo[4976]: pam_unix(sudo:session): session closed for user usertest2586machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.12 seconds)2587machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2588machine # [ 54.110353] sudo[4986]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2589machine # [ 54.117738] sudo[4986]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2590machine # [ 54.125658] systemd[621]: Stopped migrated.service.2591machine # [ 54.128387] sudo[4986]: pam_unix(sudo:session): session closed for user usertest2592machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.04 seconds)2593machine: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&12594machine # [ 54.305207] nixos[4995]: switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2595machine # [ 54.307576] systemd[1]: Stopped target Local File Systems.2596machine # [ 54.309219] systemd[1]: Stopped target Remote File Systems.2597machine # [ 54.509183] systemd[1]: Reload requested from client PID 4995 ('.switch-to-conf') (unit backdoor.service)...2598machine # [ 54.510880] systemd[1]: Reloading...2599machine # [ 54.734672] systemd[1]: Reloading finished in 222 ms.2600machine # [ 54.796749] systemd[621]: Reexecution requested from client PID 5064 ('switch-to-confi')...2601machine # [ 54.798060] systemd[621]: Reexecuting.2602machine # [ 54.819140] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2603machine # [ 54.889867] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2604machine # [ 54.895942] systemd[621]: Reload requested from client PID 5064 ('switch-to-confi')...2605machine # [ 54.897803] systemd[621]: Reloading...2606machine # [ 54.969928] systemd[621]: Reloading finished in 71 ms.2607machine # [ 54.975810] systemd[621]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2608machine # [ 54.982254] systemd[621]: Stopped Run user-specific NixOS activation.2609machine # [ 54.983109] systemd[621]: Stopping Run user-specific NixOS activation...2610machine # [ 54.985230] systemd[621]: Starting Run user-specific NixOS activation...2611machine # [ 55.003986] systemd[621]: Finished Run user-specific NixOS activation.2612machine # [ 55.006946] systemd[1]: Stopped target Reactivate sysinit units.2613machine # [ 55.008141] systemd[1]: Stopping Reactivate sysinit units...2614machine # [ 55.009246] systemd[1]: Reached target Reactivate sysinit units.2615machine # [ 55.012056] systemd[1]: Reached target Local File Systems.2616machine # [ 55.014308] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2617machine # [ 55.017067] systemd[1]: Starting Create SUID/SGID Wrappers...2618machine # [ 55.018744] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2619machine # [ 55.021078] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672620machine # [ 55.023086] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2621machine # [ 55.024500] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2622machine # [ 55.025667] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2623machine # [ 55.031776] systemd[1]: Reached target Remote File Systems.2624machine # [ 55.040309] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2625machine # [ 55.041830] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2626machine # [ 55.160371] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2627machine # [ 55.162242] systemd[1]: Finished Create SUID/SGID Wrappers.2628machine # [ 55.165868] systemd[1]: Starting linger-users.service...2629machine # [ 55.193356] systemd[1]: linger-users.service: Deactivated successfully.2630machine # [ 55.195087] systemd[1]: Finished linger-users.service.2631machine # [ 55.452282] nixos[4995]: finished switching to system configuration /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test2632machine: (finished: must succeed: /nix/store/sjxzkir7m5pc8l94cqwh032kf0qbisji-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds)2633(finished: subtest: user services, in 36.96 seconds)2634(finished: run the VM test script, in 56.50 seconds)2635test script finished in 56.56s2636cleanup2637kill QemuMachine (pid 45)2638machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2639machine # [2026-10-19T03:01:10Z INFO virtiofsd] Client disconnected, shutting down2640machine # [2026-10-19T03:01:10Z INFO virtiofsd] Client disconnected, shutting down2641machine # [2026-10-19T03:01:10Z INFO virtiofsd] Client disconnected, shutting down2642(finished: cleanup, in 0.18 seconds)2643additionally exposed symbols:2644 machine, other,2645 vlan1,2646 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