vm-test-run-switch-test-user
checks.x86_64-linux.switchTest-user
· build #19
· 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.M00S4OzCM6', 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: e26bb448-d92e-4572-a2e9-7d1ceb981c9717machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)27machine # 28machine # 29machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0030machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...31machine # 32machine # 33machine # 34machine # 35machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0036machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...37machine # 38machine # 39machine # Booting from ROM...40machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Aug 28 06:22:54 UTC 202641machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/init regInfo=/nix/store/cn7psgydl0m8aihlnys8219lfrjzla7f-closure-info/registration console=ttyS0,115200n8 console=tty042machine # [ 0.000000] BIOS-provided physical RAM map:43machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable44machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved45machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved46machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable47machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved48machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved49machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved52machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved53machine # [ 0.000000] NX (Execute Disable) protection: active54machine # [ 0.000000] APIC: Static calls initialized55machine # [ 0.000000] SMBIOS 2.8 present.56machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201457machine # [ 0.000000] DMI: Memory slots populated: 1/158machine # [ 0.000000] Hypervisor detected: KVM59machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000060machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0061machine # [ 0.000000] kvm-clock: using sched offset of 466828225 cycles62machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns63machine # [ 0.000004] tsc: Detected 2400.010 MHz processor64machine # [ 0.000820] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000847] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000849] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002749] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002760] Using GB pages for direct mapping69machine # [ 0.002848] RAMDISK: [mem 0x3e429000-0x3ffcffff]70machine # [ 0.002857] ACPI: Early table checksum verification disabled71machine # [ 0.002859] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002863] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002867] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002873] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002875] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002877] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002879] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002880] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002882] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002883] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002884] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002885] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002885] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002886] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002886] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002887] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003114] No NUMA configuration found88machine # [ 0.003115] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003118] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.005337] Zone ranges:91machine # [ 0.005338] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.005340] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.005341] Normal empty94machine # [ 0.005341] Device empty95machine # [ 0.005342] Movable zone start for each node96machine # [ 0.005343] Early memory node ranges97machine # [ 0.005343] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.005344] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.005345] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.005364] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.005616] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.021956] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.022404] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.022414] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.022441] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.022444] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.022447] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.022448] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.022450] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.022450] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.022454] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.022455] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.022463] TSC deadline timer available114machine # [ 0.022469] CPU topo: Max. logical packages: 1115machine # [ 0.022469] CPU topo: Max. logical dies: 1116machine # [ 0.022470] CPU topo: Max. dies per package: 1117machine # [ 0.022474] CPU topo: Max. threads per core: 1118machine # [ 0.022475] CPU topo: Num. cores per package: 1119machine # [ 0.022475] CPU topo: Num. threads per package: 1120machine # [ 0.022476] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.022495] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.022571] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.022573] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.022574] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.022575] Booting paravirtualized kernel on KVM126machine # [ 0.022578] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.027019] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.029028] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.029072] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.029073] 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/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/init regInfo=/nix/store/cn7psgydl0m8aihlnys8219lfrjzla7f-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.029163] Unknown kernel command line parameters "regInfo=/nix/store/cn7psgydl0m8aihlnys8219lfrjzla7f-closure-info/registration", will be passed to user space.132machine # [ 0.029175] random: crng init done133machine # [ 0.029176] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.029197] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.029209] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.029239] Fallback order for Node 0: 0137machine # [ 0.029241] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.029242] Policy zone: DMA32139machine # [ 0.031540] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.033832] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.036047] allocated 2097152 bytes of page_ext142machine # [ 0.045403] ftrace: allocating 48733 entries in 192 pages143machine # [ 0.045406] ftrace: allocated 192 pages with 2 groups144machine # [ 0.046209] Dynamic Preempt: lazy145machine # [ 0.046345] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.046346] rcu: RCU event tracing is enabled.147machine # [ 0.046347] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.046348] Trampoline variant of Tasks RCU enabled.149machine # [ 0.046349] Rude variant of Tasks RCU enabled.150machine # [ 0.046349] Tracing variant of Tasks RCU enabled.151machine # [ 0.046350] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.046350] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.046401] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.046403] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.046403] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.050701] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.050990] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.050997] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.051100] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.054641] Console: colour VGA+ 80x25161machine # [ 0.054644] printk: legacy console [tty0] enabled162machine # [ 0.083646] printk: legacy console [ttyS0] enabled163machine # [ 0.191088] ACPI: Core revision 20250807164machine # [ 0.192216] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.194241] APIC: Switch to symmetric I/O mode setup166machine # [ 0.195613] x2apic enabled167machine # [ 0.196593] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.198708] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.199948] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns170machine # [ 0.201802] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)171machine # [ 0.203877] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.204943] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.205799] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.206803] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.207800] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.208799] Transient Scheduler Attacks: Vulnerable: No microcode177machine # [ 0.209798] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.211556] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.212600] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.213809] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.215367] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.216800] active return thunk: srso_alias_return_thunk183machine # [ 0.217811] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.218799] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.219798] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.220799] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.221799] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.222798] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.223799] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.225799] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256191machine # [ 0.226799] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64192machine # [ 0.227799] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512193machine # [ 0.228799] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024194machine # [ 0.229799] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8195machine # [ 0.230798] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.196machine # [ 0.264661] Freeing SMP alternatives memory: 44K197machine # [ 0.265391] pid_max: default: 32768 minimum: 301198machine # [ 0.265897] LSM: initializing lsm=capability,landlock,yama,bpf,ima199machine # [ 0.266901] landlock: Up and running.200machine # [ 0.267799] Yama: becoming mindful.201machine # [ 0.269020] LSM support for eBPF active202machine # [ 0.269912] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)203machine # [ 0.270817] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204machine # [ 0.273490] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)205machine # [ 0.274318] Performance Events: Fam17h+ core perfctr, AMD PMU driver.206machine # [ 0.274804] ... version: 2207machine # [ 0.275587] ... bit width: 48208machine # [ 0.275801] ... generic counters: 6209machine # [ 0.276560] ... generic bitmap: 000000000000003f210machine # [ 0.276813] ... fixed-purpose counters: 0211machine # [ 0.277544] ... fixed-purpose bitmap: 0000000000000000212machine # [ 0.277800] ... value mask: 0000ffffffffffff213machine # [ 0.278721] ... max period: 00007fffffffffff214machine # [ 0.279560] ... global_ctrl mask: 000000000000003f215machine # [ 0.279912] signal: max sigframe size: 3376216machine # [ 0.280744] rcu: Hierarchical SRCU implementation.217machine # [ 0.281475] rcu: Max phase no-delay instances is 400.218machine # [ 0.286304] smp: Bringing up secondary CPUs ...219machine # [ 0.286818] smp: Brought up 1 node, 1 CPU220machine # [ 0.287584] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)221machine # [ 0.288884] Memory: 941784K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 98840K reserved, 0K cma-reserved)222machine # [ 0.290026] devtmpfs: initialized223machine # [ 0.290876] x86/mm: Memory block size: 128MB224machine # [ 0.292513] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)225machine # [ 0.293792] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).226machine # [ 0.294877] pinctrl core: initialized pinctrl subsystem227machine # [ 0.296089] PM: RTC time: 02:41:39, date: 2026-09-03228machine # [ 0.299432] NET: Registered PF_NETLINK/PF_ROUTE protocol family229machine # [ 0.300143] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations230machine # [ 0.300818] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations231machine # [ 0.302071] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations232machine # [ 0.302811] audit: initializing netlink subsys (disabled)233machine # [ 0.303802] audit: type=2000 audit(1788403300.077:1): state=initialized audit_enabled=0 res=1234machine # [ 0.304987] thermal_sys: Registered thermal governor 'fair_share'235machine # [ 0.304989] thermal_sys: Registered thermal governor 'bang_bang'236machine # [ 0.305802] thermal_sys: Registered thermal governor 'step_wise'237machine # [ 0.306802] thermal_sys: Registered thermal governor 'user_space'238machine # [ 0.307786] thermal_sys: Registered thermal governor 'power_allocator'239machine # [ 0.308602] cpuidle: using governor menu240machine # [ 0.310882] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5241machine # [ 0.312037] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]242machine # [ 0.312805] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry243machine # [ 0.313813] PCI: Using configuration type 1 for base access244machine # [ 0.314906] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.245machine # [ 0.322041] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages246machine # [ 0.322802] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page247machine # [ 0.327802] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages248machine # [ 0.328801] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page249machine # [ 0.339011] ACPI: Added _OSI(Module Device)250machine # [ 0.339783] ACPI: Added _OSI(Processor Device)251machine # [ 0.340372] ACPI: Added _OSI(Processor Aggregator Device)252machine # [ 0.347371] ACPI: 1 ACPI AML tables successfully acquired and loaded253machine # [ 0.351210] ACPI: Interpreter enabled254machine # [ 0.351816] ACPI: PM: (supports S0 S3 S4 S5)255machine # [ 0.352570] ACPI: Using IOAPIC for interrupt routing256machine # [ 0.352848] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug257machine # [ 0.353802] PCI: Using E820 reservations for host bridge windows258machine # [ 0.356938] ACPI: Enabled 2 GPEs in block 00 to 3F259machine # [ 0.365614] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])260machine # [ 0.366565] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]261machine # [ 0.368455] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]262machine # [ 0.368922] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]263machine # [ 0.370162] PCI host bridge to bus 0000:00264machine # [ 0.370806] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]265machine # [ 0.371802] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]266machine # [ 0.372801] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]267machine # [ 0.373801] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]268machine # [ 0.374801] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]269machine # [ 0.375802] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]270machine # [ 0.376802] pci_bus 0000:00: root bus resource [bus 00-ff]271machine # [ 0.377825] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint272machine # [ 0.379240] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint273machine # [ 0.381845] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]274machine # [ 0.382813] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]275machine # [ 0.383811] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]276machine # [ 0.385102] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]277machine # [ 0.386860] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint278machine # [ 0.389792] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]279machine # [ 0.390720] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]280machine # [ 0.391611] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]281machine # [ 0.392719] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]282machine # [ 0.394195] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint283machine # [ 0.396836] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]284machine # [ 0.397746] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]285machine # [ 0.398620] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]286machine # [ 0.400251] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287machine # [ 0.401835] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]288machine # [ 0.402755] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]289machine # [ 0.403649] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]290machine # [ 0.405344] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.406809] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]292machine # [ 0.407684] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]293machine # [ 0.408572] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]294machine # [ 0.410259] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.412837] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]296machine # [ 0.413787] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]297machine # [ 0.414662] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]298machine # [ 0.416258] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint299machine # [ 0.417834] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]300machine # [ 0.418746] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]301machine # [ 0.419605] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]302machine # [ 0.421406] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint303machine # [ 0.422809] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]304machine # [ 0.423709] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]305machine # [ 0.424579] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]306machine # [ 0.425702] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]307machine # [ 0.427205] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint308machine # [ 0.428813] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]309machine # [ 0.429823] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]310machine # [ 0.431388] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint311machine # [ 0.432845] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]312machine # [ 0.433747] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]313machine # [ 0.434600] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]314machine # [ 0.436341] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint315machine # [ 0.437809] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]316machine # [ 0.438707] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]317machine # [ 0.439641] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]318machine # [ 0.441319] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint319machine # [ 0.442435] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]320machine # [ 0.443026] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint321machine # [ 0.444515] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]322machine # [ 0.444999] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.447467] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]324machine # [ 0.448029] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint325machine # [ 0.449384] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]326machine # [ 0.450065] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint327machine # [ 0.451099] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO328machine # [ 0.452082] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint329machine # [ 0.453829] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]330machine # [ 0.454734] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]331machine # [ 0.455933] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint332machine # [ 0.457495] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]333machine # [ 0.460772] ACPI: PCI: Interrupt link LNKA configured for IRQ 10334machine # [ 0.461721] ACPI: PCI: Interrupt link LNKB configured for IRQ 10335machine # [ 0.462697] ACPI: PCI: Interrupt link LNKC configured for IRQ 11336machine # [ 0.463703] ACPI: PCI: Interrupt link LNKD configured for IRQ 11337machine # [ 0.464691] ACPI: PCI: Interrupt link LNKE configured for IRQ 10338machine # [ 0.465899] ACPI: PCI: Interrupt link LNKF configured for IRQ 10339machine # [ 0.466900] ACPI: PCI: Interrupt link LNKG configured for IRQ 11340machine # [ 0.467909] ACPI: PCI: Interrupt link LNKH configured for IRQ 11341machine # [ 0.468840] ACPI: PCI: Interrupt link GSIA configured for IRQ 16342machine # [ 0.469814] ACPI: PCI: Interrupt link GSIB configured for IRQ 17343machine # [ 0.470816] ACPI: PCI: Interrupt link GSIC configured for IRQ 18344machine # [ 0.471816] ACPI: PCI: Interrupt link GSID configured for IRQ 19345machine # [ 0.472814] ACPI: PCI: Interrupt link GSIE configured for IRQ 20346machine # [ 0.473816] ACPI: PCI: Interrupt link GSIF configured for IRQ 21347machine # [ 0.474813] ACPI: PCI: Interrupt link GSIG configured for IRQ 22348machine # [ 0.475816] ACPI: PCI: Interrupt link GSIH configured for IRQ 23349machine # [ 0.477833] iommu: Default domain type: Translated350machine # [ 0.478678] iommu: DMA domain TLB invalidation policy: lazy mode351machine # [ 0.479861] ACPI: bus type USB registered352machine # [ 0.480629] usbcore: registered new interface driver usbfs353machine # [ 0.481532] usbcore: registered new interface driver hub354machine # [ 0.481817] usbcore: registered new device driver usb355machine # [ 0.483604] NetLabel: Initializing356machine # [ 0.483820] NetLabel: domain hash size = 128357machine # [ 0.484709] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO358machine # [ 0.485620] NetLabel: unlabeled traffic allowed by default359machine # [ 0.486540] PCI: Using ACPI for IRQ routing360machine # [ 0.531004] pci 0000:00:01.0: vgaarb: setting as boot VGA device361machine # [ 0.531797] pci 0000:00:01.0: vgaarb: bridge control possible362machine # [ 0.531797] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none363machine # [ 0.531807] vgaarb: loaded364machine # [ 0.532538] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0365machine # [ 0.532801] hpet0: 3 comparators, 64-bit 100.000000 MHz counter366machine # [ 0.537894] clocksource: Switched to clocksource kvm-clock367machine # [ 0.539621] VFS: Disk quotas dquot_6.6.0368machine # [ 0.542045] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)369machine # [ 0.543405] pnp: PnP ACPI init370machine # [ 0.544244] ACPI: IRQ 4 override to edge(!), high(!)371machine # [ 0.545290] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved372machine # [ 0.546922] pnp: PnP ACPI: found 6 devices373machine # [ 0.554426] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns374machine # [ 0.555964] clocksource: Switched to clocksource acpi_pm375machine # [ 0.556979] NET: Registered PF_INET protocol family376machine # [ 0.558047] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)377machine # [ 0.574459] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)378machine # [ 0.576042] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)379machine # [ 0.577421] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)380machine # [ 0.578837] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)381machine # [ 0.580406] TCP: Hash tables configured (established 8192 bind 8192)382machine # [ 0.581666] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)383machine # [ 0.583026] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)384machine # [ 0.584186] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)385machine # [ 0.585494] NET: Registered PF_UNIX/PF_LOCAL protocol family386machine # [ 0.586572] NET: Registered PF_XDP protocol family387machine # [ 0.587480] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]388machine # [ 0.588554] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]389machine # [ 0.589616] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]390machine # [ 0.590744] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]391machine # [ 0.591935] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]392machine # [ 0.593098] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]393machine # [ 0.595015] ACPI: \_SB_.GSIA: Enabled at IRQ 16394machine # [ 0.597632] ACPI: \_SB_.GSIB: Enabled at IRQ 17395machine # [ 0.599687] ACPI: \_SB_.GSIC: Enabled at IRQ 18396machine # [ 0.601608] ACPI: \_SB_.GSID: Enabled at IRQ 19397machine # [ 0.603274] PCI: CLS 0 bytes, default 64398machine # [ 0.604244] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns399machine # [ 0.606168] Trying to unpack rootfs image as initramfs...400machine # [ 0.652079] Initialise system trusted keyrings401machine # [ 0.654876] workingset: timestamp_bits=40 max_order=18 bucket_order=0402machine # [ 0.676940] Key type asymmetric registered403machine # [ 0.677660] Asymmetric key parser 'x509' registered404machine # [ 0.678568] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)405machine # [ 0.684007] io scheduler mq-deadline registered406machine # [ 0.684740] io scheduler kyber registered407machine # [ 0.688487] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled408machine # [ 0.691862] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A409machine # [ 0.695305] Linux agpgart interface v0.103410machine # [ 0.696133] ACPI: bus type drm_connector registered411machine # [ 0.699341] usbcore: registered new interface driver usbserial_generic412machine # [ 0.700464] usbserial: USB Serial support registered for generic413machine # [ 0.703830] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.414machine # [ 0.703830] Please enable it if your BIOS has the CPPC option.415machine # [ 0.706186] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled416machine # [ 0.709987] drop_monitor: Initializing network drop monitor service417machine # [ 0.711250] NET: Registered PF_INET6 protocol family418machine # [ 0.714350] Segment Routing with IPv6419machine # [ 0.717846] In-situ OAM (IOAM) with IPv6420machine # [ 0.718949] IPI shorthand broadcast: enabled421machine # [ 0.727280] sched_clock: Marking stable (582014510, 144801002)->(802293157, -75477645)422machine # [ 0.732043] registered taskstats version 1423machine # [ 0.733080] Loading compiled-in X.509 certificates424machine # [ 0.750840] Demotion targets for Node 0: null425machine # [ 0.752873] Key type .fscrypt registered426machine # [ 0.753510] Key type fscrypt-provisioning registered427machine # [ 0.754483] ima: No TPM chip found, activating TPM-bypass!428machine # [ 0.757845] ima: Allocated hash algorithm: sha1429machine # [ 0.758662] ima: No architecture policies found430machine # [ 0.762839] PM: Magic number: 10:584:661431machine # [ 0.764547] RAS: Correctable Errors collector initialized.432machine # [ 0.773789] clk: Disabling unused clocks433machine # [ 0.777846] PM: genpd: Disabling unused power domains434machine # [ 0.930045] Freeing initrd memory: 28316K435machine # [ 0.933303] Freeing unused decrypted memory: 2028K436machine # [ 0.936091] Freeing unused kernel image (initmem) memory: 3644K437machine # [ 0.937233] Write protecting the kernel read-only data: 32768k438machine # [ 0.939197] Freeing unused kernel image (text/rodata gap) memory: 1216K439machine # [ 0.940763] Freeing unused kernel image (rodata/data gap) memory: 756K440machine # [ 0.991751] x86/mm: Checked W+X mappings: passed, no W+X pages found.441machine # [ 0.992891] Run /init as init process442machine # [ 1.002080] systemd[1]: Inserted module 'autofs4'443machine # [ 1.018873] fuse: init (API version 7.45)444machine # [ 1.024834] ACPI: \_SB_.GSIG: Enabled at IRQ 22445machine # [ 1.027122] ACPI: \_SB_.GSIH: Enabled at IRQ 23446machine # [ 1.030186] ACPI: \_SB_.GSIE: Enabled at IRQ 20447machine # [ 1.032372] ACPI: \_SB_.GSIF: Enabled at IRQ 21448machine # [ 1.056564] systemd[1]: Successfully made /usr/ read-only.449machine # [ 1.393145] systemd[1]: systemd 262-2451b1a running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)450machine # [ 1.405219] systemd[1]: Detected virtualization kvm.451machine # [ 1.407347] systemd[1]: Detected architecture x86-64.452machine # [ 1.409480] systemd[1]: Running in initrd.453machine # [ 1.412068] systemd[1]: Initializing machine ID from random generator.454machine # [ 1.414988] systemd[1]: Hostname set to <machine>.455machine # [ 1.505589] systemd[1]: bpf-restrict-fs: LSM BPF program attached456machine # [ 1.542344] systemd[1]: Queued start job for default target Initrd Default Target.457machine # [ 1.546174] systemd[1]: Created slice Slice /system/modprobe.458machine # [ 1.547387] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459machine # [ 1.548865] systemd[1]: Expecting device /dev/disk/by-label/nixos...460machine # [ 1.550012] systemd[1]: Reached target Path Units.461machine # [ 1.550917] systemd[1]: Reached target Slice Units.462machine # [ 1.551785] systemd[1]: Reached target Swaps.463machine # [ 1.552596] systemd[1]: Reached target Timer Units.464machine # [ 1.553598] systemd[1]: Listening on D-Bus System Message Bus Socket.465machine # [ 1.554914] systemd[1]: Listening on Journal Socket (/dev/log).466machine # [ 1.556120] systemd[1]: Listening on Journal Sockets.467machine # [ 1.557133] systemd[1]: Listening on udev Kernel Socket.468machine # [ 1.558121] systemd[1]: Reached target Socket Units.469machine # [ 1.559886] systemd[1]: Starting Create List of Static Device Nodes...470machine # [ 1.563642] systemd[1]: Starting Load Kernel Module 9pnet_virtio...471machine # [ 1.570381] systemd[1]: Starting Load Kernel Module configfs...472machine # [ 1.584009] systemd[1]: Starting Journal Service...473machine # [ 1.598908] systemd[1]: Starting Load Kernel Modules...474machine # [ 1.605900] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os475machine # [ 1.622241] netfs: FS-Cache loaded476machine # [ 1.629565] 9pnet: Installing 9P2000 support477machine # [ 1.641080] systemd[1]: Starting Coldplug All udev Devices...478machine # [ 1.654460] systemd-journald[67]: Collecting audit messages is disabled.479machine # [ 1.658096] systemd[1]: Finished Create List of Static Device Nodes.480machine # [ 1.661537] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.481machine # [ 1.669227] systemd[1]: Finished Load Kernel Module 9pnet_virtio.482machine # [ 1.676310] systemd[1]: modprobe@configfs.service: Deactivated successfully.483machine # [ 1.683205] systemd[1]: Finished Load Kernel Module configfs.484machine # [ 1.687261] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config485machine # [ 1.689652] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.486machine # [ 1.698829] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev487machine # [ 1.700629] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...488machine # [ 1.729341] systemd[1]: Finished Load Kernel Modules.489machine # [ 1.737301] systemd[1]: Starting Apply Kernel Variables...490machine # [ 1.752952] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491machine # [ 1.762159] systemd[1]: Starting Create Static Device Nodes in /dev...492machine # [ 1.774197] systemd[1]: Started Journal Service.493machine # [ 1.632175] systemd-modules-load[68]: Inserted module 'dm_mod'494machine # [ 1.633774] systemd-modules-load[68]: Inserted module 'virtio_balloon'495machine # [ 1.637439] systemd-modules-load[68]: Inserted module 'virtio_gpu'496machine # [ 1.642290] systemd[1]: Finished Apply Kernel Variables.497machine # [ 1.658377] systemd[1]: Finished Create Static Device Nodes in /dev.498machine # [ 1.660319] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 1.661272] systemd[1]: Reached target Local File Systems.500machine # [ 1.665056] systemd[1]: Starting Create System Files and Directories...501machine # [ 1.672130] systemd[1]: Starting Rule-based Manager for Device Events and Files...502machine # [ 1.700125] systemd[1]: Finished Create System Files and Directories.503machine # [ 1.718907] systemd-udevd[83]: Using default interface naming scheme 'v261'.504machine # [ 1.743733] systemd[1]: Started Rule-based Manager for Device Events and Files.505machine # [ 1.796706] systemd[1]: Finished Coldplug All udev Devices.506machine # [ 1.797678] systemd[1]: Reached target System Initialization.507machine # [ 1.798567] systemd[1]: Reached target Basic System.508machine # [ 2.090628] virtio_blk virtio5: 1/0/0 default/read/poll queues509machine # [ 2.107637] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)510machine # [ 2.130254] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12511machine # [ 2.152614] serio: i8042 KBD port at 0x60,0x64 irq 1512machine # [ 2.153316] serio: i8042 AUX port at 0x60,0x64 irq 12513machine # [ 2.160492] ehci-pci 0000:00:1d.7: EHCI Host Controller514machine # [ 2.161346] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1515machine # [ 2.163290] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000516machine # [ 2.172847] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00517machine # [ 2.173703] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18518machine # [ 2.175644] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1519machine # [ 2.177076] usb usb1: Product: EHCI Host Controller520machine # [ 2.177723] usb usb1: Manufacturer: Linux 6.18.48 ehci_hcd521machine # [ 2.179722] usb usb1: SerialNumber: 0000:00:1d.7522machine # [ 2.180981] hub 1-0:1.0: USB hub found523machine # [ 2.183141] hub 1-0:1.0: 6 ports detected524machine # [ 2.189674] uhci_hcd 0000:00:1d.0: UHCI Host Controller525machine # [ 2.190405] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2526machine # [ 2.203069] uhci_hcd 0000:00:1d.0: detected 2 ports527machine # [ 2.208510] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200528machine # [ 2.217041] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18529machine # [ 2.218185] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.235439] usb usb2: Product: UHCI Host Controller531machine # [ 2.098076] systemd[1]: Starting Virtual Console Setup...532machine # [ 2.247873] usb usb2: Manufacturer: Linux 6.18.48 uhci_hcd533machine # [ 2.248596] usb usb2: SerialNumber: 0000:00:1d.0534machine # [ 2.109106] (udev-worker)[93]: Network interface NamePolicy= disabled on kernel command line.535machine # [ 2.267889] hub 2-0:1.0: USB hub found536machine # [ 2.126806] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.537machine # [ 2.128844] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.538machine # [ 2.275857] hub 2-0:1.0: 2 ports detected539machine # [ 2.135531] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.540machine # [ 2.140983] systemd[1]: Finished Virtual Console Setup.541machine # [ 2.293975] SCSI subsystem initialized542machine # [ 2.296275] uhci_hcd 0000:00:1d.1: UHCI Host Controller543machine # [ 2.297038] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3544machine # [ 2.303755] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0545machine # [ 2.312903] uhci_hcd 0000:00:1d.1: detected 2 ports546machine # [ 2.313673] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220547machine # [ 2.177136] systemd[1]: Found device /dev/disk/by-label/nixos.548machine # [ 2.178880] systemd[1]: Reached target Initrd Root Device.549machine # [ 2.180124] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...550machine # [ 2.328932] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18551machine # [ 2.330061] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1552machine # [ 2.338849] usb usb3: Product: UHCI Host Controller553machine # [ 2.339532] usb usb3: Manufacturer: Linux 6.18.48 uhci_hcd554machine # [ 2.343852] usb usb3: SerialNumber: 0000:00:1d.1555machine # [ 2.347568] hub 3-0:1.0: USB hub found556machine # [ 2.348472] hub 3-0:1.0: 2 ports detected557machine # [ 2.355258] uhci_hcd 0000:00:1d.2: UHCI Host Controller558machine # [ 2.357100] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4559machine # [ 2.361898] uhci_hcd 0000:00:1d.2: detected 2 ports560machine # [ 2.362673] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240561machine # [ 2.220326] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks562machine # [ 2.366961] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18563machine # [ 2.227486] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.564machine # [ 2.373826] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1565machine # [ 2.376312] usb usb4: Product: UHCI Host Controller566machine # [ 2.380912] usb usb4: Manufacturer: Linux 6.18.48 uhci_hcd567machine # [ 2.381675] usb usb4: SerialNumber: 0000:00:1d.2568machine # [ 2.385551] hub 4-0:1.0: USB hub found569machine # [ 2.387402] hub 4-0:1.0: 2 ports detected570machine # [ 2.403713] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode571machine # [ 2.408697] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)572machine # [ 2.409574] ahci 0000:00:1f.2: flags: 64bit ncq only573machine # [ 2.417928] scsi host0: ahci574machine # [ 2.419185] scsi host1: ahci575machine # [ 2.419833] usb 1-1: new high-speed USB device number 2 using ehci-pci576machine # [ 2.422110] scsi host2: ahci577machine # [ 2.424209] scsi host3: ahci578machine # [ 2.425836] scsi host4: ahci579machine # [ 2.427571] scsi host5: ahci580machine # [ 2.429322] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1581machine # [ 2.430442] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1582machine # [ 2.431667] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1583machine # [ 2.432855] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1584machine # [ 2.433994] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1585machine # [ 2.435144] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1586machine # [ 2.549086] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00587machine # [ 2.551924] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10588machine # [ 2.555248] usb 1-1: Product: QEMU USB Tablet589machine # [ 2.557044] usb 1-1: Manufacturer: QEMU590machine # [ 2.558654] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1591machine # [ 2.587522] hid: raw HID events driver (C) Jiri Kosina592machine # [ 2.507593] systemd[1]: Mounting /sysroot...593machine # [ 2.745320] ata1: SATA link down (SStatus 0 SControl 300)594machine # [ 2.747677] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)595machine # [ 2.750387] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100596machine # [ 2.752497] ata3.00: applying bridge limits597machine # [ 2.754419] ata2: SATA link down (SStatus 0 SControl 300)598machine # [ 2.756750] ata5: SATA link down (SStatus 0 SControl 300)599machine # [ 2.759097] ata4: SATA link down (SStatus 0 SControl 300)600machine # [ 2.761225] ata6: SATA link down (SStatus 0 SControl 300)601machine # [ 2.763507] ata3.00: configured for UDMA/100602machine # [ 2.765931] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5603machine # [ 2.829451] usbcore: registered new interface driver usbhid604machine # [ 2.830259] usbhid: USB HID core driver605machine # [ 2.846911] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2606machine # [ 2.850250] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0607machine # [ 2.871694] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray608machine # [ 2.882343] cdrom: Uniform CD-ROM driver Revision: 3.20609machine # [ 2.885267] EXT4-fs (vda): mounted filesystem e26bb448-d92e-4572-a2e9-7d1ceb981c97 r/w with ordered data mode. Quota mode: none.610machine # [ 2.744910] systemd[1]: Mounted /sysroot.611machine # [ 2.745658] systemd[1]: Reached target Initrd Root File System.612machine # [ 2.751774] systemd[1]: Mounting /sysroot/nix/.ro-store...613machine # [ 2.755102] systemd[1]: Mounting /sysroot/nix/.rw-store...614machine # [ 2.758284] systemd[1]: Mounting /sysroot/run...615machine # [ 2.762750] systemd[1]: Mounting /sysroot/tmp/shared...616machine # [ 2.771270] systemd[1]: Mounting /sysroot/tmp/xchg...617machine # [ 2.781071] systemd[1]: Starting Mountpoints Configured in the Real Root...618machine # [ 2.809729] systemd-sysroot-fstab-check[146]: /sysroot should be mounted in the initrd, will request daemon-reload.619machine # [ 2.823817] systemd[1]: Mounted /sysroot/nix/.rw-store.620machine # [ 2.969646] 9p: Installing v9fs 9p2000 file system support621machine # [ 2.834421] systemd[1]: Mounted /sysroot/run.622machine # [ 2.837380] systemd[1]: Mounted /sysroot/nix/.ro-store.623machine # [ 2.840111] systemd[1]: Mounted /sysroot/tmp/shared.624machine # [ 2.840865] systemd[1]: Mounted /sysroot/tmp/xchg.625machine # [ 2.851079] systemd[1]: Reload requested from client PID 146 ('systemd-sysroot') (unit initrd-parse-etc.service)...626machine # [ 2.852580] systemd[1]: Reloading...627machine # [ 2.909891] systemd[1]: Reloading finished in 56 ms.628machine # [ 2.918901] systemd-sysroot-fstab-check[146]: Requesting initrd-fs.target/start/replace...629machine # [ 2.922116] systemd-sysroot-fstab-check[146]: Requesting swap.target/start/replace...630machine # [ 2.923558] systemd[1]: Starting rw-sysroot-nix-store.service...631machine # [ 2.927545] systemd[1]: initrd-parse-etc.service: Deactivated successfully.632machine # [ 2.929118] systemd[1]: Finished Mountpoints Configured in the Real Root.633machine # [ 2.931116] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.634machine # [ 2.941135] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.635machine # [ 2.942556] systemd[1]: Finished rw-sysroot-nix-store.service.636machine # [ 3.508098] systemd[1]: Mounting /sysroot/nix/store...637machine # [ 3.556525] systemd[1]: Mounted /sysroot/nix/store.638machine # [ 3.560337] systemd[1]: Reached target Initrd File Systems.639machine # [ 3.564117] systemd[1]: Starting Find NixOS closure...640machine # [ 3.568666] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...641machine # [ 3.593819] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.642machine # [ 3.595692] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.643machine # [ 3.604510] systemd[1]: Finished Find NixOS closure.644machine # [ 3.605642] systemd[1]: Reached target Initrd Default Target.645machine # [ 3.607373] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...646machine # [ 3.620714] systemd[1]: Stopped target Initrd Default Target.647machine # [ 3.621818] systemd[1]: Stopped target Basic System.648machine # [ 3.622849] systemd[1]: Stopped target Initrd Root Device.649machine # [ 3.625109] systemd[1]: Stopped target Path Units.650machine # [ 3.625836] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.651machine # [ 3.626902] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.652machine # [ 3.627982] systemd[1]: Stopped target Slice Units.653machine # [ 3.628721] systemd[1]: Stopped target Socket Units.654machine # [ 3.629706] systemd[1]: Stopped target System Initialization.655machine # [ 3.630586] systemd[1]: Stopped target Swaps.656machine # [ 3.631302] systemd[1]: Stopped target Timer Units.657machine # [ 3.632039] systemd[1]: dbus.socket: Deactivated successfully.658machine # [ 3.632935] systemd[1]: Closed D-Bus System Message Bus Socket.659machine # [ 3.634145] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.660machine # [ 3.635454] systemd[1]: Stopped Find NixOS closure.661machine # [ 3.636202] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio662machine # [ 3.638453] systemd[1]: Starting rw-sysroot-nix-store.service...663machine # [ 3.639494] systemd[1]: systemd-sysctl.service: Deactivated successfully.664machine # [ 3.641256] systemd[1]: Stopped Apply Kernel Variables.665machine # [ 3.642306] systemd[1]: systemd-modules-load.service: Deactivated successfully.666machine # [ 3.643554] systemd[1]: Stopped Load Kernel Modules.667machine # [ 3.644620] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.668machine # [ 3.646171] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.669machine # [ 3.647551] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.670machine # [ 3.648835] systemd[1]: Stopped Create System Files and Directories.671machine # [ 3.650360] systemd[1]: Stopped target Local File Systems.672machine # [ 3.652087] systemd[1]: Stopped target Preparation for Local File Systems.673machine # [ 3.654087] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.674machine # [ 3.655143] systemd[1]: Stopped Coldplug All udev Devices.675machine # [ 3.656254] systemd[1]: Stopping Rule-based Manager for Device Events and Files...676machine # [ 3.658168] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.677machine # [ 3.659942] systemd[1]: Stopped Virtual Console Setup.678machine # [ 3.668713] systemd[1]: initrd-cleanup.service: Deactivated successfully.679machine # [ 3.675075] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.680machine # [ 3.677599] systemd[1]: systemd-udevd.service: Deactivated successfully.681machine # [ 3.679323] systemd[1]: Stopped Rule-based Manager for Device Events and Files.682machine # [ 3.681307] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.683machine # [ 3.682503] systemd[1]: Finished rw-sysroot-nix-store.service.684machine # [ 3.685536] systemd[1]: Starting Cleanup udev Database...685machine # [ 3.686433] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.686machine # [ 3.687958] systemd[1]: Stopped Create Static Device Nodes in /dev.687machine # [ 3.688826] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.688machine # [ 3.689907] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.689machine # [ 3.691147] systemd[1]: kmod-static-nodes.service: Deactivated successfully.690machine # [ 3.693143] systemd[1]: Stopped Create List of Static Device Nodes.691machine # [ 3.706738] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.692machine # [ 3.708229] systemd[1]: Finished Cleanup udev Database.693machine # [ 3.709606] systemd[1]: Reached target Switch Root.694machine # [ 3.711558] systemd[1]: Starting NixOS Activation...695machine # [ 3.866116] initrd-nixos-activation-start[194]: booting system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test696machine # [ 3.927026] initrd-nixos-activation-start[194]: running activation script...697machine # [ 4.322759] initrd-nixos-activation-start[217]: setting up /etc...698machine # [ 4.536595] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.699machine # [ 4.539076] systemd[1]: Finished NixOS Activation.700machine # [ 4.540203] systemd[1]: Starting Switch Root...701machine # [ 4.553348] systemd[1]: Switching root.702machine # [ 4.827497] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).703machine # [ 4.958849] NET: Registered PF_VSOCK protocol family704machine # [ 5.336127] systemd[1]: systemd 262-2451b1a running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)705machine # [ 5.345579] systemd[1]: Detected virtualization kvm.706machine # [ 5.347395] systemd[1]: Detected architecture x86-64.707machine # [ 5.349330] systemd[1]: Detected first boot.708machine # [ 5.356240] systemd[1]: Initializing machine ID from random generator.709machine # [ 5.479213] systemd[1]: bpf-restrict-fs: LSM BPF program attached710machine # [ 5.595867] systemd[1]: Applying preset policy.711machine # [ 5.992180] systemd[1]: Populated /etc with preset unit settings.712machine # [ 6.379945] systemd[1]: initrd-switch-root.service: Deactivated successfully.713machine # [ 6.381483] systemd[1]: Stopped initrd-switch-root.service.714machine # [ 6.384154] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.715machine # [ 6.386402] systemd[1]: Created slice Slice /system/getty.716machine # [ 6.387724] systemd[1]: Created slice User and Session Slice.717machine # [ 6.388880] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.718machine # [ 6.390099] systemd[1]: Started Forward Password Requests to Wall Directory Watch.719machine # [ 6.391180] systemd[1]: Expecting device /dev/hvc0...720machine # [ 6.391921] systemd[1]: Expecting device /dev/ttyS0...721machine # [ 6.392627] systemd[1]: Reached target Local Encrypted Volumes.722machine # [ 6.393427] systemd[1]: Stopped target initrd-fs.target.723machine # [ 6.394192] systemd[1]: Stopped target initrd-root-fs.target.724machine # [ 6.394990] systemd[1]: Stopped target initrd-switch-root.target.725machine # [ 6.395846] systemd[1]: Reached target Virtual Machines and Containers.726machine # [ 6.396707] systemd[1]: Reached target Path Units.727machine # [ 6.397422] systemd[1]: Reached target Remote File Systems.728machine # [ 6.398215] systemd[1]: Reached target Slice Units.729machine # [ 6.398936] systemd[1]: Reached target Swaps.730machine # [ 6.401631] systemd[1]: Listening on Query the User Interactively for a Password.731machine # [ 6.417171] systemd[1]: Listening on Process Core Dump Socket.732machine # [ 6.419947] systemd[1]: Listening on Credential Encryption/Decryption.733machine # [ 6.422468] systemd[1]: Listening on Factory Reset Management.734machine # [ 6.423400] systemd[1]: Listening on Hostname Service Socket.735machine # [ 6.427301] systemd[1]: Starting Journal Log Access Socket...736machine # [ 6.428923] systemd[1]: Listening on Journal Audit Socket.737machine # [ 6.431570] systemd[1]: Listening on Console Output Muting Service Socket.738machine # [ 6.432756] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.739machine # [ 6.434535] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os740machine # [ 6.435854] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki741machine # [ 6.444505] systemd[1]: Listening on Disk Repartitioning Service Socket.742machine # [ 6.445574] systemd[1]: Listening on udev Varlink Socket.743machine # [ 6.449831] systemd[1]: Mounting Huge Pages File System...744machine # [ 6.452958] systemd[1]: Mounting POSIX Message Queue File System...745machine # [ 6.459690] systemd[1]: Mounting Kernel Debug File System...746machine # [ 6.465544] systemd[1]: Mounting Kernel Trace File System...747machine # [ 6.472902] systemd[1]: Starting Create List of Static Device Nodes...748machine # [ 6.475148] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio749machine # [ 6.482765] systemd[1]: Starting Load Kernel Module configfs...750machine # [ 6.485129] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm751machine # [ 6.490858] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore752machine # [ 6.493471] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse753machine # [ 6.530393] systemd[1]: Mounting FUSE Control File System...754machine # [ 6.533762] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67755machine # [ 6.565134] systemd[1]: Starting Journal Service...756machine # [ 6.570847] systemd[1]: Starting Load Kernel Modules...757machine # [ 6.581431] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...758machine # [ 6.589724] systemd[1]: Starting Remount Root and Kernel File Systems...759machine # [ 6.592591] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os760machine # [ 6.598269] systemd[1]: Starting Coldplug All udev Devices...761machine # [ 6.634138] systemd[1]: Listening on Journal Log Access Socket.762machine # [ 6.639355] systemd[1]: Mounted Huge Pages File System.763machine # [ 6.645059] systemd[1]: Mounted POSIX Message Queue File System.764machine # [ 6.650599] systemd[1]: Mounted Kernel Debug File System.765machine # [ 6.653824] systemd-journald[288]: Collecting audit messages is enabled.766machine # [ 6.657500] systemd[1]: Mounted Kernel Trace File System.767machine # [ 6.663612] systemd[1]: Finished Create List of Static Device Nodes.768machine # [ 6.667663] loop: module loaded769machine # [ 6.673216] systemd[1]: modprobe@configfs.service: Deactivated successfully.770machine # [ 6.678984] systemd[1]: Finished Load Kernel Module configfs.771machine # [ 6.684299] systemd[1]: Mounted FUSE Control File System.772machine # [ 6.690967] systemd[1]: Finished Load Kernel Modules.773machine # [ 6.547972] systemd[1]: Queued start job for default target Multi-User System.774machine # [ 6.697212] systemd[1]: Started Journal Service.775machine # [ 6.553911] systemd[1]: systemd-journald.service: Deactivated successfully.776machine # [ 6.555572] systemd-modules-load[289]: Inserted module 'loop'777machine # [ 6.703836] EXT4-fs (vda): re-mounted e26bb448-d92e-4572-a2e9-7d1ceb981c97.778machine # [ 6.574943] systemd[1]: Finished Remount Root and Kernel File Systems.779machine # [ 6.584708] systemd[1]: Listening on Disk Image Download Service Socket.780machine # [ 6.590264] systemd[1]: Mounting Kernel Configuration File System...781machine # [ 6.599886] systemd[1]: Starting Firewall...782machine # [ 6.601361] systemd-oomd[290]: No swap; memory pressure usage will be degraded783machine # [ 6.608261] systemd[1]: Starting Flush Journal to Persistent Storage...784machine # [ 6.609348] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore785machine # [ 6.626975] systemd[1]: Starting Load/Save OS Random Seed...786machine # [ 6.639084] systemd[1]: Starting Apply Kernel Variables...787machine # [ 6.648658] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...788machine # [ 6.654185] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os789machine # [ 6.658328] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.790machine # [ 6.824892] systemd-journald[288]: Received client request to flush runtime journal.791machine # [ 6.844820] systemd[1]: Mounted Kernel Configuration File System.792machine # [ 6.847167] systemd[1]: Finished Load/Save OS Random Seed.793machine # [ 6.849629] systemd[1]: Reached target First Boot Complete.794machine # [ 6.852524] systemd[1]: Finished Apply Kernel Variables.795machine # [ 6.854349] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.796machine # [ 6.857088] systemd[1]: Starting Create Static Device Nodes in /dev...797machine # [ 6.859281] systemd[1]: Finished Flush Journal to Persistent Storage.798machine # [ 6.923194] systemd[1]: Finished Create Static Device Nodes in /dev.799machine # [ 6.924384] systemd[1]: Reached target Preparation for Local File Systems.800machine # [ 6.930092] systemd[1]: Starting Rule-based Manager for Device Events and Files...801machine # [ 7.000777] systemd[1]: Finished Coldplug All udev Devices.802machine # [ 7.018828] systemd-udevd[332]: Using default interface naming scheme 'v261'.803machine # [ 7.111684] systemd[1]: Started Rule-based Manager for Device Events and Files.804machine # [ 7.241843] systemd[1]: Mounting /run/wrappers...805machine # [ 7.253746] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse806machine # [ 7.290951] systemd[1]: Mounted /run/wrappers.807machine # [ 7.292627] systemd[1]: Reached target Local File Systems.808machine # [ 7.296827] systemd[1]: Listening on Boot Loader Control Service Socket.809machine # [ 7.301279] systemd[1]: Starting register-nix-paths.service...810machine # [ 7.305605] systemd[1]: Starting Create SUID/SGID Wrappers...811machine # [ 7.307508] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.812machine # [ 7.324606] systemd[1]: Starting Save Transient machine-id to Disk...813machine # [ 7.341691] systemd[1]: Starting Create System Files and Directories...814machine # [ 7.417702] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.815machine # [ 7.421765] systemd[1]: Finished Save Transient machine-id to Disk.816machine # [ 7.465815] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.817machine # [ 7.497381] systemd[1]: Finished Create System Files and Directories.818machine # [ 7.506418] systemd[1]: Starting Rebuild Journal Catalog...819machine # [ 7.517535] systemd[1]: Starting Record System Boot/Shutdown in UTMP...820machine # [ 7.542560] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.821machine # [ 7.605761] systemd[1]: Finished Record System Boot/Shutdown in UTMP.822machine # [ 7.620591] (udev-worker)[374]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.823machine # [ 7.625972] (udev-worker)[374]: Network interface NamePolicy= disabled on kernel command line.824machine # [ 7.631238] (udev-worker)[371]: Network interface NamePolicy= disabled on kernel command line.825machine # [ 7.664156] systemd[1]: Finished Rebuild Journal Catalog.826machine # [ 7.670942] systemd[1]: Starting Update is Completed...827machine # [ 7.743378] systemd[1]: Finished Update is Completed.828machine # [ 7.881089] systemd[1]: Condition check resulted in Virtio network device being skipped.829machine # [ 7.882290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore830machine # [ 7.883647] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.831machine # [ 7.884809] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67832machine # [ 7.890112] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore833machine # [ 7.892787] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os834machine # [ 7.894896] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os835machine # [ 7.922770] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.836machine # [ 7.925470] systemd[1]: Finished Create SUID/SGID Wrappers.837machine # [ 7.971128] systemd[1]: Finished Firewall.838machine # [ 8.186946] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3839machine # [ 8.199447] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console840machine # [ 8.239474] systemd[1]: Finished register-nix-paths.service.841machine # [ 8.240547] systemd[1]: Reached target System Initialization.842machine # [ 8.242726] systemd[1]: Started Discard unused filesystem blocks once a week.843machine # [ 8.243751] systemd[1]: Started Daily Cleanup of Temporary Directories.844machine # [ 8.245656] systemd[1]: Reached target Timer Units.845machine # [ 8.247148] systemd[1]: Listening on D-Bus System Message Bus Socket.846machine # [ 8.248305] systemd[1]: Listening on Nix Daemon Socket.847machine # [ 8.249405] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.848machine # [ 8.250805] systemd[1]: Reached target Socket Units.849machine # [ 8.252461] systemd[1]: Reached target Basic System.850machine # [ 8.256472] systemd[1]: Started backdoor.service.851machine # [ 8.223644] ACPI: button: Power Button [PWRF]852machine # [ 8.239955] mousedev: PS/2 mouse device common for all mice853machine # [ 8.309467] rtc_cmos 00:04: RTC can wake from S4854machine # [ 8.327338] rtc_cmos 00:04: registered as rtc0855machine # [ 8.261397] systemd[1]: Starting Import lastlog data into lastlog2 database...856machine # [ 8.271499] systemd[1]: Starting Name Service Cache Daemon (nsncd)...857machine # [ 8.327417] rtc_cmos 00:04: setting system clock to 2026-09-03T02:41:47 UTC (1788403307)858machine # [ 8.286818] systemd[1]: Starting Post-Boot Actions...859machine # [ 8.297616] systemd[1]: Started Reset console on configuration changes.860machine # [ 8.327503] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs861machine # [ 8.328054] systemd-journald[288]: Time jumped backwards, rotating.862machine # [ 8.351678] parport_pc 00:02: reported by Plug and Play ACPI863machine # [ 8.318349] systemd[1]: Starting resolvconf update...864machine # [ 8.358453] systemd[1]: Starting D-Bus System Message Bus...865machine # connecting to host...866machine # [ 8.402160] nsncd[505]: Sep 03 02:41:47.714 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"867machine # [ 8.405566] systemd[1]: Finished Post-Boot Actions.868machine # [ 8.406475] systemd[1]: Started Name Service Cache Daemon (nsncd).869machine # [ 8.354852] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]870machine # [ 8.371688] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4871machine # [ 8.418304] systemd[1]: Reached target Host and Network Name Lookups.872machine: Guest shell says: b'Spawning backdoor root shell...\n'873machine # [ 8.419823] systemd[1]: Reached target User and Group Name Lookups.874machine: connected to guest root shell875machine: (connecting took 9.10 seconds)876machine: (finished: waiting for the VM to finish booting, in 9.43 seconds)877machine # [ 8.438983] systemd[1]: Starting User Login Management...878machine # [ 8.474168] systemd[1]: Finished Import lastlog data into lastlog2 database.879machine # [ 8.375407] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized880machine # [ 8.402602] Console: switching to colour dummy device 80x25881machine # [ 8.650377] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt882machine # [ 8.650515] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD883machine # [ 8.563964] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...884machine # [ 8.713397] [drm] Found bochs VGA, ID 0xb0c5.885machine # [ 8.741332] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.886machine # [ 8.606280] systemd-logind[530]: New seat seat0.887machine # [ 8.611216] systemd[1]: Started User Login Management.888machine # [ 8.759527] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic889machine # [ 8.760241] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0890machine # [ 8.617485] systemd[1]: Stopped target Host and Network Name Lookups.891machine # [ 8.618452] systemd[1]: Stopping Host and Network Name Lookups...892machine # [ 8.619274] systemd[1]: Stopped target User and Group Name Lookups.893machine # [ 8.620573] systemd[1]: Stopping User and Group Name Lookups...894machine # [ 8.623132] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'895machine # [ 8.624310] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"896machine # [ 8.773743] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6897machine # [ 8.631751] systemd[1]: Starting linger-users.service...898machine # [ 8.777699] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5899machine # [ 8.634123] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...900machine # [ 8.643208] systemd[1]: nscd.service: Deactivated successfully.901machine # [ 8.647237] systemd[1]: Stopped Name Service Cache Daemon (nsncd).902machine # [ 8.669179] systemd[1]: Started D-Bus System Message Bus.903machine # [ 8.693149] systemd[1]: Starting Name Service Cache Daemon (nsncd)...904machine # [ 8.711653] dbus-broker-launch[513]: Ready905machine # [ 8.769755] nsncd[596]: Sep 03 02:41:48.086 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"906machine # [ 8.773328] systemd[1]: Finished resolvconf update.907machine # [ 8.774583] systemd[1]: Started Name Service Cache Daemon (nsncd).908machine # [ 8.797625] systemd[1]: Created slice Slice /user/1001.909machine # [ 8.798516] systemd[1]: Reached target Preparation for Network.910machine # [ 8.799356] systemd[1]: Reached target Host and Network Name Lookups.911machine # [ 8.800186] systemd[1]: Reached target User and Group Name Lookups.912machine # [ 8.809099] systemd[1]: Starting DHCP Client...913machine # [ 8.818612] systemd[1]: Starting Address configuration of eth1...914machine # [ 8.824176] systemd[1]: Starting Extra networking commands....915machine # [ 8.834159] systemd[1]: Starting User Runtime Directory /run/user/1001...916machine # [ 8.843052] systemd[1]: linger-users.service: Deactivated successfully.917machine # [ 8.849853] systemd[1]: Finished linger-users.service.918machine # [ 8.965465] Console: switching to colour frame buffer device 160x50919machine # [ 9.019554] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device920machine # [ 8.880074] systemd[1]: Starting Virtual Console Setup...921machine # [ 8.892212] systemd-logind[530]: Watching system buttons on /dev/input/event2 (Power Button)922machine # [ 8.900846] systemd-logind[530]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)923machine # [ 8.948490] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.924machine # [ 8.949701] systemd[1]: Stopped Virtual Console Setup.925machine # [ 8.954359] systemd[1]: Starting Virtual Console Setup...926machine # [ 9.116565] ppdev: user-space parallel port driver927machine # [ 9.130126] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)928machine # [ 9.001522] systemd[1]: Finished User Runtime Directory /run/user/1001.929machine # [ 9.002544] systemd[1]: Starting User Manager for UID 1001...930machine # [ 9.006130] systemd-logind[530]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)931machine # [ 9.170131] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)932machine # [ 9.103693] (systemd)[628]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)933machine # [ 9.112315] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.934machine # [ 9.118606] systemd[1]: Stopped Virtual Console Setup.935machine # [ 9.120183] systemd[1]: Starting Virtual Console Setup...936machine # [ 9.129391] systemd-logind[530]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.937machine # [ 9.176708] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy938machine # [ 9.249478] network-addresses-eth1-start[616]: adding address 192.168.1.1/24... done939machine # [ 9.275252] network-addresses-eth1-start[616]: adding address 2001:db8:1::1/64... done940machine # [ 9.316671] systemd[1]: Finished Address configuration of eth1.941machine # [ 9.417315] systemd[628]: Queued start job for default target Main User Target.942machine # [ 9.418632] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy943machine # [ 9.420185] systemd[628]: Created slice User Application Slice.944machine # [ 9.422494] systemd[628]: Started Daily Cleanup of User's Temporary Directories.945machine # [ 9.423857] systemd[628]: Reached target Paths.946machine # [ 9.424864] systemd[628]: Reached target Timers.947machine # [ 9.429098] systemd[628]: Starting D-Bus User Message Bus Socket...948machine # [ 9.434095] systemd[628]: Starting Create User Files and Directories...949machine # [ 9.583507] kvm_amd: TSC scaling supported950machine # [ 9.585298] kvm_amd: Nested Virtualization enabled951machine # [ 9.599125] kvm_amd: Nested Paging enabled952machine # [ 9.599671] kvm_amd: LBR virtualization supported953machine # [ 9.457272] systemd[1]: Finished Extra networking commands..954machine # [ 9.463300] systemd[1]: Reached target Network.955machine # [ 9.464532] systemd[1]: Starting Permit User Sessions...956machine # [ 9.610945] kvm_amd: Virtual VMLOAD VMSAVE supported957machine # [ 9.611467] kvm_amd: Virtual GIF supported958machine # [ 9.616947] kvm_amd: Virtual NMI enabled959machine # [ 9.478670] systemd[628]: Finished Create User Files and Directories.960machine # [ 9.517827] dhcpcd[639]: dhcpcd-10.3.2 starting961machine # [ 9.523528] systemd[628]: Listening on D-Bus User Message Bus Socket.962machine # [ 9.524984] systemd[628]: Reached target Sockets.963machine # [ 9.528978] systemd[628]: Reached target Basic System.964machine # [ 9.530485] systemd[1]: Started User Manager for UID 1001.965machine # [ 9.531715] systemd[628]: Starting Run user-specific NixOS activation...966machine # [ 9.541875] dhcpcd[704]: dev: loaded udev967machine # [ 9.545702] systemd[1]: Finished Permit User Sessions.968machine # [ 9.550869] systemd[1]: Started Getty on tty1.969machine # [ 9.551967] systemd[1]: Reached target Login Prompts.970machine # [ 9.711112] EDAC MC: Ver: 3.0.0971machine # [ 9.584339] systemd[628]: Finished Run user-specific NixOS activation.972machine # [ 9.585802] systemd[628]: Reached target Main User Target.973machine # [ 9.732343] 8021q: 802.1Q VLAN Support v1.8974machine # [ 9.588307] systemd[628]: Startup finished in 428ms.975machine # [ 9.734869] 8021q: adding VLAN 0 to HW filter on device eth1976machine # [ 9.617355] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.977machine # [ 9.823144] cfg80211: Loading compiled-in X.509 certificates for regulatory database978machine # [ 9.851013] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'979machine # [ 9.851684] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'980machine # [ 9.855577] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2981machine # [ 9.856476] cfg80211: failed to load regulatory.db982machine # [ 9.924463] 8021q: adding VLAN 0 to HW filter on device eth0983machine # [ 9.780942] dhcpcd[704]: eth0: waiting for carrier984machine # [ 9.781940] dhcpcd[704]: eth0: carrier acquired985machine # [ 9.794331] dhcpcd[704]: DUID 00:01:00:01:32:2b:9a:ed:52:54:00:12:34:56986machine # [ 9.795525] dhcpcd[704]: eth0: IAID 00:12:34:56987machine # [ 9.796261] dhcpcd[704]: eth0: adding address fe80::5054:ff:fe12:3456988machine # [ 9.906675] systemd-vconsole-setup[634]: Configuration of first virtual console was skipped, ignoring remaining ones.989machine # [ 9.911980] systemd[1]: Finished Virtual Console Setup.990machine # [ 11.097438] dhcpcd[704]: eth0: soliciting a DHCP lease991machine # [ 11.267481] NET: Registered PF_PACKET protocol family992machine # [ 11.128932] dhcpcd[704]: eth0: offered 10.0.2.15 from 10.0.2.2993machine # [ 11.134353] dhcpcd[704]: eth0: probing address 10.0.2.15/24994machine # [ 11.393537] dhcpcd[704]: eth0: soliciting an IPv6 router995machine # [ 11.396951] dhcpcd[704]: eth0: Router Advertisement from fe80::2996machine # [ 11.399126] dhcpcd[704]: eth0: adding address fec0::5054:ff:fe12:3456/64997machine # [ 11.401406] dhcpcd[704]: eth0: adding route to fec0::/64998machine # [ 11.403342] dhcpcd[704]: eth0: adding default route via fe80::2999machine # [ 16.440807] dhcpcd[704]: eth0: leased 10.0.2.15 for 86400 seconds1000machine # [ 16.442234] dhcpcd[704]: eth0: adding route to 10.0.2.0/241001machine # [ 16.443300] dhcpcd[704]: eth0: adding default route via 10.0.2.21002machine # [ 16.521812] systemd[1]: Started DHCP Client.1003machine # [ 16.523689] systemd[1]: Reached target Multi-User System.1004machine # [ 16.524764] systemd[1]: Startup finished in 851ms (kernel) + 3.912s (initrd) + 11.760s (userspace) = 16.524s.1005machine: (finished: waiting for unit multi-user.target, in 18.36 seconds)1006machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test1007machine # Checking switch inhibitors... done1008machine # [ 17.981989] nixos[859]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1009machine # activating the configuration...1010machine # [ 17.985954] systemd[1]: Stopped target Remote File Systems.1011machine # [ 17.986858] systemd[1]: Stopped target Local File Systems.1012machine # setting up /etc...1013machine # [ 18.392665] systemd[1]: Reload requested from client PID 859 ('.switch-to-conf') (unit backdoor.service)...1014machine # [ 18.394403] systemd[1]: Reloading...1015machine # [ 18.946989] systemd[1]: Reloading finished in 550 ms.1016machine # reloading user units for usertest...1017machine # [ 18.979612] systemd[628]: Created slice User Core Session Slice.1018machine # [ 18.982879] systemd[628]: Starting D-Bus User Message Bus...1019machine # [ 19.042556] dbus-broker-launch[929]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.import1.service'1020machine # [ 19.045203] dbus-broker-launch[929]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1021machine # [ 19.048126] dbus-broker-launch[929]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1022machine # [ 19.051328] dbus-broker-launch[929]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1023machine # [ 19.054884] dbus-broker-launch[929]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1024machine # [ 19.056416] dbus-broker-launch[929]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1025machine # [ 19.058241] systemd[628]: Started D-Bus User Message Bus.1026machine # [ 19.066817] dbus-broker-launch[929]: Ready1027machine # [ 19.173096] systemd[628]: Reexecution requested from client PID 928 ('switch-to-confi')...1028machine # [ 19.175255] systemd[628]: Reexecuting.1029machine # [ 19.203486] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1030machine # [ 19.361145] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1031machine # [ 19.365721] systemd[628]: Reload requested from client PID 928 ('switch-to-confi')...1032machine # [ 19.366913] systemd[628]: Reloading...1033machine # [ 19.520476] systemd[628]: Reloading finished in 151 ms.1034machine # [ 19.524841] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1035machine # restarting the following user units: nixos-activation.service1036machine # [ 19.532057] systemd[628]: Stopped Run user-specific NixOS activation.1037machine # [ 19.534043] systemd[628]: Stopping Run user-specific NixOS activation...1038machine # [ 19.536539] systemd[628]: Starting Run user-specific NixOS activation...1039machine # [ 19.575826] systemd[628]: Finished Run user-specific NixOS activation.1040machine # restarting sysinit-reactivation.target1041machine # [ 19.593062] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1042machine # [ 19.693927] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1043machine # [ 19.697256] systemd[1]: Reached target Reactivate sysinit units.1044machine # [ 19.700701] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1045machine # [ 19.702495] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1046machine # [ 19.707812] systemd[1]: Reached target Remote File Systems.1047machine # [ 19.711543] systemd[1]: Starting linger-users.service...1048machine # [ 19.713737] systemd[1]: Reached target Local File Systems.1049machine # [ 19.719119] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1050machine # [ 19.724672] systemd[1]: Starting Create SUID/SGID Wrappers...1051machine # [ 19.725515] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1052machine # [ 19.727736] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671053machine # [ 19.730159] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1054machine # [ 19.732921] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1055machine # [ 19.735235] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1056machine # [ 19.783679] systemd[1]: linger-users.service: Deactivated successfully.1057machine # [ 19.784766] systemd[1]: Finished linger-users.service.1058machine # [ 20.008889] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1059machine # [ 20.011537] systemd[1]: Finished Create SUID/SGID Wrappers.1060machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1061machine # [ 20.267500] nixos[859]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1062machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test, in 2.91 seconds)1063machine: must succeed: rm /etc/NIXOS1064machine: (finished: must succeed: rm /etc/NIXOS, in 0.02 seconds)1065machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test/bin/switch-to-configuration test1066machine # this should succeed (config: /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test, action: test)1067machine # Checking switch inhibitors... done1068machine # [ 20.901878] nixos[1007]: switching to system configuration /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test1069machine # activating the configuration...1070machine # [ 20.905860] systemd[1]: Stopped target Local File Systems.1071machine # [ 20.906740] systemd[1]: Stopped target Remote File Systems.1072machine # removing user ‘usertest’1073machine # setting up /etc...1074machine # [ 21.336978] systemd[1]: Reload requested from client PID 1007 ('.switch-to-conf') (unit backdoor.service)...1075machine # [ 21.338734] systemd[1]: Reloading...1076machine # [ 21.884095] systemd[1]: Reloading finished in 543 ms.1077machine # reloading user units for usertest...1078machine # [ 22.004244] systemd[628]: Reexecution requested from client PID 1078 ('switch-to-confi')...1079machine # [ 22.006943] systemd[628]: Reexecuting.1080machine # [ 22.032483] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1081machine # [ 22.157147] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1082machine # [ 22.163138] systemd[628]: Reload requested from client PID 1078 ('switch-to-confi')...1083machine # [ 22.164964] systemd[628]: Reloading...1084machine # [ 22.311082] systemd[628]: Reloading finished in 144 ms.1085machine # [ 22.316865] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1086machine # restarting the following user units: nixos-activation.service1087machine # [ 22.324753] systemd[628]: Stopped Run user-specific NixOS activation.1088machine # [ 22.326262] systemd[628]: Stopping Run user-specific NixOS activation...1089machine # [ 22.329274] systemd[628]: Starting Run user-specific NixOS activation...1090machine # [ 22.368721] systemd[628]: Finished Run user-specific NixOS activation.1091machine # restarting sysinit-reactivation.target1092machine # [ 22.372788] systemd[1]: Stopped target Reactivate sysinit units.1093machine # reloading the following units: dbus-broker.service1094machine # [ 22.375268] systemd[1]: Stopping Reactivate sysinit units...1095machine # [ 22.376577] systemd[1]: Reached target Reactivate sysinit units.1096machine # [ 22.378453] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1097machine # [ 22.379740] systemd[1]: Reloading D-Bus System Message Bus...1098machine # [ 22.403124] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1099machine # [ 22.405057] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1100machine # [ 22.405977] dbus-broker-launch[513]: Invalid user-name in /nix/store/h586j6zx3nwqd354pg3jzsx05yvfqlr4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1101machine # [ 22.434878] systemd[1]: Reloaded D-Bus System Message Bus.1102machine # restarting the following units: network-addresses-eth1.service1103machine # [ 22.441384] systemd[1]: Stopping Address configuration of eth1...1104machine # [ 22.481109] network-addresses-eth1-pre-stop[1090]: deleting address 192.168.1.1/24... done1105machine # [ 22.493162] network-addresses-eth1-pre-stop[1090]: deleting address 2001:db8:1::1/64... done1106machine # [ 22.501541] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1107machine # [ 22.503376] systemd[1]: Stopped Address configuration of eth1.1108machine # [ 22.507671] systemd[1]: Starting Address configuration of eth1...1109machine # [ 22.577186] network-addresses-eth1-start[1095]: adding address 192.168.1.2/24... done1110machine # [ 22.588425] network-addresses-eth1-start[1095]: adding address 2001:db8:1::2/64... done1111machine # [ 22.603454] systemd[1]: Finished Address configuration of eth1.1112machine # [ 22.604871] systemd[1]: Reached target Remote File Systems.1113machine # [ 22.607763] systemd[1]: Reached target Local File Systems.1114machine # [ 22.610074] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1115machine # [ 22.613800] systemd[1]: Starting Create SUID/SGID Wrappers...1116machine # [ 22.615289] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1117machine # [ 22.617205] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671118machine # [ 22.620195] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1119machine # [ 22.622141] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1120machine # [ 22.623910] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1121machine # [ 22.636656] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1122machine # [ 22.638334] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1123machine # [ 22.956094] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1124machine # [ 22.957913] systemd[1]: Finished Create SUID/SGID Wrappers.1125machine # [ 22.962360] systemd[1]: Starting linger-users.service...1126machine # [ 22.999316] linger-users-start[1168]: id: ‘usertest’: no such user1127machine # [ 23.001088] linger-users-start[1166]: Removing linger for missing user usertest1128machine # [ 23.008465] systemd[1]: linger-users.service: Deactivated successfully.1129machine # [ 23.009876] systemd[1]: Finished linger-users.service.1130machine # [ 23.266764] nixos[1007]: finished switching to system configuration /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test1131machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test/bin/switch-to-configuration test, in 2.98 seconds)1132machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&11133machine # [ 23.794201] nixos[1174]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1134machine # [ 23.796667] systemd[1]: Stopped target Remote File Systems.1135machine # [ 23.798519] systemd[1]: Stopped target Local File Systems.1136machine # [ 24.196732] systemd[1]: Reload requested from client PID 1174 ('.switch-to-conf') (unit backdoor.service)...1137machine # [ 24.198466] systemd[1]: Reloading...1138machine # [ 24.709095] systemd[1]: Reloading finished in 508 ms.1139machine # [ 24.825154] systemd[628]: Reexecution requested from client PID 1243 ('switch-to-confi')...1140machine # [ 24.826394] systemd[628]: Reexecuting.1141machine # [ 24.856370] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1142machine # [ 24.996462] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1143machine # [ 25.002252] systemd[628]: Reload requested from client PID 1243 ('switch-to-confi')...1144machine # [ 25.003523] systemd[628]: Reloading...1145machine # [ 25.141461] systemd[628]: Reloading finished in 135 ms.1146machine # [ 25.145883] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1147machine # [ 25.152289] systemd[628]: Stopped Run user-specific NixOS activation.1148machine # [ 25.154059] systemd[628]: Stopping Run user-specific NixOS activation...1149machine # [ 25.156573] systemd[628]: Starting Run user-specific NixOS activation...1150machine # [ 25.194802] systemd[628]: Finished Run user-specific NixOS activation.1151machine # [ 25.198331] systemd[1]: Stopped target Reactivate sysinit units.1152machine # [ 25.200086] systemd[1]: Stopping Reactivate sysinit units...1153machine # [ 25.200828] systemd[1]: Reached target Reactivate sysinit units.1154machine # [ 25.203855] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1155machine # [ 25.204894] systemd[1]: Reloading D-Bus System Message Bus...1156machine # [ 25.216741] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1157machine # [ 25.218576] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1158machine # [ 25.219646] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1159machine # [ 25.237085] systemd[1]: Reloaded D-Bus System Message Bus.1160machine # [ 25.242278] systemd[1]: Stopping Address configuration of eth1...1161machine # [ 25.281466] network-addresses-eth1-pre-stop[1255]: deleting address 192.168.1.2/24... done1162machine # [ 25.292043] network-addresses-eth1-pre-stop[1255]: deleting address 2001:db8:1::2/64... done1163machine # [ 25.300462] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1164machine # [ 25.302071] systemd[1]: Stopped Address configuration of eth1.1165machine # [ 25.306368] systemd[1]: Starting Address configuration of eth1...1166machine # [ 25.367929] network-addresses-eth1-start[1260]: adding address 192.168.1.1/24... done1167machine # [ 25.378961] network-addresses-eth1-start[1260]: adding address 2001:db8:1::1/64... done1168machine # [ 25.392054] systemd[1]: Finished Address configuration of eth1.1169machine # [ 25.397917] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1170machine # [ 25.399674] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1171machine # [ 25.401738] systemd[1]: Reached target Local File Systems.1172machine # [ 25.406915] systemd[1]: Reached target Remote File Systems.1173machine # [ 25.411295] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1174machine # [ 25.417130] systemd[1]: Starting Create SUID/SGID Wrappers...1175machine # [ 25.418821] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1176machine # [ 25.420471] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671177machine # [ 25.422812] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1178machine # [ 25.424667] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1179machine # [ 25.427122] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1180machine # [ 25.712930] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1181machine # [ 25.715296] systemd[1]: Finished Create SUID/SGID Wrappers.1182machine # [ 25.720282] systemd[1]: Starting linger-users.service...1183machine # [ 25.768660] systemd[1]: linger-users.service: Deactivated successfully.1184machine # [ 25.773172] systemd[1]: Finished linger-users.service.1185machine # [ 26.030147] nixos[1174]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1186machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.76 seconds)1187subtest: user services1188machine: waiting for unit user@1001.service1189machine: (finished: waiting for unit user@1001.service, in 0.18 seconds)1190machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11191machine # [ 26.752648] nixos[1334]: switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1192machine # [ 26.755533] systemd[1]: Stopped target Local File Systems.1193machine # [ 26.756839] systemd[1]: Stopped target Remote File Systems.1194machine # [ 27.237841] systemd[1]: Reload requested from client PID 1334 ('.switch-to-conf') (unit backdoor.service)...1195machine # [ 27.239533] systemd[1]: Reloading...1196machine # [ 27.723628] systemd[1]: Reloading finished in 481 ms.1197machine # [ 27.835510] systemd[628]: Reexecution requested from client PID 1403 ('switch-to-confi')...1198machine # [ 27.836887] systemd[628]: Reexecuting.1199machine # [ 27.863084] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 27.993580] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 27.998881] systemd[628]: Reload requested from client PID 1403 ('switch-to-confi')...1202machine # [ 28.000327] systemd[628]: Reloading...1203machine # [ 28.128897] systemd[628]: Reloading finished in 127 ms.1204machine # [ 28.132848] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1205machine # [ 28.140794] systemd[628]: Starting usertest.service...1206machine # [ 28.168917] systemd[628]: Finished usertest.service.1207machine # [ 28.172075] systemd[628]: Stopped Run user-specific NixOS activation.1208machine # [ 28.172942] systemd[628]: Stopping Run user-specific NixOS activation...1209machine # [ 28.175412] systemd[628]: Starting Run user-specific NixOS activation...1210machine # [ 28.211234] systemd[628]: Finished Run user-specific NixOS activation.1211machine # [ 28.215088] systemd[1]: Stopped target Reactivate sysinit units.1212machine # [ 28.216280] systemd[1]: Stopping Reactivate sysinit units...1213machine # [ 28.217348] systemd[1]: Reached target Reactivate sysinit units.1214machine # [ 28.223525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1215machine # [ 28.225182] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1216machine # [ 28.227192] systemd[1]: Reached target Remote File Systems.1217machine # [ 28.228776] systemd[1]: Reached target Local File Systems.1218machine # [ 28.234205] systemd[1]: Starting linger-users.service...1219machine # [ 28.240245] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1220machine # [ 28.244051] systemd[1]: Starting Create SUID/SGID Wrappers...1221machine # [ 28.244800] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1222machine # [ 28.247502] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671223machine # [ 28.249524] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1224machine # [ 28.251055] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1225machine # [ 28.252213] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1226machine # [ 28.300742] systemd[1]: linger-users.service: Deactivated successfully.1227machine # [ 28.302316] systemd[1]: Finished linger-users.service.1228machine # [ 28.497157] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1229machine # [ 28.498304] systemd[1]: Finished Create SUID/SGID Wrappers.1230machine # [ 28.756673] nixos[1334]: finished switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1231machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.54 seconds)1232machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1233machine # [ 28.839548] sudo[1473]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1234machine # [ 28.851357] sudo[1473]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1235machine # [ 28.862849] sudo[1473]: pam_unix(sudo:session): session closed for user usertest1236machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.10 seconds)1237machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11238machine # [ 29.294906] nixos[1482]: switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1239machine # [ 29.298399] systemd[1]: Stopped target Local File Systems.1240machine # [ 29.299328] systemd[1]: Stopped target Remote File Systems.1241machine # [ 29.680787] systemd[1]: Reload requested from client PID 1482 ('.switch-to-conf') (unit backdoor.service)...1242machine # [ 29.682506] systemd[1]: Reloading...1243machine # [ 30.144458] systemd[1]: Reloading finished in 460 ms.1244machine # [ 30.264177] systemd[628]: Reexecution requested from client PID 1552 ('switch-to-confi')...1245machine # [ 30.265457] systemd[628]: Reexecuting.1246machine # [ 30.292890] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1247machine # [ 30.433573] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1248machine # [ 30.438620] systemd[628]: Reload requested from client PID 1552 ('switch-to-confi')...1249machine # [ 30.440328] systemd[628]: Reloading...1250machine # [ 30.556054] systemd[628]: Reloading finished in 114 ms.1251machine # [ 30.561835] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1252machine # [ 30.568615] systemd[628]: Stopped Run user-specific NixOS activation.1253machine # [ 30.570140] systemd[628]: Stopping Run user-specific NixOS activation...1254machine # [ 30.572749] systemd[628]: Starting Run user-specific NixOS activation...1255machine # [ 30.608343] systemd[628]: Finished Run user-specific NixOS activation.1256machine # [ 30.611905] systemd[1]: Stopped target Reactivate sysinit units.1257machine # [ 30.613370] systemd[1]: Stopping Reactivate sysinit units...1258machine # [ 30.614527] systemd[1]: Reached target Reactivate sysinit units.1259machine # [ 30.617712] systemd[1]: Reached target Local File Systems.1260machine # [ 30.620231] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1261machine # [ 30.621456] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1262machine # [ 30.622669] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1263machine # [ 30.626844] systemd[1]: Starting Create SUID/SGID Wrappers...1264machine # [ 30.627819] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1265machine # [ 30.628969] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671266machine # [ 30.632155] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1267machine # [ 30.633984] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1268machine # [ 30.636152] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1269machine # [ 30.642623] systemd[1]: Reached target Remote File Systems.1270machine # [ 30.920963] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1271machine # [ 30.922734] systemd[1]: Finished Create SUID/SGID Wrappers.1272machine # [ 30.927564] systemd[1]: Starting linger-users.service...1273machine # [ 30.977498] systemd[1]: linger-users.service: Deactivated successfully.1274machine # [ 30.978571] systemd[1]: Finished linger-users.service.1275machine # [ 31.236434] nixos[1482]: finished switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1276machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.38 seconds)1277machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&11278machine # [ 31.778540] nixos[1623]: switching to system configuration /nix/store/2pwc75bmwp35s45afn0p00dflw4p4c5l-nixos-system-machine-test1279machine # [ 31.781062] systemd[1]: Stopped target Local File Systems.1280machine # [ 31.783155] systemd[1]: Stopped target Remote File Systems.1281machine # [ 32.229071] systemd[1]: Reload requested from client PID 1623 ('.switch-to-conf') (unit backdoor.service)...1282machine # [ 32.230719] systemd[1]: Reloading...1283machine # [ 32.630330] systemd[1]: Reloading finished in 397 ms.1284machine # [ 32.743114] systemd[628]: Stopped usertest.service.1285machine # [ 32.745065] systemd[628]: Reexecution requested from client PID 1693 ('switch-to-confi')...1286machine # [ 32.747099] systemd[628]: Reexecuting.1287machine # [ 32.778303] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1288machine # [ 32.921167] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1289machine # [ 32.926953] systemd[628]: Reload requested from client PID 1693 ('switch-to-confi')...1290machine # [ 32.928243] systemd[628]: Reloading...1291machine # [ 33.063419] systemd[628]: Reloading finished in 133 ms.1292machine # [ 33.068882] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1293machine # [ 33.076350] systemd[628]: Starting usertest.service...1294machine # [ 33.104114] systemd[628]: Finished usertest.service.1295machine # [ 33.107285] systemd[628]: Stopped Run user-specific NixOS activation.1296machine # [ 33.108304] systemd[628]: Stopping Run user-specific NixOS activation...1297machine # [ 33.111274] systemd[628]: Starting Run user-specific NixOS activation...1298machine # [ 33.143421] systemd[628]: Finished Run user-specific NixOS activation.1299machine # [ 33.146842] systemd[1]: Stopped target Reactivate sysinit units.1300machine # [ 33.148535] systemd[1]: Stopping Reactivate sysinit units...1301machine # [ 33.150036] systemd[1]: Reached target Reactivate sysinit units.1302machine # [ 33.154540] systemd[1]: Reached target Local File Systems.1303machine # [ 33.156954] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1304machine # [ 33.158878] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1305machine # [ 33.161500] systemd[1]: Reached target Remote File Systems.1306machine # [ 33.165454] systemd[1]: Starting linger-users.service...1307machine # [ 33.171711] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1308machine # [ 33.174819] systemd[1]: Starting Create SUID/SGID Wrappers...1309machine # [ 33.175657] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1310machine # [ 33.179335] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671311machine # [ 33.181287] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1312machine # [ 33.182725] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1313machine # [ 33.184472] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 33.237767] systemd[1]: linger-users.service: Deactivated successfully.1315machine # [ 33.239502] systemd[1]: Finished linger-users.service.1316machine # [ 33.458276] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1317machine # [ 33.460092] systemd[1]: Finished Create SUID/SGID Wrappers.1318machine # [ 33.718498] nixos[1623]: finished switching to system configuration /nix/store/2pwc75bmwp35s45afn0p00dflw4p4c5l-nixos-system-machine-test1319machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceModified/bin/switch-to-configuration test 2>&1, in 2.48 seconds)1320machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1321machine # [ 33.780939] sudo[1763]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1322machine # [ 33.796573] sudo[1763]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1323machine # [ 33.806309] sudo[1763]: pam_unix(sudo:session): session closed for user usertest1324machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1325machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&11326machine # [ 34.295159] nixos[1772]: switching to system configuration /nix/store/rsvhv8x3s643mrbgylbr28kg132y9kjn-nixos-system-machine-test1327machine # [ 34.297525] systemd[1]: Stopped target Local File Systems.1328machine # [ 34.299478] systemd[1]: Stopped target Remote File Systems.1329machine # [ 34.718414] systemd[1]: Reload requested from client PID 1772 ('.switch-to-conf') (unit backdoor.service)...1330machine # [ 34.720313] systemd[1]: Reloading...1331machine # [ 35.163298] systemd[1]: Reloading finished in 441 ms.1332machine # [ 35.298879] systemd[628]: Reexecution requested from client PID 1841 ('switch-to-confi')...1333machine # [ 35.301604] systemd[628]: Reexecuting.1334machine # [ 35.330948] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1335machine # [ 35.465482] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1336machine # [ 35.470513] systemd[628]: Reload requested from client PID 1841 ('switch-to-confi')...1337machine # [ 35.472162] systemd[628]: Reloading...1338machine # [ 35.610356] systemd[628]: Reloading finished in 137 ms.1339machine # [ 35.614874] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1340machine # [ 35.618832] systemd[628]: Stopped usertest.service.1341machine # [ 35.620240] systemd[628]: Stopping usertest.service...1342machine # [ 35.623137] systemd[628]: Starting usertest.service...1343machine # [ 35.653150] systemd[628]: Finished usertest.service.1344machine # [ 35.658485] systemd[628]: Stopped Run user-specific NixOS activation.1345machine # [ 35.659562] systemd[628]: Stopping Run user-specific NixOS activation...1346machine # [ 35.663486] systemd[628]: Starting Run user-specific NixOS activation...1347machine # [ 35.698700] systemd[628]: Finished Run user-specific NixOS activation.1348machine # [ 35.702617] systemd[1]: Stopped target Reactivate sysinit units.1349machine # [ 35.704045] systemd[1]: Stopping Reactivate sysinit units...1350machine # [ 35.704781] systemd[1]: Reached target Reactivate sysinit units.1351machine # [ 35.707366] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1352machine # [ 35.709381] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1353machine # [ 35.714291] systemd[1]: Reached target Remote File Systems.1354machine # [ 35.717799] systemd[1]: Starting linger-users.service...1355machine # [ 35.723961] systemd[1]: Reached target Local File Systems.1356machine # [ 35.724726] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1357machine # [ 35.729448] systemd[1]: Starting Create SUID/SGID Wrappers...1358machine # [ 35.730312] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1359machine # [ 35.731442] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671360machine # [ 35.733475] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1361machine # [ 35.734931] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1362machine # [ 35.736142] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1363machine # [ 35.787095] systemd[1]: linger-users.service: Deactivated successfully.1364machine # [ 35.788810] systemd[1]: Finished linger-users.service.1365machine # [ 36.034730] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1366machine # [ 36.037139] systemd[1]: Finished Create SUID/SGID Wrappers.1367machine # [ 36.291339] nixos[1772]: finished switching to system configuration /nix/store/rsvhv8x3s643mrbgylbr28kg132y9kjn-nixos-system-machine-test1368machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceNostop/bin/switch-to-configuration test 2>&1, in 2.49 seconds)1369machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1370machine # [ 36.333401] sudo[1912]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1371machine # [ 36.343665] sudo[1912]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1372machine # [ 36.354059] sudo[1912]: pam_unix(sudo:session): session closed for user usertest1373machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1374machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&11375machine # [ 36.799137] nixos[1921]: switching to system configuration /nix/store/fqyjdlyfhzd20gkrkgl3gmiqhcybgini-nixos-system-machine-test1376machine # [ 36.801611] systemd[1]: Stopped target Local File Systems.1377machine # [ 36.803372] systemd[1]: Stopped target Remote File Systems.1378machine # [ 37.258083] systemd[1]: Reload requested from client PID 1921 ('.switch-to-conf') (unit backdoor.service)...1379machine # [ 37.259732] systemd[1]: Reloading...1380machine # [ 37.749172] systemd[1]: Reloading finished in 486 ms.1381machine # [ 37.876626] systemd[628]: Reexecution requested from client PID 1990 ('switch-to-confi')...1382machine # [ 37.879539] systemd[628]: Reexecuting.1383machine # [ 37.902299] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1384machine # [ 38.045591] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1385machine # [ 38.050555] systemd[628]: Reload requested from client PID 1990 ('switch-to-confi')...1386machine # [ 38.052606] systemd[628]: Reloading...1387machine # [ 38.189392] systemd[628]: Reloading finished in 134 ms.1388machine # [ 38.192832] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1389machine # [ 38.198932] systemd[628]: Reloading usertest.service...1390machine # [ 38.224553] systemd[628]: Reloaded usertest.service.1391machine # [ 38.230089] systemd[628]: Stopped Run user-specific NixOS activation.1392machine # [ 38.230934] systemd[628]: Stopping Run user-specific NixOS activation...1393machine # [ 38.233260] systemd[628]: Starting Run user-specific NixOS activation...1394machine # [ 38.262707] systemd[628]: Finished Run user-specific NixOS activation.1395machine # [ 38.266735] systemd[1]: Stopped target Reactivate sysinit units.1396machine # [ 38.268714] systemd[1]: Stopping Reactivate sysinit units...1397machine # [ 38.269623] systemd[1]: Reached target Reactivate sysinit units.1398machine # [ 38.274119] systemd[1]: Reached target Local File Systems.1399machine # [ 38.276171] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1400machine # [ 38.280791] systemd[1]: Starting Create SUID/SGID Wrappers...1401machine # [ 38.281629] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1402machine # [ 38.282809] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671403machine # [ 38.285713] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1404machine # [ 38.288312] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1405machine # [ 38.289890] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1406machine # [ 38.292123] systemd[1]: Reached target Remote File Systems.1407machine # [ 38.303497] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1408machine # [ 38.305245] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1409machine # [ 38.596963] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1410machine # [ 38.600241] systemd[1]: Finished Create SUID/SGID Wrappers.1411machine # [ 38.603147] systemd[1]: Starting linger-users.service...1412machine # [ 38.651555] systemd[1]: linger-users.service: Deactivated successfully.1413machine # [ 38.652720] systemd[1]: Finished linger-users.service.1414machine # [ 38.910591] nixos[1921]: finished switching to system configuration /nix/store/fqyjdlyfhzd20gkrkgl3gmiqhcybgini-nixos-system-machine-test1415machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceReload/bin/switch-to-configuration test 2>&1, in 2.56 seconds)1416machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1417machine # [ 38.968705] sudo[2061]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1418machine # [ 38.982587] sudo[2061]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1419machine # [ 38.992609] sudo[2061]: pam_unix(sudo:session): session closed for user usertest1420machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1421machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11422machine # [ 39.466784] nixos[2070]: switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1423machine # [ 39.469262] systemd[1]: Stopped target Local File Systems.1424machine # [ 39.470924] systemd[1]: Stopped target Remote File Systems.1425machine # [ 39.882167] systemd[1]: Reload requested from client PID 2070 ('.switch-to-conf') (unit backdoor.service)...1426machine # [ 39.883938] systemd[1]: Reloading...1427machine # [ 40.701317] systemd[1]: Reloading finished in 814 ms.1428machine # [ 40.825527] systemd[628]: Stopped usertest.service.1429machine # [ 40.827057] systemd[628]: Reexecution requested from client PID 2139 ('switch-to-confi')...1430machine # [ 40.829762] systemd[628]: Reexecuting.1431machine # [ 40.855309] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1432machine # [ 40.981805] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1433machine # [ 40.987154] systemd[628]: Reload requested from client PID 2139 ('switch-to-confi')...1434machine # [ 40.988371] systemd[628]: Reloading...1435machine # [ 41.127161] systemd[628]: Reloading finished in 136 ms.1436machine # [ 41.132099] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 41.139696] systemd[628]: Starting usertest.service...1438machine # [ 41.167932] systemd[628]: Finished usertest.service.1439machine # [ 41.171078] systemd[628]: Stopped Run user-specific NixOS activation.1440machine # [ 41.171932] systemd[628]: Stopping Run user-specific NixOS activation...1441machine # [ 41.174578] systemd[628]: Starting Run user-specific NixOS activation...1442machine # [ 41.211721] systemd[628]: Finished Run user-specific NixOS activation.1443machine # [ 41.215084] systemd[1]: Stopped target Reactivate sysinit units.1444machine # [ 41.216668] systemd[1]: Stopping Reactivate sysinit units...1445machine # [ 41.217789] systemd[1]: Reached target Reactivate sysinit units.1446machine # [ 41.221122] systemd[1]: Reached target Remote File Systems.1447machine # [ 41.223889] systemd[1]: Starting linger-users.service...1448machine # [ 41.234247] systemd[1]: Reached target Local File Systems.1449machine # [ 41.235269] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1450machine # [ 41.240259] systemd[1]: Starting Create SUID/SGID Wrappers...1451machine # [ 41.241690] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1452machine # [ 41.243840] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671453machine # [ 41.246628] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1454machine # [ 41.248885] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1455machine # [ 41.251969] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1456machine # [ 41.254708] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1457machine # [ 41.256318] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1458machine # [ 41.300041] systemd[1]: linger-users.service: Deactivated successfully.1459machine # [ 41.301897] systemd[1]: Finished linger-users.service.1460machine # [ 41.526657] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1461machine # [ 41.528523] systemd[1]: Finished Create SUID/SGID Wrappers.1462machine # [ 41.783045] nixos[2070]: finished switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1463machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.79 seconds)1464machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1465machine # [ 41.821814] sudo[2210]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1466machine # [ 41.831709] sudo[2210]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1467machine # [ 41.841230] sudo[2210]: pam_unix(sudo:session): session closed for user usertest1468machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.06 seconds)1469machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&11470machine # [ 42.250123] nixos[2219]: switching to system configuration /nix/store/ky9xqnrgw257z6nmml3liksm3xvx2qkb-nixos-system-machine-test1471machine # [ 42.252514] systemd[1]: Stopped target Local File Systems.1472machine # [ 42.254173] systemd[1]: Stopped target Remote File Systems.1473machine # [ 42.688705] systemd[1]: Reload requested from client PID 2219 ('.switch-to-conf') (unit backdoor.service)...1474machine # [ 42.690726] systemd[1]: Reloading...1475machine # [ 43.188484] systemd[1]: Reloading finished in 495 ms.1476machine # [ 43.325329] systemd[628]: Reexecution requested from client PID 2288 ('switch-to-confi')...1477machine # [ 43.327424] systemd[628]: Reexecuting.1478machine # [ 43.358153] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1479machine # [ 43.527823] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1480machine # [ 43.534086] systemd[628]: Reload requested from client PID 2288 ('switch-to-confi')...1481machine # [ 43.535514] systemd[628]: Reloading...1482machine # [ 43.663105] systemd[628]: Reloading finished in 125 ms.1483machine # [ 43.667874] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 43.674828] systemd[628]: Reloading usertest.service...1485machine # [ 43.699994] systemd[628]: Reloaded usertest.service.1486machine # [ 43.706069] systemd[628]: Stopped Run user-specific NixOS activation.1487machine # [ 43.706980] systemd[628]: Stopping Run user-specific NixOS activation...1488machine # [ 43.709870] systemd[628]: Starting Run user-specific NixOS activation...1489machine # [ 43.736896] systemd[628]: Finished Run user-specific NixOS activation.1490machine # [ 43.740297] systemd[1]: Stopped target Reactivate sysinit units.1491machine # [ 43.742197] systemd[1]: Stopping Reactivate sysinit units...1492machine # [ 43.742940] systemd[1]: Reached target Reactivate sysinit units.1493machine # [ 43.744492] systemd[1]: Reached target Remote File Systems.1494machine # [ 43.747973] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1495machine # [ 43.750483] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1496machine # [ 43.753560] systemd[1]: Starting linger-users.service...1497machine # [ 43.762714] systemd[1]: Reached target Local File Systems.1498machine # [ 43.764664] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1499machine # [ 43.767378] systemd[1]: Starting Create SUID/SGID Wrappers...1500machine # [ 43.769232] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1501machine # [ 43.771206] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671502machine # [ 43.775294] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1503machine # [ 43.777641] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1504machine # [ 43.780156] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1505machine # [ 43.828621] systemd[1]: linger-users.service: Deactivated successfully.1506machine # [ 43.830690] systemd[1]: Finished linger-users.service.1507machine # [ 44.055726] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1508machine # [ 44.057715] systemd[1]: Finished Create SUID/SGID Wrappers.1509machine # [ 44.312647] nixos[2219]: finished switching to system configuration /nix/store/ky9xqnrgw257z6nmml3liksm3xvx2qkb-nixos-system-machine-test1510machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceReloadTrigger/bin/switch-to-configuration test 2>&1, in 2.47 seconds)1511machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1512machine # [ 44.358565] sudo[2358]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1513machine # [ 44.369618] sudo[2358]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1514machine # [ 44.379400] sudo[2358]: pam_unix(sudo:session): session closed for user usertest1515machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.07 seconds)1516machine: must fail: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&11517machine # [ 44.800692] nixos[2367]: switching to system configuration /nix/store/89wqn5mvq7cq11pr8bkw7fdjsr1zr82v-nixos-system-machine-test1518machine # [ 44.803635] systemd[1]: Stopped target Remote File Systems.1519machine # [ 44.805435] systemd[1]: Stopped target Local File Systems.1520machine # [ 45.216810] systemd[1]: Reload requested from client PID 2367 ('.switch-to-conf') (unit backdoor.service)...1521machine # [ 45.218552] systemd[1]: Reloading...1522machine # [ 45.659370] systemd[1]: Reloading finished in 438 ms.1523machine # [ 45.788457] systemd[628]: Stopped usertest.service.1524machine # [ 45.791258] systemd[628]: Reexecution requested from client PID 2436 ('switch-to-confi')...1525machine # [ 45.792457] systemd[628]: Reexecuting.1526machine # [ 45.819841] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1527machine # [ 45.968214] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1528machine # [ 45.973682] systemd[628]: Reload requested from client PID 2436 ('switch-to-confi')...1529machine # [ 45.975189] systemd[628]: Reloading...1530machine # [ 46.114712] systemd[628]: Reloading finished in 138 ms.1531machine # [ 46.119870] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1532machine # [ 46.127617] systemd[628]: Starting usertest.service...1533machine # [ 46.155524] systemd[628]: usertest.service: Main process exited, code=exited, status=1/FAILURE1534machine # [ 46.156892] systemd[628]: usertest.service: Failed with result 'exit-code'.1535machine # [ 46.158592] systemd[628]: Failed to start usertest.service.1536machine # [ 46.161580] systemd[628]: Stopped Run user-specific NixOS activation.1537machine # [ 46.163103] systemd[628]: Stopping Run user-specific NixOS activation...1538machine # [ 46.165764] systemd[628]: Starting Run user-specific NixOS activation...1539machine # [ 46.200169] systemd[628]: Finished Run user-specific NixOS activation.1540machine # [ 46.203984] systemd[1]: Stopped target Reactivate sysinit units.1541machine # [ 46.205484] systemd[1]: Stopping Reactivate sysinit units...1542machine # [ 46.206587] systemd[1]: Reached target Reactivate sysinit units.1543machine # [ 46.207913] systemd[1]: Reached target Local File Systems.1544machine # [ 46.210994] systemd[1]: Reached target Remote File Systems.1545machine # [ 46.215966] systemd[1]: Starting linger-users.service...1546machine # [ 46.223554] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1547machine # [ 46.226110] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1548machine # [ 46.229867] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1549machine # [ 46.235104] systemd[1]: Starting Create SUID/SGID Wrappers...1550machine # [ 46.236288] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1551machine # [ 46.238921] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671552machine # [ 46.241517] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1553machine # [ 46.243161] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1554machine # [ 46.244478] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 46.296507] systemd[1]: linger-users.service: Deactivated successfully.1556machine # [ 46.298360] systemd[1]: Finished linger-users.service.1557machine # [ 46.539209] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1558machine # [ 46.541255] systemd[1]: Finished Create SUID/SGID Wrappers.1559machine # [ 46.798534] nixos[2367]: switching to system configuration /nix/store/89wqn5mvq7cq11pr8bkw7fdjsr1zr82v-nixos-system-machine-test failed (status 4)1560machine: (finished: must fail: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserServiceFailing/bin/switch-to-configuration test 2>&1, in 2.42 seconds)1561machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&11562machine # [ 47.294772] nixos[2509]: switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1563machine # [ 47.298573] systemd[1]: Stopped target Remote File Systems.1564machine # [ 47.299479] systemd[1]: Stopped target Local File Systems.1565machine # [ 47.635236] systemd[1]: Reload requested from client PID 2509 ('.switch-to-conf') (unit backdoor.service)...1566machine # [ 47.636899] systemd[1]: Reloading...1567machine # [ 48.302647] systemd[1]: Reloading finished in 663 ms.1568machine # [ 48.410778] systemd[628]: Reexecution requested from client PID 2578 ('switch-to-confi')...1569machine # [ 48.413805] systemd[628]: Reexecuting.1570machine # [ 48.440742] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1571machine # [ 48.575575] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1572machine # [ 48.581042] systemd[628]: Reload requested from client PID 2578 ('switch-to-confi')...1573machine # [ 48.582760] systemd[628]: Reloading...1574machine # [ 48.716707] systemd[628]: Reloading finished in 131 ms.1575machine # [ 48.719842] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1576machine # [ 48.727103] systemd[628]: Starting usertest.service...1577machine # [ 48.753619] systemd[628]: Finished usertest.service.1578machine # [ 48.756411] systemd[628]: Stopped Run user-specific NixOS activation.1579machine # [ 48.757349] systemd[628]: Stopping Run user-specific NixOS activation...1580machine # [ 48.760046] systemd[628]: Starting Run user-specific NixOS activation...1581machine # [ 48.793650] systemd[628]: Finished Run user-specific NixOS activation.1582machine # [ 48.796965] systemd[1]: Stopped target Reactivate sysinit units.1583machine # [ 48.798202] systemd[1]: Stopping Reactivate sysinit units...1584machine # [ 48.798925] systemd[1]: Reached target Reactivate sysinit units.1585machine # [ 48.803725] systemd[1]: Reached target Remote File Systems.1586machine # [ 48.807390] systemd[1]: Starting linger-users.service...1587machine # [ 48.814059] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1588machine # [ 48.815284] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1589machine # [ 48.817461] systemd[1]: Reached target Local File Systems.1590machine # [ 48.821771] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1591machine # [ 48.827098] systemd[1]: Starting Create SUID/SGID Wrappers...1592machine # [ 48.827879] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1593machine # [ 48.830170] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671594machine # [ 48.832848] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1595machine # [ 48.835196] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1596machine # [ 48.837233] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1597machine # [ 48.882552] systemd[1]: linger-users.service: Deactivated successfully.1598machine # [ 48.884906] systemd[1]: Finished linger-users.service.1599machine # [ 49.112353] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1600machine # [ 49.114454] systemd[1]: Finished Create SUID/SGID Wrappers.1601machine # [ 49.371581] nixos[2509]: finished switching to system configuration /nix/store/74jijcfb869g27zlnayqwp72ql5jjkfr-nixos-system-machine-test1602machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/simpleUserService/bin/switch-to-configuration test 2>&1, in 2.57 seconds)1603machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1604machine # [ 49.430405] sudo[2648]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1605machine # [ 49.442855] sudo[2648]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1606machine # [ 49.451896] sudo[2648]: pam_unix(sudo:session): session closed for user usertest1607machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.08 seconds)1608machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&11609machine # [ 49.860397] nixos[2657]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1610machine # [ 49.863104] systemd[1]: Stopped target Remote File Systems.1611machine # [ 49.864876] systemd[1]: Stopped target Local File Systems.1612machine # [ 50.220068] systemd[1]: Reload requested from client PID 2657 ('.switch-to-conf') (unit backdoor.service)...1613machine # [ 50.221763] systemd[1]: Reloading...1614machine # [ 50.626149] systemd[1]: Reloading finished in 401 ms.1615machine # [ 50.715774] systemd[628]: Stopped usertest.service.1616machine # [ 50.717887] systemd[628]: Reexecution requested from client PID 2726 ('switch-to-confi')...1617machine # [ 50.719097] systemd[628]: Reexecuting.1618machine # [ 50.745683] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1619machine # [ 50.871393] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1620machine # [ 50.875708] systemd[628]: Reload requested from client PID 2726 ('switch-to-confi')...1621machine # [ 50.877627] systemd[628]: Reloading...1622machine # [ 50.993745] systemd[628]: Reloading finished in 114 ms.1623machine # [ 50.997848] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1624machine # [ 51.004067] systemd[628]: Stopped Run user-specific NixOS activation.1625machine # [ 51.005664] systemd[628]: Stopping Run user-specific NixOS activation...1626machine # [ 51.009128] systemd[628]: Starting Run user-specific NixOS activation...1627machine # [ 51.039593] systemd[628]: Finished Run user-specific NixOS activation.1628machine # [ 51.043648] systemd[1]: Stopped target Reactivate sysinit units.1629machine # [ 51.044893] systemd[1]: Stopping Reactivate sysinit units...1630machine # [ 51.046179] systemd[1]: Reached target Reactivate sysinit units.1631machine # [ 51.047565] systemd[1]: Reached target Remote File Systems.1632machine # [ 51.049774] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1633machine # [ 51.051878] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1634machine # [ 51.057274] systemd[1]: Reached target Local File Systems.1635machine # [ 51.058990] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1636machine # [ 51.062208] systemd[1]: Starting Create SUID/SGID Wrappers...1637machine # [ 51.063980] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1638machine # [ 51.065338] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671639machine # [ 51.067629] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1640machine # [ 51.070192] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1641machine # [ 51.072305] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1642machine # [ 51.319247] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1643machine # [ 51.321086] systemd[1]: Finished Create SUID/SGID Wrappers.1644machine # [ 51.325476] systemd[1]: Starting linger-users.service...1645machine # [ 51.376615] systemd[1]: linger-users.service: Deactivated successfully.1646machine # [ 51.378444] systemd[1]: Finished linger-users.service.1647machine # [ 51.636762] nixos[2657]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1648machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.19 seconds)1649machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service1650machine # [ 51.702748] sudo[2794]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active usertest.service1651machine # [ 51.717463] sudo[2794]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1652machine # [ 51.729921] sudo[2794]: pam_unix(sudo:session): session closed for user usertest1653machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active usertest.service, in 0.09 seconds)1654machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1655machine # [ 51.771400] sudo[2800]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1656machine # [ 51.781760] sudo[2800]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1657machine # [ 51.793061] sudo[2800]: pam_unix(sudo:session): session closed for user usertest1658machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1659machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1660machine # [ 51.830876] sudo[2806]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1661machine # [ 51.841829] sudo[2806]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1662machine # [ 51.854063] sudo[2806]: pam_unix(sudo:session): session closed for user usertest1663machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)1664machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1665machine # [ 51.892623] sudo[2812]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1666machine # [ 51.903748] sudo[2812]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1667machine # [ 51.914601] sudo[2812]: pam_unix(sudo:session): session closed for user usertest1668machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)1669machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1670machine # [ 51.954143] sudo[2818]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1671machine # [ 51.965318] sudo[2818]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1672machine # [ 51.973885] systemd[628]: Reload requested from client PID 2823 ('systemctl')...1673machine # [ 51.975364] systemd[628]: Reloading...1674machine # [ 52.115333] systemd[628]: Reloading finished in 138 ms.1675machine # [ 52.120863] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1676machine # [ 52.127950] sudo[2818]: pam_unix(sudo:session): session closed for user usertest1677machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)1678machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1679machine # [ 52.168427] sudo[2828]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1680machine # [ 52.179684] sudo[2828]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1681machine # [ 52.194052] systemd[628]: Starting migrated.service...1682machine # [ 52.224548] systemd[628]: Finished migrated.service.1683machine # [ 52.228071] sudo[2828]: pam_unix(sudo:session): session closed for user usertest1684machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)1685machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1686machine # [ 52.261662] sudo[2836]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1687machine # [ 52.270904] sudo[2836]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1688machine # [ 52.281192] sudo[2836]: pam_unix(sudo:session): session closed for user usertest1689machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1690machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1691machine # [ 52.324065] sudo[2842]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1692machine # [ 52.336591] sudo[2842]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1693machine # [ 52.348107] sudo[2842]: pam_unix(sudo:session): session closed for user usertest1694machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1695machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1696machine # [ 52.389589] sudo[2848]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1697machine # [ 52.400351] sudo[2848]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1698machine # [ 52.417451] sudo[2848]: pam_unix(sudo:session): session closed for user usertest1699machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1700machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&11701machine # [ 52.905792] nixos[2857]: switching to system configuration /nix/store/0mm6v71pr9rd0rjx9c03ix4m6kqm3y2i-nixos-system-machine-test1702machine # [ 52.908433] systemd[1]: Stopped target Remote File Systems.1703machine # [ 52.910253] systemd[1]: Stopped target Local File Systems.1704machine # [ 53.329987] systemd[1]: Reload requested from client PID 2857 ('.switch-to-conf') (unit backdoor.service)...1705machine # [ 53.331763] systemd[1]: Reloading...1706machine # [ 53.796951] systemd[1]: Reloading finished in 463 ms.1707machine # [ 53.930628] systemd[628]: Reexecution requested from client PID 2926 ('switch-to-confi')...1708machine # [ 53.931911] systemd[628]: Reexecuting.1709machine # [ 53.960872] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1710machine # [ 54.110478] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1711machine # [ 54.115577] systemd[628]: Reload requested from client PID 2926 ('switch-to-confi')...1712machine # [ 54.117352] systemd[628]: Reloading...1713machine # [ 54.262144] systemd[628]: Reloading finished in 143 ms.1714machine # [ 54.267847] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1715machine # [ 54.274434] systemd[628]: Stopped Run user-specific NixOS activation.1716machine # [ 54.276074] systemd[628]: Stopping Run user-specific NixOS activation...1717machine # [ 54.278672] systemd[628]: Starting Run user-specific NixOS activation...1718machine # [ 54.353078] systemd[628]: Reload requested from client PID 2938 ('systemctl') (unit nixos-activation.service)...1719machine # [ 54.355059] systemd[628]: Reloading...1720machine # [ 54.496657] systemd[628]: Reloading finished in 139 ms.1721machine # [ 54.501039] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1722machine # [ 54.518651] systemd[628]: Stopped migrated.service.1723machine # [ 54.528626] systemd[628]: Finished Run user-specific NixOS activation.1724machine # [ 54.530778] systemd[628]: Reload requested from client PID 2926 ('switch-to-confi')...1725machine # [ 54.532333] systemd[628]: Reloading...1726machine # [ 54.670866] systemd[628]: Reloading finished in 136 ms.1727machine # [ 54.675866] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1728machine # [ 54.685591] systemd[628]: Starting migrated.service...1729machine # [ 54.716062] systemd[628]: Finished migrated.service.1730machine # [ 54.719485] systemd[1]: Stopped target Reactivate sysinit units.1731machine # [ 54.721194] systemd[1]: Stopping Reactivate sysinit units...1732machine # [ 54.721973] systemd[1]: Reached target Reactivate sysinit units.1733machine # [ 54.723618] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1734machine # [ 54.725351] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1735machine # [ 54.730387] systemd[1]: Reached target Remote File Systems.1736machine # [ 54.734107] systemd[1]: Starting linger-users.service...1737machine # [ 54.741851] systemd[1]: Reached target Local File Systems.1738machine # [ 54.745069] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1739machine # [ 54.750576] systemd[1]: Starting Create SUID/SGID Wrappers...1740machine # [ 54.751918] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1741machine # [ 54.753733] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671742machine # [ 54.755685] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1743machine # [ 54.757772] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1744machine # [ 54.759367] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1745machine # [ 54.806907] systemd[1]: linger-users.service: Deactivated successfully.1746machine # [ 54.807972] systemd[1]: Finished linger-users.service.1747machine # [ 55.021790] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1748machine # [ 55.023632] systemd[1]: Finished Create SUID/SGID Wrappers.1749machine # [ 55.281444] nixos[2857]: finished switching to system configuration /nix/store/0mm6v71pr9rd0rjx9c03ix4m6kqm3y2i-nixos-system-machine-test1750machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixos/bin/switch-to-configuration test 2>&1, in 2.87 seconds)1751machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1752machine # [ 55.316543] sudo[3008]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1753machine # [ 55.326990] sudo[3008]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1754machine # [ 55.336279] sudo[3008]: pam_unix(sudo:session): session closed for user usertest1755machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)1756machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1757machine # [ 55.374587] sudo[3014]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1758machine # [ 55.385661] sudo[3014]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1759machine # [ 55.410655] sudo[3014]: pam_unix(sudo:session): session closed for user usertest1760machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1761machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1762machine # [ 55.450852] sudo[3020]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1763machine # [ 55.461808] sudo[3020]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1764machine # [ 55.472663] sudo[3020]: pam_unix(sudo:session): session closed for user usertest1765machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1766machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&11767machine # [ 55.920282] nixos[3029]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1768machine # [ 55.922550] systemd[1]: Stopped target Local File Systems.1769machine # [ 55.924315] systemd[1]: Stopped target Remote File Systems.1770machine # [ 56.288710] systemd[1]: Reload requested from client PID 3029 ('.switch-to-conf') (unit backdoor.service)...1771machine # [ 56.290725] systemd[1]: Reloading...1772machine # [ 56.745269] systemd[1]: Reloading finished in 451 ms.1773machine # [ 56.872605] systemd[628]: Stopped migrated.service.1774machine # [ 56.874208] systemd[628]: Reexecution requested from client PID 3098 ('switch-to-confi')...1775machine # [ 56.876865] systemd[628]: Reexecuting.1776machine # [ 56.903376] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1777machine # [ 57.042486] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1778machine # [ 57.050141] systemd[628]: Reload requested from client PID 3098 ('switch-to-confi')...1779machine # [ 57.052553] systemd[628]: Reloading...1780machine # [ 57.187728] systemd[628]: Reloading finished in 133 ms.1781machine # [ 57.193905] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1782machine # [ 57.200628] systemd[628]: Stopped Run user-specific NixOS activation.1783machine # [ 57.202250] systemd[628]: Stopping Run user-specific NixOS activation...1784machine # [ 57.205247] systemd[628]: Starting Run user-specific NixOS activation...1785machine # [ 57.240987] systemd[628]: Finished Run user-specific NixOS activation.1786machine # [ 57.244882] systemd[1]: Stopped target Reactivate sysinit units.1787machine # [ 57.246293] systemd[1]: Stopping Reactivate sysinit units...1788machine # [ 57.247451] systemd[1]: Reached target Reactivate sysinit units.1789machine # [ 57.250096] systemd[1]: Reached target Local File Systems.1790machine # [ 57.253891] systemd[1]: Reached target Remote File Systems.1791machine # [ 57.257362] systemd[1]: Starting linger-users.service...1792machine # [ 57.263434] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1793machine # [ 57.266735] systemd[1]: Starting Create SUID/SGID Wrappers...1794machine # [ 57.268131] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1795machine # [ 57.269466] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671796machine # [ 57.272211] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1797machine # [ 57.275119] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1798machine # [ 57.277373] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1799machine # [ 57.283462] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1800machine # [ 57.284659] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1801machine # [ 57.339368] systemd[1]: linger-users.service: Deactivated successfully.1802machine # [ 57.341171] systemd[1]: Finished linger-users.service.1803machine # [ 57.534151] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1804machine # [ 57.535814] systemd[1]: Finished Create SUID/SGID Wrappers.1805machine # [ 57.792640] nixos[3029]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1806machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.32 seconds)1807machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1808machine # [ 57.834601] sudo[3166]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1809machine # [ 57.844328] sudo[3166]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1810machine # [ 57.855483] sudo[3166]: pam_unix(sudo:session): session closed for user usertest1811machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1812machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1813machine # [ 57.889735] sudo[3172]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1814machine # [ 57.900697] sudo[3172]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1815machine # [ 57.910201] sudo[3172]: pam_unix(sudo:session): session closed for user usertest1816machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)1817machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1818machine # [ 57.944300] sudo[3178]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1819machine # [ 57.955686] sudo[3178]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1820machine # [ 57.966310] sudo[3178]: pam_unix(sudo:session): session closed for user usertest1821machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)1822machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1823machine # [ 57.999934] sudo[3184]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1824machine # [ 58.010499] sudo[3184]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1825machine # [ 58.019296] sudo[3184]: pam_unix(sudo:session): session closed for user usertest1826machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.05 seconds)1827machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1828machine # [ 58.051039] sudo[3190]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1829machine # [ 58.060574] sudo[3190]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1830machine # [ 58.069145] systemd[628]: Reload requested from client PID 3195 ('systemctl')...1831machine # [ 58.070931] systemd[628]: Reloading...1832machine # [ 58.226581] systemd[628]: Reloading finished in 154 ms.1833machine # [ 58.230893] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1834machine # [ 58.239242] sudo[3190]: pam_unix(sudo:session): session closed for user usertest1835machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.22 seconds)1836machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1837machine # [ 58.274322] sudo[3200]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1838machine # [ 58.285061] sudo[3200]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1839machine # [ 58.298955] systemd[628]: Starting migrated.service...1840machine # [ 58.329952] systemd[628]: Finished migrated.service.1841machine # [ 58.333917] sudo[3200]: pam_unix(sudo:session): session closed for user usertest1842machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)1843machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1844machine # [ 58.374421] sudo[3208]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1845machine # [ 58.384640] sudo[3208]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1846machine # [ 58.394452] sudo[3208]: pam_unix(sudo:session): session closed for user usertest1847machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)1848machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1849machine # [ 58.429982] sudo[3214]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1850machine # [ 58.439863] sudo[3214]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1851machine # [ 58.450053] sudo[3214]: pam_unix(sudo:session): session closed for user usertest1852machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)1853machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1854machine # [ 58.489700] sudo[3220]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1855machine # [ 58.500354] sudo[3220]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1856machine # [ 58.517797] sudo[3220]: pam_unix(sudo:session): session closed for user usertest1857machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)1858machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&11859machine # [ 58.972139] nixos[3229]: switching to system configuration /nix/store/4rch35vi708pp9q1vrrfvsd338117zdv-nixos-system-machine-test1860machine # [ 58.974517] systemd[1]: Stopped target Local File Systems.1861machine # [ 58.976438] systemd[1]: Stopped target Remote File Systems.1862machine # [ 59.408769] systemd[1]: Reload requested from client PID 3229 ('.switch-to-conf') (unit backdoor.service)...1863machine # [ 59.410553] systemd[1]: Reloading...1864machine # [ 59.881408] systemd[1]: Reloading finished in 468 ms.1865machine # [ 60.006762] systemd[628]: Reexecution requested from client PID 3298 ('switch-to-confi')...1866machine # [ 60.008736] systemd[628]: Reexecuting.1867machine # [ 60.042677] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1868machine # [ 60.188507] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1869machine # [ 60.194253] systemd[628]: Reload requested from client PID 3298 ('switch-to-confi')...1870machine # [ 60.195527] systemd[628]: Reloading...1871machine # [ 60.331913] systemd[628]: Reloading finished in 133 ms.1872machine # [ 60.336869] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1873machine # [ 60.343336] systemd[628]: Stopped Run user-specific NixOS activation.1874machine # [ 60.345063] systemd[628]: Stopping Run user-specific NixOS activation...1875machine # [ 60.347348] systemd[628]: Starting Run user-specific NixOS activation...1876machine # [ 60.395782] systemd[628]: Reload requested from client PID 3310 ('systemctl') (unit nixos-activation.service)...1877machine # [ 60.397559] systemd[628]: Reloading...1878machine # [ 60.527667] systemd[628]: Reloading finished in 127 ms.1879machine # [ 60.531136] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1880machine # [ 60.545837] systemd[628]: Finished Run user-specific NixOS activation.1881machine # [ 60.548136] systemd[628]: Reload requested from client PID 3298 ('switch-to-confi')...1882machine # [ 60.549577] systemd[628]: Reloading...1883machine # [ 60.682819] systemd[628]: Reloading finished in 131 ms.1884machine # [ 60.688856] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1885machine # [ 60.696401] systemd[628]: Stopped migrated.service.1886machine # [ 60.697268] systemd[628]: Stopping migrated.service...1887machine # [ 60.700477] systemd[628]: Starting migrated.service...1888machine # [ 60.729426] systemd[628]: Finished migrated.service.1889machine # [ 60.735201] systemd[1]: Stopped target Reactivate sysinit units.1890machine # [ 60.736617] systemd[1]: Stopping Reactivate sysinit units...1891machine # [ 60.737631] systemd[1]: Reached target Reactivate sysinit units.1892machine # [ 60.739121] systemd[1]: Reached target Remote File Systems.1893machine # [ 60.743545] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1894machine # [ 60.745546] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1895machine # [ 60.749024] systemd[1]: Reached target Local File Systems.1896machine # [ 60.752329] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1897machine # [ 60.757117] systemd[1]: Starting Create SUID/SGID Wrappers...1898machine # [ 60.757886] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1899machine # [ 60.760143] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671900machine # [ 60.762562] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1901machine # [ 60.764658] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 60.767139] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1903machine # [ 61.005257] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1904machine # [ 61.006883] systemd[1]: Finished Create SUID/SGID Wrappers.1905machine # [ 61.011763] systemd[1]: Starting linger-users.service...1906machine # [ 61.056882] systemd[1]: linger-users.service: Deactivated successfully.1907machine # [ 61.058598] systemd[1]: Finished linger-users.service.1908machine # [ 61.316896] nixos[3229]: finished switching to system configuration /nix/store/4rch35vi708pp9q1vrrfvsd338117zdv-nixos-system-machine-test1909machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoStop/bin/switch-to-configuration test 2>&1, in 2.81 seconds)1910machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1911machine # [ 61.384734] sudo[3379]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1912machine # [ 61.399521] sudo[3379]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1913machine # [ 61.408933] sudo[3379]: pam_unix(sudo:session): session closed for user usertest1914machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1915machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service1916machine # [ 61.439860] sudo[3385]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service1917machine # [ 61.449302] sudo[3385]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1918machine # [ 61.472886] sudo[3385]: pam_unix(sudo:session): session closed for user usertest1919machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)1920machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner1921machine # [ 61.514468] sudo[3391]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner1922machine # [ 61.525763] sudo[3391]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1923machine # [ 61.543200] sudo[3391]: pam_unix(sudo:session): session closed for user usertest1924machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)1925machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&11926machine # [ 61.961815] nixos[3400]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1927machine # [ 61.965538] systemd[1]: Stopped target Remote File Systems.1928machine # [ 61.966382] systemd[1]: Stopped target Local File Systems.1929machine # [ 62.342662] systemd[1]: Reload requested from client PID 3400 ('.switch-to-conf') (unit backdoor.service)...1930machine # [ 62.344397] systemd[1]: Reloading...1931machine # [ 63.451213] systemd[1]: Reloading finished in 1104 ms.1932machine # [ 63.579727] systemd[628]: Stopped migrated.service.1933machine # [ 63.581294] systemd[628]: Reexecution requested from client PID 3469 ('switch-to-confi')...1934machine # [ 63.584164] systemd[628]: Reexecuting.1935machine # [ 63.614192] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1936machine # [ 63.753370] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1937machine # [ 63.758779] systemd[628]: Reload requested from client PID 3469 ('switch-to-confi')...1938machine # [ 63.760068] systemd[628]: Reloading...1939machine # [ 63.893318] systemd[628]: Reloading finished in 131 ms.1940machine # [ 63.897900] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1941machine # [ 63.904670] systemd[628]: Stopped Run user-specific NixOS activation.1942machine # [ 63.906093] systemd[628]: Stopping Run user-specific NixOS activation...1943machine # [ 63.909119] systemd[628]: Starting Run user-specific NixOS activation...1944machine # [ 63.943278] systemd[628]: Finished Run user-specific NixOS activation.1945machine # [ 63.946637] systemd[1]: Stopped target Reactivate sysinit units.1946machine # [ 63.947903] systemd[1]: Stopping Reactivate sysinit units...1947machine # [ 63.948944] systemd[1]: Reached target Reactivate sysinit units.1948machine # [ 63.955425] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1949machine # [ 63.957114] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1950machine # [ 63.960845] systemd[1]: Reached target Remote File Systems.1951machine # [ 63.964517] systemd[1]: Starting linger-users.service...1952machine # [ 63.970785] systemd[1]: Reached target Local File Systems.1953machine # [ 63.971696] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1954machine # [ 63.975443] systemd[1]: Starting Create SUID/SGID Wrappers...1955machine # [ 63.976281] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1956machine # [ 63.977446] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671957machine # [ 63.979448] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1958machine # [ 63.980880] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1959machine # [ 63.983893] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1960machine # [ 64.037721] systemd[1]: linger-users.service: Deactivated successfully.1961machine # [ 64.039410] systemd[1]: Finished linger-users.service.1962machine # [ 64.266290] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1963machine # [ 64.269254] systemd[1]: Finished Create SUID/SGID Wrappers.1964machine # [ 64.526577] nixos[3400]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test1965machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.99 seconds)1966machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service1967machine # [ 64.581925] sudo[3537]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service1968machine # [ 64.596681] sudo[3537]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1969machine # [ 64.607628] sudo[3537]: pam_unix(sudo:session): session closed for user usertest1970machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)1971machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants1972machine # [ 64.646530] sudo[3543]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants1973machine # [ 64.657702] sudo[3543]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1974machine # [ 64.666512] sudo[3543]: pam_unix(sudo:session): session closed for user usertest1975machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)1976machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service1977machine # [ 64.697597] sudo[3549]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service1978machine # [ 64.707453] sudo[3549]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1979machine # [ 64.716313] sudo[3549]: pam_unix(sudo:session): session closed for user usertest1980machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.05 seconds)1981machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service1982machine # [ 64.754984] sudo[3555]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service1983machine # [ 64.764623] sudo[3555]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1984machine # [ 64.772928] sudo[3555]: pam_unix(sudo:session): session closed for user usertest1985machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)1986machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload1987machine # [ 64.802317] sudo[3561]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload1988machine # [ 64.811438] sudo[3561]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1989machine # [ 64.818455] systemd[628]: Reload requested from client PID 3566 ('systemctl')...1990machine # [ 64.820121] systemd[628]: Reloading...1991machine # [ 64.945233] systemd[628]: Reloading finished in 123 ms.1992machine # [ 64.949881] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1993machine # [ 64.957720] sudo[3561]: pam_unix(sudo:session): session closed for user usertest1994machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.19 seconds)1995machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service1996machine # [ 64.995555] sudo[3571]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service1997machine # [ 65.005428] sudo[3571]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)1998machine # [ 65.015526] systemd[628]: Starting migrated.service...1999machine # [ 65.044763] systemd[628]: Finished migrated.service.2000machine # [ 65.048451] sudo[3571]: pam_unix(sudo:session): session closed for user usertest2001machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2002machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2003machine # [ 65.089213] sudo[3579]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2004machine # [ 65.099750] sudo[3579]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2005machine # [ 65.109362] sudo[3579]: pam_unix(sudo:session): session closed for user usertest2006machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2007machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2008machine # [ 65.150171] sudo[3585]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2009machine # [ 65.163081] sudo[3585]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2010machine # [ 65.175171] sudo[3585]: pam_unix(sudo:session): session closed for user usertest2011machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.07 seconds)2012machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2013machine # [ 65.214989] sudo[3591]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2014machine # [ 65.225839] sudo[3591]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2015machine # [ 65.241444] sudo[3591]: pam_unix(sudo:session): session closed for user usertest2016machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2017machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&12018machine # [ 65.699713] nixos[3600]: switching to system configuration /nix/store/gfh95a0nns2rw6k70pr8dsyd3sq1q1ip-nixos-system-machine-test2019machine # [ 65.703272] systemd[1]: Stopped target Remote File Systems.2020machine # [ 65.704148] systemd[1]: Stopped target Local File Systems.2021machine # [ 66.136101] systemd[1]: Reload requested from client PID 3600 ('.switch-to-conf') (unit backdoor.service)...2022machine # [ 66.137834] systemd[1]: Reloading...2023machine # [ 66.589132] systemd[1]: Reloading finished in 449 ms.2024machine # [ 66.725975] systemd[628]: Reexecution requested from client PID 3669 ('switch-to-confi')...2025machine # [ 66.727297] systemd[628]: Reexecuting.2026machine # [ 66.757941] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2027machine # [ 66.890732] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2028machine # [ 66.896692] systemd[628]: Reload requested from client PID 3669 ('switch-to-confi')...2029machine # [ 66.898510] systemd[628]: Reloading...2030machine # [ 67.019716] systemd[628]: Reloading finished in 119 ms.2031machine # [ 67.023839] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2032machine # [ 67.031174] systemd[628]: Stopped Run user-specific NixOS activation.2033machine # [ 67.032046] systemd[628]: Stopping Run user-specific NixOS activation...2034machine # [ 67.034674] systemd[628]: Starting Run user-specific NixOS activation...2035machine # [ 67.075967] systemd[628]: Reload requested from client PID 3681 ('systemctl') (unit nixos-activation.service)...2036machine # [ 67.077694] systemd[628]: Reloading...2037machine # [ 67.202525] systemd[628]: Reloading finished in 122 ms.2038machine # [ 67.207051] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2039machine # [ 67.219433] systemd[628]: Finished Run user-specific NixOS activation.2040machine # [ 67.221577] systemd[628]: Reload requested from client PID 3669 ('switch-to-confi')...2041machine # [ 67.223115] systemd[628]: Reloading...2042machine # [ 67.344792] systemd[628]: Reloading finished in 119 ms.2043machine # [ 67.348849] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2044machine # [ 67.356938] systemd[628]: Reloading migrated.service...2045machine # [ 67.381492] systemd[628]: Reloaded migrated.service.2046machine # [ 67.387551] systemd[1]: Stopped target Reactivate sysinit units.2047machine # [ 67.389106] systemd[1]: Stopping Reactivate sysinit units...2048machine # [ 67.389839] systemd[1]: Reached target Reactivate sysinit units.2049machine # [ 67.391620] systemd[1]: Reached target Remote File Systems.2050machine # [ 67.393796] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2051machine # [ 67.395448] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2052machine # [ 67.398360] systemd[1]: Reached target Local File Systems.2053machine # [ 67.405715] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2054machine # [ 67.410441] systemd[1]: Starting Create SUID/SGID Wrappers...2055machine # [ 67.411393] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2056machine # [ 67.413206] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672057machine # [ 67.415912] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2058machine # [ 67.418117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2059machine # [ 67.420110] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2060machine # [ 67.657318] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2061machine # [ 67.659283] systemd[1]: Finished Create SUID/SGID Wrappers.2062machine # [ 67.662888] systemd[1]: Starting linger-users.service...2063machine # [ 67.709843] systemd[1]: linger-users.service: Deactivated successfully.2064machine # [ 67.710930] systemd[1]: Finished linger-users.service.2065machine # [ 67.966581] nixos[3600]: finished switching to system configuration /nix/store/gfh95a0nns2rw6k70pr8dsyd3sq1q1ip-nixos-system-machine-test2066machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosReloadOnly/bin/switch-to-configuration test 2>&1, in 2.73 seconds)2067machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2068machine # [ 68.032597] sudo[3750]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2069machine # [ 68.048421] sudo[3750]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2070machine # [ 68.058185] sudo[3750]: pam_unix(sudo:session): session closed for user usertest2071machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2072machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2073machine # [ 68.096841] sudo[3756]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2074machine # [ 68.107622] sudo[3756]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2075machine # [ 68.117798] sudo[3756]: pam_unix(sudo:session): session closed for user usertest2076machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2077machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&12078machine # [ 68.561863] nixos[3765]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2079machine # [ 68.564524] systemd[1]: Stopped target Local File Systems.2080machine # [ 68.566298] systemd[1]: Stopped target Remote File Systems.2081machine # [ 68.897338] systemd[1]: Reload requested from client PID 3765 ('.switch-to-conf') (unit backdoor.service)...2082machine # [ 68.899181] systemd[1]: Reloading...2083machine # [ 69.392087] systemd[1]: Reloading finished in 491 ms.2084machine # [ 69.504960] systemd[628]: Stopped migrated.service.2085machine # [ 69.506458] systemd[628]: Reexecution requested from client PID 3834 ('switch-to-confi')...2086machine # [ 69.509109] systemd[628]: Reexecuting.2087machine # [ 69.535813] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2088machine # [ 69.652593] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2089machine # [ 69.657898] systemd[628]: Reload requested from client PID 3834 ('switch-to-confi')...2090machine # [ 69.659266] systemd[628]: Reloading...2091machine # [ 69.803770] systemd[628]: Reloading finished in 142 ms.2092machine # [ 69.807888] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2093machine # [ 69.814671] systemd[628]: Stopped Run user-specific NixOS activation.2094machine # [ 69.816193] systemd[628]: Stopping Run user-specific NixOS activation...2095machine # [ 69.819125] systemd[628]: Starting Run user-specific NixOS activation...2096machine # [ 69.855597] systemd[628]: Finished Run user-specific NixOS activation.2097machine # [ 69.859593] systemd[1]: Stopped target Reactivate sysinit units.2098machine # [ 69.860699] systemd[1]: Stopping Reactivate sysinit units...2099machine # [ 69.862171] systemd[1]: Reached target Reactivate sysinit units.2100machine # [ 69.865481] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2101machine # [ 69.867244] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2102machine # [ 69.869939] systemd[1]: Reached target Local File Systems.2103machine # [ 69.872202] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2104machine # [ 69.876235] systemd[1]: Starting Create SUID/SGID Wrappers...2105machine # [ 69.878445] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2106machine # [ 69.879650] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672107machine # [ 69.881551] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2108machine # [ 69.882943] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2109machine # [ 69.885315] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2110machine # [ 69.887973] systemd[1]: Reached target Remote File Systems.2111machine # [ 70.149575] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2112machine # [ 70.151303] systemd[1]: Finished Create SUID/SGID Wrappers.2113machine # [ 70.156577] systemd[1]: Starting linger-users.service...2114machine # [ 70.200565] systemd[1]: linger-users.service: Deactivated successfully.2115machine # [ 70.203374] systemd[1]: Finished linger-users.service.2116machine # [ 70.459967] nixos[3765]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2117machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.35 seconds)2118machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2119machine # [ 70.514779] sudo[3902]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2120machine # [ 70.530064] sudo[3902]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2121machine # [ 70.540803] sudo[3902]: pam_unix(sudo:session): session closed for user usertest2122machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2123machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2124machine # [ 70.571562] sudo[3908]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2125machine # [ 70.581873] sudo[3908]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2126machine # [ 70.592546] sudo[3908]: pam_unix(sudo:session): session closed for user usertest2127machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.05 seconds)2128machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2129machine # [ 70.633205] sudo[3914]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service2130machine # [ 70.644789] sudo[3914]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2131machine # [ 70.658282] sudo[3914]: pam_unix(sudo:session): session closed for user usertest2132machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.07 seconds)2133machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2134machine # [ 70.697791] sudo[3920]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2135machine # [ 70.710606] sudo[3920]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2136machine # [ 70.720613] sudo[3920]: pam_unix(sudo:session): session closed for user usertest2137machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)2138machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2139machine # [ 70.756880] sudo[3926]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2140machine # [ 70.767783] sudo[3926]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2141machine # [ 70.775810] systemd[628]: Reload requested from client PID 3931 ('systemctl')...2142machine # [ 70.777972] systemd[628]: Reloading...2143machine # [ 70.911049] systemd[628]: Reloading finished in 131 ms.2144machine # [ 70.915886] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2145machine # [ 70.922840] sudo[3926]: pam_unix(sudo:session): session closed for user usertest2146machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.20 seconds)2147machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2148machine # [ 70.962992] sudo[3936]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2149machine # [ 70.973660] sudo[3936]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2150machine # [ 70.986643] systemd[628]: Starting migrated.service...2151machine # [ 71.014488] systemd[628]: Finished migrated.service.2152machine # [ 71.018339] sudo[3936]: pam_unix(sudo:session): session closed for user usertest2153machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2154machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2155machine # [ 71.055729] sudo[3944]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2156machine # [ 71.066730] sudo[3944]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2157machine # [ 71.076603] sudo[3944]: pam_unix(sudo:session): session closed for user usertest2158machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2159machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2160machine # [ 71.113346] sudo[3950]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2161machine # [ 71.123593] sudo[3950]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2162machine # [ 71.131971] sudo[3950]: pam_unix(sudo:session): session closed for user usertest2163machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2164machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2165machine # [ 71.162812] sudo[3956]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2166machine # [ 71.174333] sudo[3956]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2167machine # [ 71.192061] sudo[3956]: pam_unix(sudo:session): session closed for user usertest2168machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2169machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&12170machine # [ 71.670559] nixos[3965]: switching to system configuration /nix/store/y2f55rpddzw9rgxgifdzswzknillxz7i-nixos-system-machine-test2171machine # [ 71.673704] systemd[1]: Stopped target Local File Systems.2172machine # [ 71.675298] systemd[1]: Stopped target Remote File Systems.2173machine # [ 72.065324] systemd[1]: Reload requested from client PID 3965 ('.switch-to-conf') (unit backdoor.service)...2174machine # [ 72.067122] systemd[1]: Reloading...2175machine # [ 72.787410] systemd[1]: Reloading finished in 717 ms.2176machine # [ 72.922403] systemd[628]: Reexecution requested from client PID 4034 ('switch-to-confi')...2177machine # [ 72.924389] systemd[628]: Reexecuting.2178machine # [ 72.952090] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2179machine # [ 73.102822] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2180machine # [ 73.109044] systemd[628]: Reload requested from client PID 4034 ('switch-to-confi')...2181machine # [ 73.110949] systemd[628]: Reloading...2182machine # [ 73.249741] systemd[628]: Reloading finished in 137 ms.2183machine # [ 73.254844] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2184machine # [ 73.261265] systemd[628]: Stopped Run user-specific NixOS activation.2185machine # [ 73.263057] systemd[628]: Stopping Run user-specific NixOS activation...2186machine # [ 73.265532] systemd[628]: Starting Run user-specific NixOS activation...2187machine # [ 73.310409] systemd[628]: Reload requested from client PID 4046 ('systemctl') (unit nixos-activation.service)...2188machine # [ 73.312702] systemd[628]: Reloading...2189machine # [ 73.448743] systemd[628]: Reloading finished in 136 ms.2190machine # [ 73.454061] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2191machine # [ 73.466299] systemd[628]: Finished Run user-specific NixOS activation.2192machine # [ 73.468502] systemd[628]: Reload requested from client PID 4034 ('switch-to-confi')...2193machine # [ 73.469887] systemd[628]: Reloading...2194machine # [ 73.604964] systemd[628]: Reloading finished in 133 ms.2195machine # [ 73.609858] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 73.620949] systemd[1]: Stopped target Reactivate sysinit units.2197machine # [ 73.621981] systemd[1]: Stopping Reactivate sysinit units...2198machine # [ 73.622765] systemd[1]: Reached target Reactivate sysinit units.2199machine # [ 73.625361] systemd[1]: Reached target Remote File Systems.2200machine # [ 73.629274] systemd[1]: Starting linger-users.service...2201machine # [ 73.640065] systemd[1]: Reached target Local File Systems.2202machine # [ 73.640810] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2203machine # [ 73.645105] systemd[1]: Starting Create SUID/SGID Wrappers...2204machine # [ 73.645890] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2205machine # [ 73.647613] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672206machine # [ 73.650470] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2207machine # [ 73.652856] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2208machine # [ 73.656831] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2209machine # [ 73.658318] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2210machine # [ 73.659855] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2211machine # [ 73.708763] systemd[1]: linger-users.service: Deactivated successfully.2212machine # [ 73.710614] systemd[1]: Finished linger-users.service.2213machine # [ 73.925377] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2214machine # [ 73.927136] systemd[1]: Finished Create SUID/SGID Wrappers.2215machine # [ 74.185566] nixos[3965]: finished switching to system configuration /nix/store/y2f55rpddzw9rgxgifdzswzknillxz7i-nixos-system-machine-test2216machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedToNixosNoRestart/bin/switch-to-configuration test 2>&1, in 3.00 seconds)2217machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2218machine # [ 74.241858] sudo[4113]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2219machine # [ 74.255778] sudo[4113]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2220machine # [ 74.265891] sudo[4113]: pam_unix(sudo:session): session closed for user usertest2221machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2222machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2223machine # [ 74.305104] sudo[4119]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2224machine # [ 74.315949] sudo[4119]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2225machine # [ 74.326140] sudo[4119]: pam_unix(sudo:session): session closed for user usertest2226machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2227machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&12228machine # [ 74.783380] nixos[4128]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2229machine # [ 74.786757] systemd[1]: Stopped target Remote File Systems.2230machine # [ 74.787761] systemd[1]: Stopped target Local File Systems.2231machine # [ 75.126096] systemd[1]: Reload requested from client PID 4128 ('.switch-to-conf') (unit backdoor.service)...2232machine # [ 75.127749] systemd[1]: Reloading...2233machine # [ 75.966292] systemd[1]: Reloading finished in 836 ms.2234machine # [ 76.071203] systemd[628]: Stopped migrated.service.2235machine # [ 76.072815] systemd[628]: Reexecution requested from client PID 4197 ('switch-to-confi')...2236machine # [ 76.075586] systemd[628]: Reexecuting.2237machine # [ 76.098151] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2238machine # [ 76.215510] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2239machine # [ 76.220235] systemd[628]: Reload requested from client PID 4197 ('switch-to-confi')...2240machine # [ 76.221593] systemd[628]: Reloading...2241machine # [ 76.342236] systemd[628]: Reloading finished in 118 ms.2242machine # [ 76.345857] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2243machine # [ 76.354136] systemd[628]: Stopped Run user-specific NixOS activation.2244machine # [ 76.355103] systemd[628]: Stopping Run user-specific NixOS activation...2245machine # [ 76.358346] systemd[628]: Starting Run user-specific NixOS activation...2246machine # [ 76.407885] systemd[628]: Finished Run user-specific NixOS activation.2247machine # [ 76.412396] systemd[1]: Stopped target Reactivate sysinit units.2248machine # [ 76.414427] systemd[1]: Stopping Reactivate sysinit units...2249machine # [ 76.415470] systemd[1]: Reached target Reactivate sysinit units.2250machine # [ 76.420561] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2251machine # [ 76.422436] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2252machine # [ 76.424588] systemd[1]: Reached target Local File Systems.2253machine # [ 76.427063] systemd[1]: Reached target Remote File Systems.2254machine # [ 76.431036] systemd[1]: Starting linger-users.service...2255machine # [ 76.440280] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2256machine # [ 76.445335] systemd[1]: Starting Create SUID/SGID Wrappers...2257machine # [ 76.446312] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2258machine # [ 76.447540] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672259machine # [ 76.449939] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2260machine # [ 76.453688] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2261machine # [ 76.455081] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2262machine # [ 76.514875] systemd[1]: linger-users.service: Deactivated successfully.2263machine # [ 76.516532] systemd[1]: Finished linger-users.service.2264machine # [ 76.751203] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2265machine # [ 76.753108] systemd[1]: Finished Create SUID/SGID Wrappers.2266machine # [ 77.013362] nixos[4128]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2267machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.69 seconds)2268machine: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2269machine # [ 77.068163] sudo[4265]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2270machine # [ 77.077934] sudo[4265]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2271machine # [ 77.089439] sudo[4265]: pam_unix(sudo:session): session closed for user usertest2272machine: (finished: must fail: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.07 seconds)2273machine: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user2274machine # [ 77.130109] sudo[4271]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.local/share/systemd/user2275machine # [ 77.141680] sudo[4271]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2276machine # [ 77.150631] sudo[4271]: pam_unix(sudo:session): session closed for user usertest2277machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.local/share/systemd/user, in 0.06 seconds)2278machine: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service2279machine # [ 77.183372] sudo[4277]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service /home/usertest/.local/share/systemd/user/migrated.service2280machine # [ 77.193578] sudo[4277]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2281machine # [ 77.203416] sudo[4277]: pam_unix(sudo:session): session closed for user usertest2282machine: (finished: must succeed: sudo -u usertest cp /nix/store/gizknzaqxgkdg471gzc71mvrg9648pl5-migrated.service ~usertest/.local/share/systemd/user/migrated.service, in 0.05 seconds)2283machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2284machine # [ 77.242404] sudo[4283]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2285machine # [ 77.253303] sudo[4283]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2286machine # [ 77.261667] systemd[628]: Reload requested from client PID 4288 ('systemctl')...2287machine # [ 77.262927] systemd[628]: Reloading...2288machine # [ 77.409240] systemd[628]: Reloading finished in 143 ms.2289machine # [ 77.413092] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2290machine # [ 77.421502] sudo[4283]: pam_unix(sudo:session): session closed for user usertest2291machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.22 seconds)2292machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2293machine # [ 77.463428] sudo[4293]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2294machine # [ 77.474675] sudo[4293]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2295machine # [ 77.489192] systemd[628]: Starting migrated.service...2296machine # [ 77.521213] systemd[628]: Finished migrated.service.2297machine # [ 77.525191] sudo[4293]: pam_unix(sudo:session): session closed for user usertest2298machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.10 seconds)2299machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2300machine # [ 77.562454] sudo[4301]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2301machine # [ 77.573353] sudo[4301]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2302machine # [ 77.584144] sudo[4301]: pam_unix(sudo:session): session closed for user usertest2303machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.06 seconds)2304machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2305machine # [ 77.624835] sudo[4307]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2306machine # [ 77.636315] sudo[4307]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2307machine # [ 77.647384] sudo[4307]: pam_unix(sudo:session): session closed for user usertest2308machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2309machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2310machine # [ 77.691190] sudo[4313]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2311machine # [ 77.703685] sudo[4313]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2312machine # [ 77.746391] sudo[4313]: pam_unix(sudo:session): session closed for user usertest2313machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.10 seconds)2314machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12315machine # [ 78.272551] nixos[4322]: switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2316machine # [ 78.275572] systemd[1]: Stopped target Local File Systems.2317machine # [ 78.277152] systemd[1]: Stopped target Remote File Systems.2318machine # [ 78.711214] systemd[1]: Reload requested from client PID 4322 ('.switch-to-conf') (unit backdoor.service)...2319machine # [ 78.712910] systemd[1]: Reloading...2320machine # [ 81.265439] systemd[1]: Reloading finished in 2550 ms.2321machine # [ 81.396980] systemd[628]: Reexecution requested from client PID 4391 ('switch-to-confi')...2322machine # [ 81.399930] systemd[628]: Reexecuting.2323machine # [ 81.424835] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2324machine # [ 81.580444] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2325machine # [ 81.586331] systemd[628]: Reload requested from client PID 4391 ('switch-to-confi')...2326machine # [ 81.588064] systemd[628]: Reloading...2327machine # [ 81.738602] systemd[628]: Reloading finished in 149 ms.2328machine # [ 81.742891] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2329machine # [ 81.750080] systemd[628]: Stopped Run user-specific NixOS activation.2330machine # [ 81.750971] systemd[628]: Stopping Run user-specific NixOS activation...2331machine # [ 81.753693] systemd[628]: Starting Run user-specific NixOS activation...2332machine # [ 81.789228] systemd[628]: Finished Run user-specific NixOS activation.2333machine # [ 81.791572] systemd[628]: Reload requested from client PID 4391 ('switch-to-confi')...2334machine # [ 81.792817] systemd[628]: Reloading...2335machine # [ 81.938610] systemd[628]: Reloading finished in 143 ms.2336machine # [ 81.944871] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2337machine # [ 81.952690] systemd[628]: Stopped migrated.service.2338machine # [ 81.954499] systemd[628]: Stopping migrated.service...2339machine # [ 81.956858] systemd[628]: Starting migrated.service...2340machine # [ 81.989822] systemd[628]: Finished migrated.service.2341machine # [ 81.996206] systemd[1]: Stopped target Reactivate sysinit units.2342machine # [ 81.997531] systemd[1]: Stopping Reactivate sysinit units...2343machine # [ 81.998415] systemd[1]: Reached target Reactivate sysinit units.2344machine # [ 82.001804] systemd[1]: Reached target Remote File Systems.2345machine # [ 82.005747] systemd[1]: Starting linger-users.service...2346machine # [ 82.009272] systemd[1]: Reached target Local File Systems.2347machine # [ 82.011161] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2348machine # [ 82.017320] systemd[1]: Starting Create SUID/SGID Wrappers...2349machine # [ 82.018426] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2350machine # [ 82.019696] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672351machine # [ 82.022461] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2352machine # [ 82.025299] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2353machine # [ 82.028514] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2354machine # [ 82.034867] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2355machine # [ 82.036213] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2356machine # [ 82.092499] systemd[1]: linger-users.service: Deactivated successfully.2357machine # [ 82.094482] systemd[1]: Finished linger-users.service.2358machine # [ 82.350890] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2359machine # [ 82.353200] systemd[1]: Finished Create SUID/SGID Wrappers.2360machine # [ 82.611380] nixos[4322]: finished switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2361machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 4.87 seconds)2362machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2363machine # [ 82.672669] sudo[4465]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2364machine # [ 82.686300] sudo[4465]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2365machine # [ 82.696380] sudo[4465]: pam_unix(sudo:session): session closed for user usertest2366machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.08 seconds)2367machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2368machine # [ 82.734794] sudo[4471]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2369machine # [ 82.745805] sudo[4471]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2370machine # [ 82.768532] sudo[4471]: pam_unix(sudo:session): session closed for user usertest2371machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.07 seconds)2372machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2373machine # [ 82.806437] sudo[4477]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2374machine # [ 82.815971] sudo[4477]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2375machine # [ 82.824498] sudo[4477]: pam_unix(sudo:session): session closed for user usertest2376machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2377machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12378machine # [ 83.241404] nixos[4486]: switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2379machine # [ 83.244689] systemd[1]: Stopped target Remote File Systems.2380machine # [ 83.245720] systemd[1]: Stopped target Local File Systems.2381machine # [ 83.601080] systemd[1]: Reload requested from client PID 4486 ('.switch-to-conf') (unit backdoor.service)...2382machine # [ 83.602734] systemd[1]: Reloading...2383machine # [ 84.041953] systemd[1]: Reloading finished in 436 ms.2384machine # [ 84.172610] systemd[628]: Reexecution requested from client PID 4555 ('switch-to-confi')...2385machine # [ 84.173929] systemd[628]: Reexecuting.2386machine # [ 84.202941] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2387machine # [ 84.344876] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2388machine # [ 84.350866] systemd[628]: Reload requested from client PID 4555 ('switch-to-confi')...2389machine # [ 84.352553] systemd[628]: Reloading...2390machine # [ 84.492493] systemd[628]: Reloading finished in 138 ms.2391machine # [ 84.497854] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2392machine # [ 84.505232] systemd[628]: Stopped Run user-specific NixOS activation.2393machine # [ 84.506194] systemd[628]: Stopping Run user-specific NixOS activation...2394machine # [ 84.509342] systemd[628]: Starting Run user-specific NixOS activation...2395machine # [ 84.550044] systemd[628]: Finished Run user-specific NixOS activation.2396machine # [ 84.553558] systemd[1]: Stopped target Reactivate sysinit units.2397machine # [ 84.554921] systemd[1]: Stopping Reactivate sysinit units...2398machine # [ 84.556131] systemd[1]: Reached target Reactivate sysinit units.2399machine # [ 84.557362] systemd[1]: Reached target Local File Systems.2400machine # [ 84.561444] systemd[1]: Reached target Remote File Systems.2401machine # [ 84.566689] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2402machine # [ 84.571894] systemd[1]: Starting Create SUID/SGID Wrappers...2403machine # [ 84.572704] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2404machine # [ 84.573813] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672405machine # [ 84.575735] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2406machine # [ 84.577171] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2407machine # [ 84.580738] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2408machine # [ 84.582756] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2409machine # [ 84.585248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2410machine # [ 84.876635] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2411machine # [ 84.877754] systemd[1]: Finished Create SUID/SGID Wrappers.2412machine # [ 84.882393] systemd[1]: Starting linger-users.service...2413machine # [ 84.934673] systemd[1]: linger-users.service: Deactivated successfully.2414machine # [ 84.936361] systemd[1]: Finished linger-users.service.2415machine # [ 85.194315] nixos[4486]: finished switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2416machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 2.37 seconds)2417machine: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd2418machine # [ 85.260727] sudo[4623]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.local/share/systemd2419machine # [ 85.273426] sudo[4623]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2420machine # [ 85.282475] sudo[4623]: pam_unix(sudo:session): session closed for user usertest2421machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.local/share/systemd, in 0.08 seconds)2422machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2423machine # [ 85.321126] sudo[4629]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2424machine # [ 85.331768] sudo[4629]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2425machine # [ 85.340983] systemd[628]: Reload requested from client PID 4634 ('systemctl')...2426machine # [ 85.342208] systemd[628]: Reloading...2427machine # [ 85.480520] systemd[628]: Reloading finished in 139 ms.2428machine # [ 85.485930] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2429machine # [ 85.493377] sudo[4629]: pam_unix(sudo:session): session closed for user usertest2430machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2431machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&12432machine # [ 85.949610] nixos[4642]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2433machine # [ 85.951961] systemd[1]: Stopped target Remote File Systems.2434machine # [ 85.953685] systemd[1]: Stopped target Local File Systems.2435machine # [ 86.349489] systemd[1]: Reload requested from client PID 4642 ('.switch-to-conf') (unit backdoor.service)...2436machine # [ 86.351242] systemd[1]: Reloading...2437machine # [ 86.844581] systemd[1]: Reloading finished in 491 ms.2438machine # [ 86.966658] systemd[628]: Stopped migrated.service.2439machine # [ 86.969048] systemd[628]: Reexecution requested from client PID 4711 ('switch-to-confi')...2440machine # [ 86.970262] systemd[628]: Reexecuting.2441machine # [ 86.997892] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2442machine # [ 87.132521] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2443machine # [ 87.137749] systemd[628]: Reload requested from client PID 4711 ('switch-to-confi')...2444machine # [ 87.139591] systemd[628]: Reloading...2445machine # [ 87.259811] systemd[628]: Reloading finished in 119 ms.2446machine # [ 87.265859] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2447machine # [ 87.272727] systemd[628]: Stopped Run user-specific NixOS activation.2448machine # [ 87.273753] systemd[628]: Stopping Run user-specific NixOS activation...2449machine # [ 87.277045] systemd[628]: Starting Run user-specific NixOS activation...2450machine # [ 87.307923] systemd[628]: Finished Run user-specific NixOS activation.2451machine # [ 87.311488] systemd[1]: Stopped target Reactivate sysinit units.2452machine # [ 87.313123] systemd[1]: Stopping Reactivate sysinit units...2453machine # [ 87.313897] systemd[1]: Reached target Reactivate sysinit units.2454machine # [ 87.317806] systemd[1]: Reached target Remote File Systems.2455machine # [ 87.321357] systemd[1]: Starting linger-users.service...2456machine # [ 87.324841] systemd[1]: Reached target Local File Systems.2457machine # [ 87.333448] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2458machine # [ 87.337069] systemd[1]: Starting Create SUID/SGID Wrappers...2459machine # [ 87.337972] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2460machine # [ 87.340189] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672461machine # [ 87.342736] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2462machine # [ 87.346625] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2463machine # [ 87.349527] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2464machine # [ 87.350668] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2465machine # [ 87.352118] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2466machine # [ 87.396152] systemd[1]: linger-users.service: Deactivated successfully.2467machine # [ 87.397917] systemd[1]: Finished linger-users.service.2468machine # [ 87.629757] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2469machine # [ 87.631509] systemd[1]: Finished Create SUID/SGID Wrappers.2470machine # [ 87.886686] nixos[4642]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2471machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.40 seconds)2472machine: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants2473machine # [ 87.930335] sudo[4779]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/mkdir -p /home/usertest/.config/systemd/user/default.target.wants2474machine # [ 87.940796] sudo[4779]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2475machine # [ 87.949158] sudo[4779]: pam_unix(sudo:session): session closed for user usertest2476machine: (finished: must succeed: sudo -u usertest mkdir -p ~usertest/.config/systemd/user/default.target.wants, in 0.06 seconds)2477machine: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service2478machine # [ 87.982806] sudo[4785]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service /home/usertest/.config/systemd/user/migrated.service2479machine # [ 87.994589] sudo[4785]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2480machine # [ 88.005559] sudo[4785]: pam_unix(sudo:session): session closed for user usertest2481machine: (finished: must succeed: sudo -u usertest cp /nix/store/8r2dxr6c6a1cwjgaxxibyvmjzr5dmv6k-migrated.service ~usertest/.config/systemd/user/migrated.service, in 0.06 seconds)2482machine: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service2483machine # [ 88.044386] sudo[4791]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/ln -sfn ../migrated.service /home/usertest/.config/systemd/user/default.target.wants/migrated.service2484machine # [ 88.054689] sudo[4791]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2485machine # [ 88.064978] sudo[4791]: pam_unix(sudo:session): session closed for user usertest2486machine: (finished: must succeed: sudo -u usertest ln -sfn ../migrated.service ~usertest/.config/systemd/user/default.target.wants/migrated.service, in 0.06 seconds)2487machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2488machine # [ 88.104616] sudo[4797]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2489machine # [ 88.114407] sudo[4797]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2490machine # [ 88.121866] systemd[628]: Reload requested from client PID 4802 ('systemctl')...2491machine # [ 88.123358] systemd[628]: Reloading...2492machine # [ 88.261583] systemd[628]: Reloading finished in 136 ms.2493machine # [ 88.267099] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2494machine # [ 88.275291] sudo[4797]: pam_unix(sudo:session): session closed for user usertest2495machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2496machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service2497machine # [ 88.310854] sudo[4807]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user start migrated.service2498machine # [ 88.321771] sudo[4807]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2499machine # [ 88.334882] systemd[628]: Starting migrated.service...2500machine # [ 88.360027] systemd[628]: Finished migrated.service.2501machine # [ 88.363391] sudo[4807]: pam_unix(sudo:session): session closed for user usertest2502machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user start migrated.service, in 0.09 seconds)2503machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service2504machine # [ 88.395410] sudo[4815]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user is-active migrated.service2505machine # [ 88.404618] sudo[4815]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2506machine # [ 88.413579] sudo[4815]: pam_unix(sudo:session): session closed for user usertest2507machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user is-active migrated.service, in 0.05 seconds)2508machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2509machine # [ 88.444549] sudo[4821]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2510machine # [ 88.453878] sudo[4821]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2511machine # [ 88.462443] sudo[4821]: pam_unix(sudo:session): session closed for user usertest2512machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.05 seconds)2513machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2514machine # [ 88.492960] sudo[4827]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2515machine # [ 88.502392] sudo[4827]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2516machine # [ 88.520256] sudo[4827]: pam_unix(sudo:session): session closed for user usertest2517machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.06 seconds)2518machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&12519machine # [ 89.017774] nixos[4836]: switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2520machine # [ 89.021573] systemd[1]: Stopped target Local File Systems.2521machine # [ 89.022471] systemd[1]: Stopped target Remote File Systems.2522machine # [ 89.434965] systemd[1]: Reload requested from client PID 4836 ('.switch-to-conf') (unit backdoor.service)...2523machine # [ 89.437117] systemd[1]: Reloading...2524machine # [ 91.717841] systemd[1]: Reloading finished in 2277 ms.2525machine # [ 91.815592] systemd[628]: Reexecution requested from client PID 4905 ('switch-to-confi')...2526machine # [ 91.816914] systemd[628]: Reexecuting.2527machine # [ 91.842094] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2528machine # [ 91.960190] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2529machine # [ 91.968086] systemd[628]: Reload requested from client PID 4905 ('switch-to-confi')...2530machine # [ 91.969338] systemd[628]: Reloading...2531machine # [ 92.101439] systemd[628]: Reloading finished in 129 ms.2532machine # [ 92.105859] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2533machine # [ 92.112543] systemd[628]: Stopped Run user-specific NixOS activation.2534machine # [ 92.114086] systemd[628]: Stopping Run user-specific NixOS activation...2535machine # [ 92.116797] systemd[628]: Starting Run user-specific NixOS activation...2536machine # [ 92.151709] systemd[628]: Finished Run user-specific NixOS activation.2537machine # [ 92.153617] systemd[628]: Reload requested from client PID 4905 ('switch-to-confi')...2538machine # [ 92.155113] systemd[628]: Reloading...2539machine # [ 92.292931] systemd[628]: Reloading finished in 136 ms.2540machine # [ 92.299854] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2541machine # [ 92.310731] systemd[1]: Stopped target Reactivate sysinit units.2542machine # [ 92.311721] systemd[1]: Stopping Reactivate sysinit units...2543machine # [ 92.313059] systemd[1]: Reached target Reactivate sysinit units.2544machine # [ 92.314325] systemd[1]: Reached target Local File Systems.2545machine # [ 92.318748] systemd[1]: Reached target Remote File Systems.2546machine # [ 92.322703] systemd[1]: Starting linger-users.service...2547machine # [ 92.333358] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2548machine # [ 92.337335] systemd[1]: Starting Create SUID/SGID Wrappers...2549machine # [ 92.339730] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2550machine # [ 92.341729] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672551machine # [ 92.344327] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2552machine # [ 92.347092] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2553machine # [ 92.349374] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2554machine # [ 92.350499] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2555machine # [ 92.351676] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2556machine # [ 92.398478] systemd[1]: linger-users.service: Deactivated successfully.2557machine # [ 92.400522] systemd[1]: Finished linger-users.service.2558machine # [ 92.649873] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2559machine # [ 92.652228] systemd[1]: Finished Create SUID/SGID Wrappers.2560machine # [ 92.910072] nixos[4836]: finished switching to system configuration /nix/store/qcxs7wgsmywm9n1717017r6n8iz5xqpk-nixos-system-machine-test2561machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/specialisation/userServiceMigratedShadowed/bin/switch-to-configuration test 2>&1, in 4.40 seconds)2562machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service2563machine # [ 92.974311] sudo[4977]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user show -p FragmentPath migrated.service2564machine # [ 92.988285] sudo[4977]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2565machine # [ 93.005075] sudo[4977]: pam_unix(sudo:session): session closed for user usertest2566machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user show -p FragmentPath migrated.service, in 0.09 seconds)2567machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner2568machine # [ 93.045662] sudo[4983]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/cat /run/user/1001/migrated-owner2569machine # [ 93.056734] sudo[4983]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2570machine # [ 93.065723] sudo[4983]: pam_unix(sudo:session): session closed for user usertest2571machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 cat /run/user/1001/migrated-owner, in 0.06 seconds)2572machine: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd2573machine # [ 93.104387] sudo[4989]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; COMMAND=/run/current-system/sw/bin/rm -rf /home/usertest/.config/systemd2574machine # [ 93.115261] sudo[4989]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2575machine # [ 93.125828] sudo[4989]: pam_unix(sudo:session): session closed for user usertest2576machine: (finished: must succeed: sudo -u usertest rm -rf ~usertest/.config/systemd, in 0.06 seconds)2577machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload2578machine # [ 93.163300] sudo[4995]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user daemon-reload2579machine # [ 93.174442] sudo[4995]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2580machine # [ 93.182588] systemd[628]: Reload requested from client PID 5000 ('systemctl')...2581machine # [ 93.184357] systemd[628]: Reloading...2582machine # [ 93.325879] systemd[628]: Reloading finished in 139 ms.2583machine # [ 93.329848] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2584machine # [ 93.337178] sudo[4995]: pam_unix(sudo:session): session closed for user usertest2585machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user daemon-reload, in 0.21 seconds)2586machine: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service2587machine # [ 93.376987] sudo[5005]: root : TTY=hvc0 ; PWD=/tmp ; USER=usertest ; ENV=XDG_RUNTIME_DIR=/run/user/1001 ; COMMAND=/run/current-system/sw/bin/systemctl --user stop migrated.service2588machine # [ 93.387789] sudo[5005]: pam_unix(sudo:session): session opened for user usertest(uid=1001) by (uid=0)2589machine # [ 93.398932] systemd[628]: Stopped migrated.service.2590machine # [ 93.402063] sudo[5005]: pam_unix(sudo:session): session closed for user usertest2591machine: (finished: must succeed: sudo -u usertest XDG_RUNTIME_DIR=/run/user/1001 systemctl --user stop migrated.service, in 0.06 seconds)2592machine: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&12593machine # [ 93.894662] nixos[5014]: switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2594machine # [ 93.897127] systemd[1]: Stopped target Local File Systems.2595machine # [ 93.898770] systemd[1]: Stopped target Remote File Systems.2596machine # [ 94.270162] systemd[1]: Reload requested from client PID 5014 ('.switch-to-conf') (unit backdoor.service)...2597machine # [ 94.271892] systemd[1]: Reloading...2598machine # [ 94.706123] systemd[1]: Reloading finished in 432 ms.2599machine # [ 94.804351] systemd[628]: Reexecution requested from client PID 5083 ('switch-to-confi')...2600machine # [ 94.806803] systemd[628]: Reexecuting.2601machine # [ 94.833197] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2602machine # [ 94.960754] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2603machine # [ 94.966975] systemd[628]: Reload requested from client PID 5083 ('switch-to-confi')...2604machine # [ 94.968751] systemd[628]: Reloading...2605machine # [ 95.106837] systemd[628]: Reloading finished in 137 ms.2606machine # [ 95.112873] systemd[628]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2607machine # [ 95.119340] systemd[628]: Stopped Run user-specific NixOS activation.2608machine # [ 95.121131] systemd[628]: Stopping Run user-specific NixOS activation...2609machine # [ 95.123773] systemd[628]: Starting Run user-specific NixOS activation...2610machine # [ 95.159303] systemd[628]: Finished Run user-specific NixOS activation.2611machine # [ 95.162939] systemd[1]: Stopped target Reactivate sysinit units.2612machine # [ 95.164679] systemd[1]: Stopping Reactivate sysinit units...2613machine # [ 95.165822] systemd[1]: Reached target Reactivate sysinit units.2614machine # [ 95.167202] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2615machine # [ 95.168846] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2616machine # [ 95.171460] systemd[1]: Reached target Remote File Systems.2617machine # [ 95.175400] systemd[1]: Starting linger-users.service...2618machine # [ 95.180654] systemd[1]: Reached target Local File Systems.2619machine # [ 95.181485] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2620machine # [ 95.185328] systemd[1]: Starting Create SUID/SGID Wrappers...2621machine # [ 95.187141] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2622machine # [ 95.189104] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672623machine # [ 95.190996] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2624machine # [ 95.192414] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2625machine # [ 95.193549] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2626machine # [ 95.253838] systemd[1]: linger-users.service: Deactivated successfully.2627machine # [ 95.255904] systemd[1]: Finished linger-users.service.2628machine # [ 95.506775] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2629machine # [ 95.510211] systemd[1]: Finished Create SUID/SGID Wrappers.2630machine # [ 95.766884] nixos[5014]: finished switching to system configuration /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test2631machine: (finished: must succeed: /nix/store/19ipvpps5i3l5xwr8c075dvi7lz64lf5-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.37 seconds)2632(finished: subtest: user services, in 69.74 seconds)2633(finished: run the VM test script, in 96.77 seconds)2634test script finished in 96.83s2635cleanup2636kill QemuMachine (pid 45)2637machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2638(finished: cleanup, in 0.14 seconds)2639additionally exposed symbols:2640 machine, other,2641 vlan1,2642 start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh