vm-test-run-switch-test-units
checks.x86_64-linux.switchTest-units
· build #61
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.01 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 # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.Qk35sNQ1ro', 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: 4fd9cbef-57a5-4cc2-a3b1-5b6236e4b44c17machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-10-19T03:00:10Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-10-19T03:00:10Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-10-19T03:00:10Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-10-19T03:00:10Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-10-19T03:00:10Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-10-19T03:00:10Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-10-19T03:00:10Z INFO virtiofsd] Client connected, servicing requests39machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)40machine # 41machine # 42machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0043machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...44machine # 45machine # 46machine # 47machine # 48machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0049machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...50machine # 51machine # 52machine # Booting from ROM...53machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.56 (nixbld@localhost) (gcc (GCC) 16.2.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC @179172517254machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/init regInfo=/nix/.ro-store/hzr8wa0h851zxbkvc2kj779vni2j4v6w-closure-info/registration console=ttyS0,115200n8 console=tty055machine # [ 0.000000] BIOS-provided physical RAM map:56machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable57machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved58machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved59machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable60machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved61machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved62machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved63machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved64machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved65machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved66machine # [ 0.000000] NX (Execute Disable) protection: active67machine # [ 0.000000] APIC: Static calls initialized68machine # [ 0.000000] SMBIOS 2.8 present.69machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201470machine # [ 0.000000] DMI: Memory slots populated: 1/171machine # [ 0.000000] Hypervisor detected: KVM72machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000073machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0074machine # [ 0.000000] kvm-clock: using sched offset of 453967646 cycles75machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76machine # [ 0.000005] tsc: Detected 2400.008 MHz processor77machine # [ 0.000757] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078machine # [ 0.000792] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79machine # [ 0.000794] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80machine # [ 0.002670] found SMP MP-table at [mem 0x000f5450-0x000f545f]81machine # [ 0.002683] Using GB pages for direct mapping82machine # [ 0.002791] RAMDISK: [mem 0x3e2b5000-0x3ffcffff]83machine # [ 0.002798] ACPI: Early table checksum verification disabled84machine # [ 0.002801] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85machine # [ 0.002805] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86machine # [ 0.002809] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002817] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002819] ACPI: FACS 0x000000003FFE0000 00004089machine # [ 0.002820] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90machine # [ 0.002822] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002824] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002825] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002826] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94machine # [ 0.002827] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95machine # [ 0.002828] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96machine # [ 0.002828] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97machine # [ 0.002829] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98machine # [ 0.002829] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99machine # [ 0.002830] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100machine # [ 0.003049] No NUMA configuration found101machine # [ 0.003050] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102machine # [ 0.003053] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103machine # [ 0.005298] Zone ranges:104machine # [ 0.005299] DMA [mem 0x0000000000001000-0x0000000000ffffff]105machine # [ 0.005301] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106machine # [ 0.005302] Normal empty107machine # [ 0.005303] Device empty108machine # [ 0.005303] Movable zone start for each node109machine # [ 0.005304] Early memory node ranges110machine # [ 0.005305] node 0: [mem 0x0000000000001000-0x000000000009efff]111machine # [ 0.005305] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112machine # [ 0.005307] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113machine # [ 0.005326] On node 0, zone DMA: 1 pages in unavailable ranges114machine # [ 0.005591] On node 0, zone DMA: 97 pages in unavailable ranges115machine # [ 0.023503] On node 0, zone DMA32: 40 pages in unavailable ranges116machine # [ 0.023952] ACPI: PM-Timer IO Port: 0x608117machine # [ 0.023962] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118machine # [ 0.023990] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119machine # [ 0.023993] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120machine # [ 0.023994] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121machine # [ 0.023996] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122machine # [ 0.023997] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123machine # [ 0.023997] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124machine # [ 0.024000] ACPI: Using ACPI (MADT) for SMP configuration information125machine # [ 0.024001] ACPI: HPET id: 0x8086a201 base: 0xfed00000126machine # [ 0.024004] TSC deadline timer available127machine # [ 0.024008] CPU topo: Max. logical packages: 1128machine # [ 0.024009] CPU topo: Max. logical dies: 1129machine # [ 0.024009] CPU topo: Max. dies per package: 1130machine # [ 0.024012] CPU topo: Max. threads per core: 1131machine # [ 0.024014] CPU topo: Num. cores per package: 1132machine # [ 0.024014] CPU topo: Num. threads per package: 1133machine # [ 0.024014] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134machine # [ 0.024030] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135machine # [ 0.024059] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136machine # [ 0.024060] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137machine # [ 0.024062] [mem 0x40000000-0xafffffff] available for PCI devices138machine # [ 0.024063] Booting paravirtualized kernel on KVM139machine # [ 0.024066] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140machine # [ 0.028688] setup_percpu: NR_CPUS:512 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141machine # [ 0.030879] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142machine # [ 0.030921] kvm-guest: PV spinlocks disabled, single CPU143machine # [ 0.030923] 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/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/init regInfo=/nix/.ro-store/hzr8wa0h851zxbkvc2kj779vni2j4v6w-closure-info/registration console=ttyS0,115200n8 console=tty0144machine # [ 0.031015] Unknown kernel command line parameters "regInfo=/nix/.ro-store/hzr8wa0h851zxbkvc2kj779vni2j4v6w-closure-info/registration", will be passed to user space.145machine # [ 0.031028] random: crng init done146machine # [ 0.031029] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147machine # [ 0.031050] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148machine # [ 0.031591] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149machine # [ 0.031620] Fallback order for Node 0: 0150machine # [ 0.031623] Built 1 zonelists, mobility grouping on. Total pages: 262006151machine # [ 0.031624] Policy zone: DMA32152machine # [ 0.034081] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153machine # [ 0.036634] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154machine # [ 0.038937] allocated 2097152 bytes of page_ext155machine # [ 0.048765] ftrace: allocating 48842 entries in 192 pages156machine # [ 0.048767] ftrace: allocated 192 pages with 2 groups157machine # [ 0.049598] Dynamic Preempt: lazy158machine # [ 0.049768] rcu: Preemptible hierarchical RCU implementation.159machine # [ 0.049769] rcu: RCU event tracing is enabled.160machine # [ 0.049769] rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.161machine # [ 0.049776] Trampoline variant of Tasks RCU enabled.162machine # [ 0.049777] Rude variant of Tasks RCU enabled.163machine # [ 0.049777] Tracing variant of Tasks RCU enabled.164machine # [ 0.049778] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165machine # [ 0.049779] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166machine # [ 0.049802] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.049804] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.049805] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.054106] NR_IRQS: 33024, nr_irqs: 256, preallocated irqs: 16170machine # [ 0.054384] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.054390] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172machine # [ 0.054487] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173machine # [ 0.057996] Console: colour VGA+ 80x25174machine # [ 0.057999] printk: legacy console [tty0] enabled175machine # [ 0.087327] printk: legacy console [ttyS0] enabled176machine # [ 0.194475] ACPI: Core revision 20250807177machine # [ 0.195462] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178machine # [ 0.197201] APIC: Switch to symmetric I/O mode setup179machine # [ 0.198197] x2apic enabled180machine # [ 0.199012] APIC: Switched APIC routing to: physical x2apic181machine # [ 0.200921] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182machine # [ 0.201967] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns183machine # [ 0.203805] Calibrating delay loop (skipped) preset value.. 4800.01 BogoMIPS (lpj=2400008)184machine # [ 0.204887] x86/cpu: User Mode Instruction Prevention (UMIP) activated185machine # [ 0.205923] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186machine # [ 0.206802] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187machine # [ 0.207805] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188machine # [ 0.208802] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189machine # [ 0.209802] Transient Scheduler Attacks: Vulnerable: No microcode190machine # [ 0.211802] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191machine # [ 0.212802] Speculative Return Stack Overflow: Mitigation: Safe RET192machine # [ 0.213802] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193machine # [ 0.215809] Spectre V2 : Enabling IBPB for BPF194machine # [ 0.216586] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195machine # [ 0.217803] active return thunk: srso_alias_return_thunk196machine # [ 0.218823] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197machine # [ 0.219802] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198machine # [ 0.220802] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199machine # [ 0.221802] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200machine # [ 0.223634] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201machine # [ 0.224621] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202machine # [ 0.225802] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203machine # [ 0.226802] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.227802] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.228802] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.229802] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.230802] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.231802] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209machine # [ 0.265056] Freeing SMP alternatives memory: 48K210machine # [ 0.265805] pid_max: default: 32768 minimum: 301211machine # [ 0.266645] LSM: initializing lsm=capability,landlock,yama,bpf,ima212machine # [ 0.267896] landlock: Up and running.213machine # [ 0.268564] Yama: becoming mindful.214machine # [ 0.269031] LSM support for eBPF active215machine # [ 0.269938] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216machine # [ 0.270822] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.273923] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218machine # [ 0.275375] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219machine # [ 0.275811] ... version: 2220machine # [ 0.276584] ... bit width: 48221machine # [ 0.276804] ... generic counters: 6222machine # [ 0.277548] ... generic bitmap: 000000000000003f223machine # [ 0.277805] ... fixed-purpose counters: 0224machine # [ 0.278571] ... fixed-purpose bitmap: 0000000000000000225machine # [ 0.278804] ... value mask: 0000ffffffffffff226machine # [ 0.279804] ... max period: 00007fffffffffff227machine # [ 0.280764] ... global_ctrl mask: 000000000000003f228machine # [ 0.281660] signal: max sigframe size: 3376229machine # [ 0.282462] rcu: Hierarchical SRCU implementation.230machine # [ 0.282808] rcu: Max phase no-delay instances is 400.231machine # [ 0.288366] smp: Bringing up secondary CPUs ...232machine # [ 0.288828] smp: Brought up 1 node, 1 CPU233machine # [ 0.289546] smpboot: Total of 1 processors activated (4800.01 BogoMIPS)234machine # [ 0.290021] Memory: 940316K/1048024K available (18132K kernel code, 2734K rwdata, 13652K rodata, 3660K init, 3620K bss, 100324K reserved, 0K cma-reserved)235machine # [ 0.291030] devtmpfs: initialized236machine # [ 0.291869] x86/mm: Memory block size: 128MB237machine # [ 0.293542] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238machine # [ 0.294811] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239machine # [ 0.295872] pinctrl core: initialized pinctrl subsystem240machine # [ 0.297044] PM: RTC time: 03:00:10, date: 2026-10-19241machine # [ 0.300740] NET: Registered PF_NETLINK/PF_ROUTE protocol family242machine # [ 0.301973] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243machine # [ 0.302821] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244machine # [ 0.303962] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245machine # [ 0.304813] audit: initializing netlink subsys (disabled)246machine # [ 0.305997] thermal_sys: Registered thermal governor 'fair_share'247machine # [ 0.306000] thermal_sys: Registered thermal governor 'bang_bang'248machine # [ 0.306806] thermal_sys: Registered thermal governor 'step_wise'249machine # [ 0.307808] audit: type=2000 audit(1792378810.909:1): state=initialized audit_enabled=0 res=1250machine # [ 0.309805] thermal_sys: Registered thermal governor 'user_space'251machine # [ 0.309807] thermal_sys: Registered thermal governor 'power_allocator'252machine # [ 0.310819] cpuidle: using governor menu253machine # [ 0.313064] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254machine # [ 0.314020] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255machine # [ 0.314807] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256machine # [ 0.315816] PCI: Using configuration type 1 for base access257machine # [ 0.317002] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258machine # [ 0.322102] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259machine # [ 0.322806] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260machine # [ 0.327805] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261machine # [ 0.328805] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262machine # [ 0.339205] ACPI: Added _OSI(Module Device)263machine # [ 0.339806] ACPI: Added _OSI(Processor Device)264machine # [ 0.340556] ACPI: Added _OSI(Processor Aggregator Device)265machine # [ 0.349322] ACPI: 1 ACPI AML tables successfully acquired and loaded266machine # [ 0.353059] ACPI: Interpreter enabled267machine # [ 0.353779] ACPI: PM: (supports S0 S3 S4 S5)268machine # [ 0.354358] ACPI: Using IOAPIC for interrupt routing269machine # [ 0.354862] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270machine # [ 0.355805] PCI: Using E820 reservations for host bridge windows271machine # [ 0.358954] ACPI: Enabled 2 GPEs in block 00 to 3F272machine # [ 0.366838] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273machine # [ 0.367809] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274machine # [ 0.368881] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275machine # [ 0.369940] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276machine # [ 0.371153] PCI host bridge to bus 0000:00277machine # [ 0.371809] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278machine # [ 0.372805] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279machine # [ 0.373805] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280machine # [ 0.374804] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281machine # [ 0.375804] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282machine # [ 0.376804] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283machine # [ 0.377805] pci_bus 0000:00: root bus resource [bus 00-ff]284machine # [ 0.378870] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285machine # [ 0.380269] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286machine # [ 0.382933] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287machine # [ 0.383860] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288machine # [ 0.384826] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289machine # [ 0.386308] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290machine # [ 0.387547] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291machine # [ 0.390844] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292machine # [ 0.391745] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293machine # [ 0.392641] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294machine # [ 0.393748] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295machine # [ 0.395350] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296machine # [ 0.397736] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297machine # [ 0.398505] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298machine # [ 0.398826] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299machine # [ 0.400341] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.403766] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.404486] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302machine # [ 0.404826] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303machine # [ 0.406389] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.408842] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305machine # [ 0.409713] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306machine # [ 0.411425] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307machine # [ 0.411810] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.413361] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.414817] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310machine # [ 0.415826] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311machine # [ 0.417361] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312machine # [ 0.419818] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313machine # [ 0.420804] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314machine # [ 0.422500] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315machine # [ 0.424845] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316machine # [ 0.425826] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317machine # [ 0.427367] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318machine # [ 0.428845] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319machine # [ 0.429826] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320machine # [ 0.431371] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321machine # [ 0.433737] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322machine # [ 0.434514] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323machine # [ 0.434836] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324machine # [ 0.436363] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325machine # [ 0.438549] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326machine # [ 0.438811] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327machine # [ 0.439826] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328machine # [ 0.441354] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329machine # [ 0.443185] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330machine # [ 0.444012] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331machine # [ 0.445526] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332machine # [ 0.446015] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333machine # [ 0.447475] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334machine # [ 0.448016] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335machine # [ 0.450852] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336machine # [ 0.452088] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337machine # [ 0.453094] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338machine # [ 0.454058] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339machine # [ 0.456858] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340machine # [ 0.457756] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341machine # [ 0.459139] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342machine # [ 0.460605] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343machine # [ 0.464523] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344machine # [ 0.464935] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345machine # [ 0.465906] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346machine # [ 0.467132] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347machine # [ 0.467906] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348machine # [ 0.468904] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349machine # [ 0.469906] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350machine # [ 0.470925] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351machine # [ 0.471840] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352machine # [ 0.472821] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353machine # [ 0.473819] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354machine # [ 0.474818] ACPI: PCI: Interrupt link GSID configured for IRQ 19355machine # [ 0.475816] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356machine # [ 0.476820] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357machine # [ 0.477819] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358machine # [ 0.478818] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359machine # [ 0.480848] iommu: Default domain type: Translated360machine # [ 0.481714] iommu: DMA domain TLB invalidation policy: lazy mode361machine # [ 0.482874] ACPI: bus type USB registered362machine # [ 0.483669] usbcore: registered new interface driver usbfs363machine # [ 0.484557] usbcore: registered new interface driver hub364machine # [ 0.484820] usbcore: registered new device driver usb365machine # [ 0.486757] NetLabel: Initializing366machine # [ 0.487336] NetLabel: domain hash size = 128367machine # [ 0.487805] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368machine # [ 0.488851] NetLabel: unlabeled traffic allowed by default369machine # [ 0.489815] PCI: Using ACPI for IRQ routing370machine # [ 0.534534] pci 0000:00:01.0: vgaarb: setting as boot VGA device371machine # [ 0.534801] pci 0000:00:01.0: vgaarb: bridge control possible372machine # [ 0.534801] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373machine # [ 0.534809] vgaarb: loaded374machine # [ 0.535555] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375machine # [ 0.535807] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376machine # [ 0.539890] clocksource: Switched to clocksource kvm-clock377machine # [ 0.543380] VFS: Disk quotas dquot_6.6.0378machine # [ 0.544194] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379machine # [ 0.545606] pnp: PnP ACPI init380machine # [ 0.546450] ACPI: IRQ 4 override to edge(!), high(!)381machine # [ 0.547433] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382machine # [ 0.549011] pnp: PnP ACPI: found 5 devices383machine # [ 0.556562] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384machine # [ 0.558087] clocksource: Switched to clocksource acpi_pm385machine # [ 0.559087] NET: Registered PF_INET protocol family386machine # [ 0.560307] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387machine # [ 0.577244] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388machine # [ 0.578838] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389machine # [ 0.580194] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390machine # [ 0.581570] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391machine # [ 0.582860] TCP: Hash tables configured (established 8192 bind 8192)392machine # [ 0.584006] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393machine # [ 0.585310] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394machine # [ 0.586496] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.587743] NET: Registered PF_UNIX/PF_LOCAL protocol family396machine # [ 0.588753] NET: Registered PF_XDP protocol family397machine # [ 0.589638] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398machine # [ 0.590682] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399machine # [ 0.591764] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400machine # [ 0.592923] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401machine # [ 0.594093] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402machine # [ 0.595285] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403machine # [ 0.597115] ACPI: \_SB_.GSIA: Enabled at IRQ 16404machine # [ 0.599667] ACPI: \_SB_.GSIB: Enabled at IRQ 17405machine # [ 0.601791] ACPI: \_SB_.GSIC: Enabled at IRQ 18406machine # [ 0.604023] ACPI: \_SB_.GSID: Enabled at IRQ 19407machine # [ 0.605685] PCI: CLS 0 bytes, default 64408machine # [ 0.606629] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983f10a64, max_idle_ns: 440795218721 ns409machine # [ 0.608445] Trying to unpack rootfs image as initramfs...410machine # [ 0.652936] Initialise system trusted keyrings411machine # [ 0.656479] workingset: timestamp_bits=40 max_order=18 bucket_order=0412machine # [ 0.677552] Key type asymmetric registered413machine # [ 0.678312] Asymmetric key parser 'x509' registered414machine # [ 0.679230] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415machine # [ 0.684534] io scheduler mq-deadline registered416machine # [ 0.685353] io scheduler kyber registered417machine # [ 0.687973] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418machine # [ 0.689293] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419machine # [ 0.695189] Linux agpgart interface v0.103420machine # [ 0.695981] ACPI: bus type drm_connector registered421machine # [ 0.698935] usbcore: registered new interface driver usbserial_generic422machine # [ 0.700123] usbserial: USB Serial support registered for generic423machine # [ 0.702442] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424machine # [ 0.702442] Please enable it if your BIOS has the CPPC option.425machine # [ 0.704822] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426machine # [ 0.710567] drop_monitor: Initializing network drop monitor service427machine # [ 0.711770] NET: Registered PF_INET6 protocol family428machine # [ 0.714937] Segment Routing with IPv6429machine # [ 0.717455] In-situ OAM (IOAM) with IPv6430machine # [ 0.718560] IPI shorthand broadcast: enabled431machine # [ 0.726954] sched_clock: Marking stable (582013731, 144410980)->(805484609, -79059898)432machine # [ 0.732563] registered taskstats version 1433machine # [ 0.733532] Loading compiled-in X.509 certificates434machine # [ 0.750106] Demotion targets for Node 0: null435machine # [ 0.753474] Key type .fscrypt registered436machine # [ 0.754150] Key type fscrypt-provisioning registered437machine # [ 0.755162] ima: No TPM chip found, activating TPM-bypass!438machine # [ 0.757437] ima: Allocated hash algorithm: sha1439machine # [ 0.758263] ima: No architecture policies found440machine # [ 0.762438] PM: Magic number: 14:728:7441machine # [ 0.764127] RAS: Correctable Errors collector initialized.442machine # [ 0.773221] clk: Disabling unused clocks443machine # [ 0.775437] PM: genpd: Disabling unused power domains444machine # [ 0.968271] Freeing initrd memory: 29804K445machine # [ 0.971856] Freeing unused decrypted memory: 2028K446machine # [ 0.975163] Freeing unused kernel image (initmem) memory: 3660K447machine # [ 0.976279] Write protecting the kernel read-only data: 32768k448machine # [ 0.977666] Freeing unused kernel image (text/rodata gap) memory: 296K449machine # [ 0.979274] Freeing unused kernel image (rodata/data gap) memory: 684K450machine # [ 1.030527] x86/mm: Checked W+X mappings: passed, no W+X pages found.451machine # [ 1.031722] Run /init as init process452machine # [ 1.044069] systemd[1]: Inserted module 'autofs4'453machine # [ 1.064649] fuse: init (API version 7.45)454machine # [ 1.072214] ACPI: \_SB_.GSIG: Enabled at IRQ 22455machine # [ 1.075152] ACPI: \_SB_.GSIH: Enabled at IRQ 23456machine # [ 1.078676] ACPI: \_SB_.GSIE: Enabled at IRQ 20457machine # [ 1.080879] ACPI: \_SB_.GSIF: Enabled at IRQ 21458machine # [ 1.086625] virtiofs virtio5: discovered new tag: nix-store459machine # [ 1.088254] virtiofs virtio5: virtio_fs_setup_dax: No cache capability460machine # [ 1.095370] virtiofs virtio6: discovered new tag: shared461machine # [ 1.096954] virtiofs virtio6: virtio_fs_setup_dax: No cache capability462machine # [ 1.100598] virtiofs virtio7: discovered new tag: xchg463machine # [ 1.102061] virtiofs virtio7: virtio_fs_setup_dax: No cache capability464machine # [ 1.124249] systemd[1]: Successfully made /usr/ read-only.465machine # [ 1.125972] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)466machine # [ 1.131074] systemd[1]: Detected virtualization kvm.467machine # [ 1.131906] systemd[1]: Detected architecture x86-64.468machine # [ 1.132766] systemd[1]: Running in initrd.469machine # [ 1.133704] systemd[1]: Initializing machine ID from random generator.470machine # [ 1.134844] systemd[1]: Hostname set to <machine>.471machine # [ 1.205706] systemd[1]: bpf-restrict-fs: LSM BPF program attached472machine # [ 1.241554] systemd[1]: Queued start job for default target Initrd Default Target.473machine # [ 1.244626] systemd[1]: Created slice Slice /system/modprobe.474machine # [ 1.245766] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.475machine # [ 1.247114] systemd[1]: Expecting device /dev/disk/by-label/nixos...476machine # [ 1.248167] systemd[1]: Reached target Path Units.477machine # [ 1.249007] systemd[1]: Reached target Slice Units.478machine # [ 1.249838] systemd[1]: Reached target Swaps.479machine # [ 1.250623] systemd[1]: Reached target Timer Units.480machine # [ 1.251585] systemd[1]: Listening on D-Bus System Message Bus Socket.481machine # [ 1.252806] systemd[1]: Listening on Journal Socket (/dev/log).482machine # [ 1.253936] systemd[1]: Listening on Journal Sockets.483machine # [ 1.254892] systemd[1]: Listening on udev Kernel Socket.484machine # [ 1.255848] systemd[1]: Reached target Socket Units.485machine # [ 1.257632] systemd[1]: Starting Create List of Static Device Nodes...486machine # [ 1.261230] systemd[1]: Starting Load Kernel Module configfs...487machine # [ 1.270851] systemd[1]: Starting Journal Service...488machine # [ 1.280507] systemd[1]: Starting Load Kernel Modules...489machine # [ 1.295969] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os490machine # [ 1.301202] systemd[1]: Starting Coldplug All udev Devices...491machine # [ 1.314496] systemd[1]: Finished Create List of Static Device Nodes.492machine # [ 1.317332] systemd[1]: modprobe@configfs.service: Deactivated successfully.493machine # [ 1.324838] systemd[1]: Finished Load Kernel Module configfs.494machine # [ 1.331149] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config495machine # [ 1.333891] systemd-journald[65]: Collecting audit messages is disabled.496machine # [ 1.341624] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...497machine # [ 1.352120] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.498machine # [ 1.362566] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev499machine # [ 1.377981] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.500machine # [ 1.386630] systemd[1]: Starting Create Static Device Nodes in /dev...501machine # [ 1.399954] systemd[1]: Finished Load Kernel Modules.502machine # [ 1.407775] systemd[1]: Starting Apply Kernel Variables...503machine # [ 1.426718] systemd[1]: Finished Create Static Device Nodes in /dev.504machine # [ 1.433655] systemd[1]: Reached target Preparation for Local File Systems.505machine # [ 1.438498] systemd[1]: Reached target Local File Systems.506machine # [ 1.445622] systemd[1]: Starting Rule-based Manager for Device Events and Files...507machine # [ 1.310334] systemd-modules-load[66]: Inserted module 'dm_mod'508machine # [ 1.311589] systemd-modules-load[66]: Inserted module 'virtio_balloon'509machine # [ 1.457098] systemd[1]: Started Journal Service.510machine # [ 1.315346] systemd-modules-load[66]: Inserted module 'virtio_gpu'511machine # [ 1.322098] systemd[1]: Finished Apply Kernel Variables.512machine # [ 1.332329] systemd[1]: Starting Create System Files and Directories...513machine # [ 1.361667] systemd[1]: Finished Create System Files and Directories.514machine # [ 1.368376] systemd-udevd[73]: Using default interface naming scheme 'v261'.515machine # [ 1.391207] systemd[1]: Started Rule-based Manager for Device Events and Files.516machine # [ 1.451496] systemd[1]: Finished Coldplug All udev Devices.517machine # [ 1.453236] systemd[1]: Reached target System Initialization.518machine # [ 1.454097] systemd[1]: Reached target Basic System.519machine # [ 1.731742] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12520machine # [ 1.745056] virtio_blk virtio2: 1/0/0 default/read/poll queues521machine # [ 1.748148] serio: i8042 KBD port at 0x60,0x64 irq 1522machine # [ 1.754150] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)523machine # [ 1.757552] serio: i8042 AUX port at 0x60,0x64 irq 12524machine # [ 1.771106] uhci_hcd 0000:00:1d.0: UHCI Host Controller525machine # [ 1.781325] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 1526machine # [ 1.790535] uhci_hcd 0000:00:1d.0: detected 2 ports527machine # [ 1.796252] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180528machine # [ 1.801567] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18529machine # [ 1.802757] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 1.810985] usb usb1: Product: UHCI Host Controller531machine # [ 1.813448] usb usb1: Manufacturer: Linux 6.18.56 uhci_hcd532machine # [ 1.814248] usb usb1: SerialNumber: 0000:00:1d.0533machine # [ 1.820479] hub 1-0:1.0: USB hub found534machine # [ 1.679886] systemd[1]: Starting Virtual Console Setup...535machine # [ 1.826453] hub 1-0:1.0: 2 ports detected536machine # [ 1.832608] ehci-pci 0000:00:1d.7: EHCI Host Controller537machine # [ 1.833358] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 2538machine # [ 1.834648] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000539machine # [ 1.841563] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00540machine # [ 1.843971] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18541machine # [ 1.849569] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1542machine # [ 1.852046] usb usb2: Product: EHCI Host Controller543machine # [ 1.852908] usb usb2: Manufacturer: Linux 6.18.56 ehci_hcd544machine # [ 1.713934] systemd[1]: Found device /dev/disk/by-label/nixos.545machine # [ 1.859186] usb usb2: SerialNumber: 0000:00:1d.7546machine # [ 1.862524] hub 2-0:1.0: USB hub found547machine # [ 1.864733] hub 2-0:1.0: 6 ports detected548machine # [ 1.722117] systemd[1]: Reached target Initrd Root Device.549machine # [ 1.725215] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...550machine # [ 1.734441] systemd-vconsole-setup[96]: Configuration of first virtual console was skipped, ignoring remaining ones.551machine # [ 1.739760] systemd[1]: Finished Virtual Console Setup.552machine # [ 1.890378] hub 1-0:1.0: USB hub found553machine # [ 1.891120] hub 1-0:1.0: 2 ports detected554machine # [ 1.894576] uhci_hcd 0000:00:1d.1: UHCI Host Controller555machine # [ 1.895358] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3556machine # [ 1.762233] systemd-fsck[101]: nixos: clean, 12/65536 files, 13019/262144 blocks557machine # [ 1.768472] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.558machine # [ 1.914622] uhci_hcd 0000:00:1d.1: detected 2 ports559machine # [ 1.916942] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0560machine # [ 1.778736] (udev-worker)[85]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.561machine # [ 1.781164] (udev-worker)[85]: Network interface NamePolicy= disabled on kernel command line.562machine # [ 1.930617] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0563machine # [ 1.938608] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18564machine # [ 1.939869] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1565machine # [ 1.951137] usb usb3: Product: UHCI Host Controller566machine # [ 1.952623] usb usb3: Manufacturer: Linux 6.18.56 uhci_hcd567machine # [ 1.953394] usb usb3: SerialNumber: 0000:00:1d.1568machine # [ 1.955678] hub 3-0:1.0: USB hub found569machine # [ 1.958435] hub 3-0:1.0: 2 ports detected570machine # [ 1.961112] uhci_hcd 0000:00:1d.2: UHCI Host Controller571machine # [ 1.964810] SCSI subsystem initialized572machine # [ 1.965920] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4573machine # [ 1.968071] uhci_hcd 0000:00:1d.2: detected 2 ports574machine # [ 1.969108] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0575machine # [ 1.972534] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18576machine # [ 1.976429] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1577machine # [ 1.977543] usb usb4: Product: UHCI Host Controller578machine # [ 1.978225] usb usb4: Manufacturer: Linux 6.18.56 uhci_hcd579machine # [ 1.981429] usb usb4: SerialNumber: 0000:00:1d.2580machine # [ 1.982397] hub 4-0:1.0: USB hub found581machine # [ 1.983593] hub 4-0:1.0: 2 ports detected582machine # [ 2.025323] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode583machine # [ 2.026523] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)584machine # [ 2.027358] ahci 0000:00:1f.2: flags: 64bit ncq only585machine # [ 2.031119] scsi host0: ahci586machine # [ 2.032380] scsi host1: ahci587machine # [ 2.033939] scsi host2: ahci588machine # [ 2.035229] scsi host3: ahci589machine # [ 2.036625] scsi host4: ahci590machine # [ 2.038079] scsi host5: ahci591machine # [ 2.039142] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1592machine # [ 2.040713] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1593machine # [ 2.041867] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1594machine # [ 2.043053] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1595machine # [ 2.044225] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1596machine # [ 2.045388] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1597machine # [ 2.103496] usb 2-1: new high-speed USB device number 2 using ehci-pci598machine # [ 2.232889] usb 2-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00599machine # [ 2.235799] usb 2-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10600machine # [ 2.238970] usb 2-1: Product: QEMU USB Tablet601machine # [ 2.240825] usb 2-1: Manufacturer: QEMU602machine # [ 2.242548] usb 2-1: SerialNumber: 28754-0000:00:1d.7-1603machine # [ 2.290386] hid: raw HID events driver (C) Jiri Kosina604machine # [ 2.166393] systemd[1]: Mounting /sysroot...605machine # [ 2.361154] ata1: SATA link down (SStatus 0 SControl 300)606machine # [ 2.362944] ata5: SATA link down (SStatus 0 SControl 300)607machine # [ 2.365040] ata6: SATA link down (SStatus 0 SControl 300)608machine # [ 2.366761] ata4: SATA link down (SStatus 0 SControl 300)609machine # [ 2.368494] ata2: SATA link down (SStatus 0 SControl 300)610machine # [ 2.370513] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)611machine # [ 2.372312] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100612machine # [ 2.374142] ata3.00: applying bridge limits613machine # [ 2.375624] ata3.00: configured for UDMA/100614machine # [ 2.378605] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5615machine # [ 2.435541] usbcore: registered new interface driver usbhid616machine # [ 2.436364] usbhid: USB HID core driver617machine # [ 2.449657] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0/0003:0627:0001.0001/input/input2618machine # [ 2.452563] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0619machine # [ 2.487652] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray620machine # [ 2.496551] cdrom: Uniform CD-ROM driver Revision: 3.20621machine # [ 2.498724] EXT4-fs (vda): mounted filesystem 4fd9cbef-57a5-4cc2-a3b1-5b6236e4b44c r/w with ordered data mode. Quota mode: none.622machine # [ 2.358522] systemd[1]: Mounted /sysroot.623machine # [ 2.360160] systemd[1]: Reached target Initrd Root File System.624machine # [ 2.366234] systemd[1]: Starting Mountpoints Configured in the Real Root...625machine # [ 2.379782] systemd-sysroot-fstab-check[135]: /sysroot should be mounted in the initrd, will request daemon-reload.626machine # [ 2.383533] systemd[1]: Reload requested from client PID 135 ('systemd-sysroot') (unit initrd-parse-etc.service)...627machine # [ 2.386171] systemd[1]: Reloading...628machine # [ 2.461323] systemd[1]: Reloading finished in 75 ms.629machine # [ 2.468860] systemd-sysroot-fstab-check[135]: Requesting initrd-fs.target/start/replace...630machine # [ 2.473206] systemd-sysroot-fstab-check[135]: Requesting swap.target/start/replace...631machine # [ 2.476815] systemd[1]: initrd-parse-etc.service: Deactivated successfully.632machine # [ 2.477881] systemd[1]: Finished Mountpoints Configured in the Real Root.633machine # [ 2.478871] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.634machine # [ 3.168245] systemd[1]: Mounting /sysroot/nix/.ro-store...635machine # [ 3.176208] systemd[1]: Mounting /sysroot/nix/.rw-store...636machine # [ 3.183161] systemd[1]: Mounting /sysroot/run...637machine # [ 3.193331] systemd[1]: Mounting /sysroot/tmp/shared...638machine # [ 3.206757] systemd[1]: Mounting /sysroot/tmp/xchg...639machine # [ 3.242664] systemd[1]: Mounted /sysroot/nix/.rw-store.640machine # [ 3.248126] systemd[1]: Mounted /sysroot/nix/.ro-store.641machine # [ 3.250935] systemd[1]: Mounted /sysroot/run.642machine # [ 3.252614] systemd[1]: Mounted /sysroot/tmp/shared.643machine # [ 3.255205] systemd[1]: Mounted /sysroot/tmp/xchg.644machine # [ 3.259601] systemd[1]: Starting rw-sysroot-nix-store.service...645machine # [ 3.270731] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.646machine # [ 3.272399] systemd[1]: Finished rw-sysroot-nix-store.service.647machine # [ 4.169282] systemd[1]: Mounting /sysroot/nix/store...648machine # [ 4.217288] systemd[1]: Mounted /sysroot/nix/store.649machine # [ 4.218767] systemd[1]: Reached target Initrd File Systems.650machine # [ 4.220718] systemd[1]: Starting Find NixOS closure...651machine # [ 4.223584] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...652machine # [ 4.247096] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.653machine # [ 4.250070] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.654machine # [ 4.254256] systemd[1]: Finished Find NixOS closure.655machine # [ 4.255544] systemd[1]: Reached target Initrd Default Target.656machine # [ 4.257680] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...657machine # [ 4.271957] systemd[1]: Stopped target Initrd Default Target.658machine # [ 4.274058] systemd[1]: Stopped target Basic System.659machine # [ 4.274746] systemd[1]: Stopped target Initrd Root Device.660machine # [ 4.275480] systemd[1]: Stopped target Path Units.661machine # [ 4.276217] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.662machine # [ 4.277201] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.663machine # [ 4.278225] systemd[1]: Stopped target Slice Units.664machine # [ 4.279232] systemd[1]: Stopped target Socket Units.665machine # [ 4.280053] systemd[1]: Stopped target System Initialization.666machine # [ 4.281311] systemd[1]: Stopped target Swaps.667machine # [ 4.282177] systemd[1]: Stopped target Timer Units.668machine # [ 4.283782] systemd[1]: dbus.socket: Deactivated successfully.669machine # [ 4.284620] systemd[1]: Closed D-Bus System Message Bus Socket.670machine # [ 4.285459] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.671machine # [ 4.286468] systemd[1]: Stopped Find NixOS closure.672machine # [ 4.288207] systemd[1]: Starting rw-sysroot-nix-store.service...673machine # [ 4.289249] systemd[1]: systemd-sysctl.service: Deactivated successfully.674machine # [ 4.290484] systemd[1]: Stopped Apply Kernel Variables.675machine # [ 4.292122] systemd[1]: systemd-modules-load.service: Deactivated successfully.676machine # [ 4.293248] systemd[1]: Stopped Load Kernel Modules.677machine # [ 4.294236] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.678machine # [ 4.295427] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.679machine # [ 4.297060] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.680machine # [ 4.298169] systemd[1]: Stopped Create System Files and Directories.681machine # [ 4.299701] systemd[1]: Stopped target Local File Systems.682machine # [ 4.300465] systemd[1]: Stopped target Preparation for Local File Systems.683machine # [ 4.302560] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.684machine # [ 4.303567] systemd[1]: Stopped Coldplug All udev Devices.685machine # [ 4.305148] systemd[1]: Stopping Rule-based Manager for Device Events and Files...686machine # [ 4.307146] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.687machine # [ 4.308212] systemd[1]: Stopped Virtual Console Setup.688machine # [ 4.317107] systemd[1]: initrd-cleanup.service: Deactivated successfully.689machine # [ 4.319113] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.690machine # [ 4.321346] systemd[1]: systemd-udevd.service: Deactivated successfully.691machine # [ 4.323095] systemd[1]: Stopped Rule-based Manager for Device Events and Files.692machine # [ 4.330231] systemd[1]: Starting Cleanup udev Database...693machine # [ 4.333086] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.694machine # [ 4.334159] systemd[1]: Stopped Create Static Device Nodes in /dev.695machine # [ 4.334985] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.696machine # [ 4.336130] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.697machine # [ 4.337068] systemd[1]: kmod-static-nodes.service: Deactivated successfully.698machine # [ 4.337959] systemd[1]: Stopped Create List of Static Device Nodes.699machine # [ 4.338807] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.700machine # [ 4.341166] systemd[1]: Finished rw-sysroot-nix-store.service.701machine # [ 4.356217] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.702machine # [ 4.358168] systemd[1]: Finished Cleanup udev Database.703machine # [ 4.359424] systemd[1]: Reached target Switch Root.704machine # [ 4.361198] systemd[1]: Starting NixOS Activation...705machine # [ 4.441084] initrd-nixos-activation-start[187]: booting system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test706machine # [ 4.472543] initrd-nixos-activation-start[187]: running activation script...707machine # [ 4.659973] initrd-nixos-activation-start[210]: setting up /etc...708machine # [ 4.764675] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.709machine # [ 4.766083] systemd[1]: Finished NixOS Activation.710machine # [ 4.768064] systemd[1]: Starting Switch Root...711machine # [ 4.782812] systemd[1]: Switching root.712machine # [ 5.051927] systemd-journald[65]: Received SIGTERM from PID 1 (systemd).713machine # [ 5.115755] NET: Registered PF_VSOCK protocol family714machine # [ 5.140267] systemd[1]: systemd 263-7f4df15 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE +EXPERIMENTAL-ENVVAR)715machine # [ 5.144239] systemd[1]: Detected virtualization kvm.716machine # [ 5.144922] systemd[1]: Detected architecture x86-64.717machine # [ 5.145688] systemd[1]: Detected first boot.718machine # [ 5.147304] systemd[1]: Initializing machine ID from random generator.719machine # [ 5.235751] systemd[1]: bpf-restrict-fs: LSM BPF program attached720machine # [ 5.344198] systemd[1]: Applying preset policy.721machine # [ 5.514454] systemd[1]: Populated /etc with preset unit settings.722machine # [ 5.691049] systemd[1]: initrd-switch-root.service: Deactivated successfully.723machine # [ 5.692351] systemd[1]: Stopped initrd-switch-root.service.724machine # [ 5.694823] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.725machine # [ 5.696891] systemd[1]: Created slice Slice /system/getty.726machine # [ 5.698247] systemd[1]: Created slice User and Session Slice.727machine # [ 5.699161] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.728machine # [ 5.700463] systemd[1]: Started Forward Password Requests to Wall Directory Watch.729machine # [ 5.701536] systemd[1]: Expecting device /dev/hvc0...730machine # [ 5.702279] systemd[1]: Expecting device /dev/ttyS0...731machine # [ 5.703026] systemd[1]: Reached target Local Encrypted Volumes.732machine # [ 5.703835] systemd[1]: Stopped target initrd-fs.target.733machine # [ 5.704634] systemd[1]: Stopped target initrd-root-fs.target.734machine # [ 5.705442] systemd[1]: Stopped target initrd-switch-root.target.735machine # [ 5.706306] systemd[1]: Reached target Virtual Machines and Containers.736machine # [ 5.707218] systemd[1]: Reached target Path Units.737machine # [ 5.707921] systemd[1]: Reached target Remote File Systems.738machine # [ 5.708725] systemd[1]: Reached target Slice Units.739machine # [ 5.720898] systemd[1]: Reached target Swaps.740machine # [ 5.722802] systemd[1]: Listening on Query the User Interactively for a Password.741machine # [ 5.725101] systemd[1]: Listening on Process Core Dump Socket.742machine # [ 5.726886] systemd[1]: Listening on Credential Encryption/Decryption.743machine # [ 5.728568] systemd[1]: Listening on Factory Reset Management.744machine # [ 5.729531] systemd[1]: Listening on Hostname Service Socket.745machine # [ 5.732179] systemd[1]: Starting Journal Log Access Socket...746machine # [ 5.733599] systemd[1]: Listening on Journal Audit Socket.747machine # [ 5.736162] systemd[1]: Listening on Console Output Muting Service Socket.748machine # [ 5.737251] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.749machine # [ 5.738446] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os750machine # [ 5.739802] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki751machine # [ 5.743676] systemd[1]: Listening on Disk Repartitioning Service Socket.752machine # [ 5.744749] systemd[1]: Listening on udev Varlink Socket.753machine # [ 5.747212] systemd[1]: Mounting Huge Pages File System...754machine # [ 5.751009] systemd[1]: Mounting POSIX Message Queue File System...755machine # [ 5.758017] systemd[1]: Mounting Kernel Debug File System...756machine # [ 5.764836] systemd[1]: Mounting Kernel Trace File System...757machine # [ 5.774540] systemd[1]: Starting Create List of Static Device Nodes...758machine # [ 5.784169] systemd[1]: Starting Load Kernel Module configfs...759machine # [ 5.792770] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm760machine # [ 5.800361] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore761machine # [ 5.811441] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse762machine # [ 5.824155] systemd[1]: Mounting FUSE Control File System...763machine # [ 5.828247] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67764machine # [ 5.844033] systemd[1]: Starting Journal Service...765machine # [ 5.852130] systemd[1]: Starting Load Kernel Modules...766machine # [ 5.864618] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...767machine # [ 5.879492] systemd[1]: Starting Remount Root and Kernel File Systems...768machine # [ 5.881603] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os769machine # [ 5.887609] systemd[1]: Starting Coldplug All udev Devices...770machine # [ 5.902549] systemd-journald[281]: Collecting audit messages is enabled.771machine # [ 5.908134] systemd[1]: Listening on Journal Log Access Socket.772machine # [ 5.913732] systemd[1]: Mounted Huge Pages File System.773machine # [ 5.918657] systemd[1]: Mounted POSIX Message Queue File System.774machine # [ 5.922830] systemd[1]: Mounted Kernel Debug File System.775machine # [ 5.781393] systemd[1]: Queued start job for default target Multi-User System.776machine # [ 5.927317] systemd[1]: Started Journal Service.777machine # [ 5.784973] systemd[1]: Unnecessary job was removed for /sys/devices/pnp0/00:03/00:03:0/00:03:0.0/tty/ttyS0.778machine # [ 5.787557] systemd[1]: Unnecessary job was removed for /sys/devices/virtual/tty/hvc0.779machine # [ 5.790638] systemd[1]: systemd-journald.service: Deactivated successfully.780machine # [ 5.792463] systemd[1]: Mounted Kernel Trace File System.781machine # [ 5.795576] systemd[1]: Finished Create List of Static Device Nodes.782machine # [ 5.796841] systemd[1]: modprobe@configfs.service: Deactivated successfully.783machine # [ 5.807087] systemd[1]: Finished Load Kernel Module configfs.784machine # [ 5.809266] systemd[1]: Mounted FUSE Control File System.785machine # [ 5.961868] loop: module loaded786machine # [ 5.821576] systemd-modules-load[282]: Inserted module 'loop'787machine # [ 5.833204] systemd[1]: Mounting Kernel Configuration File System...788machine # [ 5.840595] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...789machine # [ 5.844145] systemd[1]: Finished Load Kernel Modules.790machine # [ 5.994443] EXT4-fs (vda): re-mounted 4fd9cbef-57a5-4cc2-a3b1-5b6236e4b44c.791machine # [ 5.857121] systemd[1]: Starting Firewall...792machine # [ 5.869103] systemd[1]: Starting Apply Kernel Variables...793machine # [ 5.877900] systemd[1]: Finished Remount Root and Kernel File Systems.794machine # [ 5.888383] systemd-oomd[284]: No swap; memory pressure usage will be degraded795machine # [ 5.894684] systemd[1]: Listening on Disk Image Download Service Socket.796machine # [ 5.908159] systemd[1]: Starting Flush Journal to Persistent Storage...797machine # [ 5.909323] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore798machine # [ 5.920253] systemd[1]: Starting Load/Save OS Random Seed...799machine # [ 5.923235] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os800machine # [ 5.924502] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.801machine # [ 6.131040] systemd-journald[281]: Received client request to flush runtime journal.802machine # [ 6.083309] systemd[1]: Mounted Kernel Configuration File System.803machine # [ 6.085388] systemd[1]: Finished Load/Save OS Random Seed.804machine # [ 6.088584] systemd[1]: Reached target First Boot Complete.805machine # [ 6.089940] systemd[1]: Finished Apply Kernel Variables.806machine # [ 6.090975] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.807machine # [ 6.092719] systemd[1]: Starting Create Static Device Nodes in /dev...808machine # [ 6.095441] systemd[1]: Finished Flush Journal to Persistent Storage.809machine # [ 6.185527] systemd[1]: Finished Coldplug All udev Devices.810machine # [ 6.193373] systemd[1]: Finished Create Static Device Nodes in /dev.811machine # [ 6.194595] systemd[1]: Reached target Preparation for Local File Systems.812machine # [ 6.198568] systemd[1]: Starting Rule-based Manager for Device Events and Files...813machine # [ 6.245343] systemd-udevd[319]: Using default interface naming scheme 'v261'.814machine # [ 6.289338] systemd[1]: Started Rule-based Manager for Device Events and Files.815machine # [ 6.359790] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse816machine # [ 6.427107] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.817machine # [ 6.452943] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.818machine # [ 6.475053] (udev-worker)[354]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.819machine # [ 6.478344] (udev-worker)[354]: Network interface NamePolicy= disabled on kernel command line.820machine # [ 6.552676] systemd[1]: Mounting /run/wrappers...821machine # [ 6.585817] systemd[1]: Condition check resulted in Virtio network device being skipped.822machine # [ 6.586930] systemd[1]: Condition check resulted in Virtio network device being skipped.823machine # [ 6.588021] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore824machine # [ 6.589420] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67825machine # [ 6.591584] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore826machine # [ 6.593947] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os827machine # [ 6.595542] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os828machine # [ 6.604861] systemd[1]: Mounted /run/wrappers.829machine # [ 6.605648] systemd[1]: Reached target Local File Systems.830machine # [ 6.610118] systemd[1]: Listening on Boot Loader Control Service Socket.831machine # [ 6.613705] systemd[1]: Starting Create SUID/SGID Wrappers...832machine # [ 6.615520] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.833machine # [ 6.620940] systemd[1]: Starting Save Transient machine-id to Disk...834machine # [ 6.626927] systemd[1]: Starting Create System Files and Directories...835machine # [ 6.758131] systemd[1]: Finished Create System Files and Directories.836machine # [ 6.768849] systemd[1]: Starting Rebuild Journal Catalog...837machine # [ 6.778386] systemd[1]: Starting Record System Boot/Shutdown in UTMP...838machine # [ 6.783111] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.839machine # [ 6.787255] systemd[1]: Finished Save Transient machine-id to Disk.840machine # [ 6.863509] systemd[1]: Finished Record System Boot/Shutdown in UTMP.841machine # [ 6.895281] systemd[1]: Finished Rebuild Journal Catalog.842machine # [ 7.045844] mousedev: PS/2 mouse device common for all mice843machine # [ 6.902661] systemd[1]: Starting Update is Completed...844machine # [ 7.050641] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console845machine # [ 6.946449] systemd[1]: Finished Update is Completed.846machine # [ 7.136979] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.847machine # [ 7.138453] systemd[1]: Finished Create SUID/SGID Wrappers.848machine # [ 7.140268] systemd[1]: Reached target System Initialization.849machine # [ 7.061314] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3850machine # [ 7.144179] systemd[1]: Started Discard unused filesystem blocks once a week.851machine # [ 7.145235] systemd[1]: Started Daily Cleanup of Temporary Directories.852machine # [ 7.147169] systemd[1]: Reached target Timer Units.853machine # [ 7.147934] systemd[1]: Listening on D-Bus System Message Bus Socket.854machine # [ 7.149735] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.855machine # [ 7.152073] systemd[1]: Reached target Socket Units.856machine # [ 7.152806] systemd[1]: Reached target Basic System.857machine # [ 7.158136] systemd[1]: Started backdoor.service.858machine # [ 7.162092] systemd[1]: Starting Import lastlog data into lastlog2 database...859machine # [ 7.092932] ACPI: button: Power Button [PWRF]860machine # [ 7.186006] rtc_cmos PNP0B00:00: RTC can wake from S4861machine # [ 7.171136] systemd[1]: Starting Name Service Cache Daemon (nsncd)...862machine # [ 7.184189] systemd[1]: Starting Post-Boot Actions...863machine # [ 7.202220] systemd[1]: Started Reset console on configuration changes.864machine # [ 7.215205] rtc_cmos PNP0B00:00: registered as rtc0865machine # [ 7.215275] rtc_cmos PNP0B00:00: setting system clock to 2026-10-19T03:00:17 UTC (1792378817)866machine # [ 7.221727] systemd[1]: Starting resolvconf update...867machine # [ 7.215377] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs868machine # [ 7.215823] systemd-journald[281]: Time jumped backwards, rotating.869machine # [ 7.267163] systemd[1]: Starting D-Bus System Message Bus...870machine # [ 7.273215] systemd[1]: Finished Firewall.871machine # connecting to host...872machine # [ 7.305883] systemd[1]: Started Name Service Cache Daemon (nsncd).873machine # [ 7.309201] nsncd[486]: Oct 19 03:00:17.732 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"874machine # [ 7.315231] systemd[1]: Finished Post-Boot Actions.875machine: Guest shell says: b'Spawning backdoor root shell...\n'876machine: connected to guest root shell877machine # [ 7.280079] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4878machine: (connecting took 8.00 seconds)879machine # [ 7.286296] Console: switching to colour dummy device 80x25880machine: (finished: waiting for the VM to finish booting, in 8.85 seconds)881machine # [ 7.299222] parport_pc 00:02: reported by Plug and Play ACPI882machine # [ 7.327704] systemd[1]: Reached target Host and Network Name Lookups.883machine # [ 7.328599] systemd[1]: Reached target User and Group Name Lookups.884machine # [ 7.349910] systemd[1]: Starting User Login Management...885machine # [ 7.395666] systemd[1]: Finished Import lastlog data into lastlog2 database.886machine # [ 7.299330] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]887machine # [ 7.319317] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized888machine # [ 7.555861] [drm] Found bochs VGA, ID 0xb0c5.889machine # [ 7.555864] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.890machine # [ 7.594428] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic891machine # [ 7.595122] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0892machine # [ 7.609684] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt893machine # [ 7.612760] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD894machine # [ 7.491290] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'...895machine # [ 7.509455] systemd-logind[521]: New seat seat0.896machine # [ 7.680862] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6897machine # [ 7.682774] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5898machine # [ 7.554704] systemd[1]: Started User Login Management.899machine # [ 7.566191] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync'900machine # [ 7.567236] dbus-broker-launch[499]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"901machine # [ 7.573494] systemd[1]: Starting linger-users.service...902machine # [ 7.584876] systemd[1]: Stopped target Host and Network Name Lookups.903machine # [ 7.585802] systemd[1]: Stopping Host and Network Name Lookups...904machine # [ 7.586734] systemd[1]: Stopped target User and Group Name Lookups.905machine # [ 7.589138] systemd[1]: Stopping User and Group Name Lookups...906machine # [ 7.592115] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...907machine # [ 7.599745] systemd[1]: Starting Virtual Console Setup...908machine # [ 7.603150] systemd[1]: nscd.service: Deactivated successfully.909machine # [ 7.608842] systemd[1]: Stopped Name Service Cache Daemon (nsncd).910machine # [ 7.633147] systemd[1]: Started D-Bus System Message Bus.911machine # [ 7.639426] systemd-logind[521]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)912machine # [ 7.654719] dbus-broker-launch[499]: Ready913machine # [ 7.675203] systemd[1]: Starting Name Service Cache Daemon (nsncd)...914machine # [ 7.851170] Console: switching to colour frame buffer device 160x50915machine # [ 7.939456] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device916machine # [ 7.730199] systemd[1]: Created slice Slice /user/1001.917machine # [ 7.796946] systemd[1]: Starting User Runtime Directory /run/user/1001...918machine # [ 7.798489] nsncd[583]: Oct 19 03:00:18.189 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"919machine # [ 7.803880] systemd-logind[521]: Watching system buttons on /dev/input/event2 (Power Button)920machine # [ 7.805297] systemd[1]: Started Name Service Cache Daemon (nsncd).921machine # [ 7.806556] systemd[1]: linger-users.service: Deactivated successfully.922machine # [ 7.807504] systemd[1]: Finished linger-users.service.923machine # [ 7.809113] systemd[1]: Reached target Host and Network Name Lookups.924machine # [ 7.809965] systemd[1]: Reached target User and Group Name Lookups.925machine # [ 7.810837] systemd[1]: Finished resolvconf update.926machine # [ 7.811496] systemd[1]: Reached target Preparation for Network.927machine # [ 7.816098] systemd[1]: Starting DHCP Client...928machine # [ 7.816743] systemd[1]: Starting Address configuration of eth1...929machine # [ 7.825066] systemd[1]: Starting Extra networking commands....930machine # [ 7.826868] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.931machine # [ 7.836596] systemd[1]: Stopped Virtual Console Setup.932machine # [ 8.000450] ppdev: user-space parallel port driver933machine # [ 7.866186] systemd[1]: Starting Virtual Console Setup...934machine # [ 7.905567] systemd[1]: Finished User Runtime Directory /run/user/1001.935machine # [ 7.936317] systemd[1]: Starting User Manager for UID 1001...936machine # [ 7.949652] systemd-logind[521]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)937machine # [ 8.103158] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)938machine # [ 8.137275] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)939machine # [ 7.999790] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.940machine # [ 8.006133] systemd[1]: Stopped Virtual Console Setup.941machine # [ 8.010124] systemd[1]: Starting Virtual Console Setup...942machine # [ 8.037364] (systemd)[619]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)943machine # [ 8.051872] systemd-logind[521]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.944machine # [ 8.072874] network-addresses-eth1-start[609]: adding address 192.168.1.1/24... done945machine # [ 8.091637] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy946machine # [ 8.158193] network-addresses-eth1-start[609]: adding address 2001:db8:1::1/64... done947machine # [ 8.206613] systemd[1]: Finished Address configuration of eth1.948machine # [ 8.466614] kvm_amd: TSC scaling supported949machine # [ 8.467075] kvm_amd: Nested Virtualization enabled950machine # [ 8.471261] kvm_amd: Nested Paging enabled951machine # [ 8.471821] kvm_amd: LBR virtualization supported952machine # [ 8.472285] kvm_amd: Virtual VMLOAD VMSAVE supported953machine # [ 8.329992] dhcpcd[630]: dhcpcd-10.3.2 starting954machine # [ 8.476513] kvm_amd: Virtual GIF supported955machine # [ 8.476919] kvm_amd: Virtual NMI enabled956machine # [ 8.341177] systemd[619]: Queued start job for default target Main User Target.957machine # [ 8.346912] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy958machine # [ 8.350285] dhcpcd[689]: dev: loaded udev959machine # [ 8.350970] systemd[619]: Created slice User Application Slice.960machine # [ 8.355263] systemd[619]: Started Daily Cleanup of User's Temporary Directories.961machine # [ 8.361286] systemd[619]: Reached target Paths.962machine # [ 8.362198] systemd[619]: Reached target Timers.963machine # [ 8.362874] systemd[619]: Starting D-Bus User Message Bus Socket...964machine # [ 8.365373] systemd[619]: Starting Create User Files and Directories...965machine # [ 8.382298] systemd[1]: Finished Extra networking commands..966machine # [ 8.384549] systemd[1]: Reached target Network.967machine # [ 8.392708] systemd[1]: Starting Permit User Sessions...968machine # [ 8.541306] 8021q: 802.1Q VLAN Support v1.8969machine # [ 8.544437] 8021q: adding VLAN 0 to HW filter on device eth1970machine # [ 8.561180] EDAC MC: Ver: 3.0.0971machine # [ 8.423151] systemd[619]: Finished Create User Files and Directories.972machine # [ 8.445563] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.973machine # [ 8.471074] systemd[619]: Listening on D-Bus User Message Bus Socket.974machine # [ 8.472069] systemd[619]: Reached target Sockets.975machine # [ 8.472741] systemd[619]: Reached target Basic System.976machine # [ 8.474496] systemd[1]: Started User Manager for UID 1001.977machine # [ 8.477423] systemd[619]: Starting Run user-specific NixOS activation...978machine # [ 8.489165] systemd[1]: Finished Permit User Sessions.979machine # [ 8.495399] systemd[1]: Started Getty on tty1.980machine # [ 8.496795] systemd[1]: Reached target Login Prompts.981machine # [ 8.690270] cfg80211: Loading compiled-in X.509 certificates for regulatory database982machine # [ 8.559796] systemd[619]: Finished Run user-specific NixOS activation.983machine # [ 8.560934] systemd[619]: Reached target Main User Target.984machine # [ 8.562141] systemd[619]: Startup finished in 483ms.985machine # [ 8.712889] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'986machine # [ 8.713597] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'987machine # [ 8.715524] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2988machine # [ 8.716369] cfg80211: failed to load regulatory.db989machine # [ 8.744483] 8021q: adding VLAN 0 to HW filter on device eth0990machine # [ 8.601436] dhcpcd[689]: eth0: waiting for carrier991machine # [ 8.603062] dhcpcd[689]: libudev: received NULL device992machine # [ 8.604199] dhcpcd[689]: libudev: received NULL device993machine # [ 8.605470] dhcpcd[689]: eth0: carrier acquired994machine # [ 8.610214] dhcpcd[689]: DUID 00:01:00:01:32:68:44:43:52:54:00:12:34:56995machine # [ 8.611452] dhcpcd[689]: eth0: IAID 00:12:34:56996machine # [ 8.612197] dhcpcd[689]: eth0: adding address fe80::5054:ff:fe12:3456997machine # [ 8.648446] systemd-vconsole-setup[626]: Configuration of first virtual console was skipped, ignoring remaining ones.998machine # [ 8.652800] systemd[1]: Finished Virtual Console Setup.999machine # [ 9.622322] dhcpcd[689]: eth0: soliciting a DHCP lease1000machine # [ 9.776088] NET: Registered PF_PACKET protocol family1001machine # [ 9.635820] dhcpcd[689]: eth0: offered 10.0.2.15 from 10.0.2.21002machine # [ 9.637618] dhcpcd[689]: eth0: probing address 10.0.2.15/241003machine # [ 10.086810] dhcpcd[689]: eth0: soliciting an IPv6 router1004machine # [ 10.089449] dhcpcd[689]: eth0: Router Advertisement from fe80::21005machine # [ 10.091937] dhcpcd[689]: eth0: adding address fec0::5054:ff:fe12:3456/641006machine # [ 10.094362] dhcpcd[689]: eth0: adding route to fec0::/641007machine # [ 10.096238] dhcpcd[689]: eth0: adding default route via fe80::21008machine # [ 14.777838] dhcpcd[689]: eth0: leased 10.0.2.15 for 86400 seconds1009machine # [ 14.781293] dhcpcd[689]: eth0: adding route to 10.0.2.0/241010machine # [ 14.783856] dhcpcd[689]: eth0: adding default route via 10.0.2.21011machine # [ 14.876157] systemd[1]: Started DHCP Client.1012machine # [ 14.878482] systemd[1]: Reached target Multi-User System.1013machine # [ 14.879453] systemd[1]: Startup finished in 891ms (kernel) + 4.056s (initrd) + 9.930s (userspace) = 14.878s.1014machine: (finished: waiting for unit multi-user.target, in 16.64 seconds)1015machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test1016machine # Checking switch inhibitors... done1017machine # [ 15.341846] nixos[841]: switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test1018machine # [ 15.344103] systemd[1]: Stopped target Remote File Systems.1019machine # [ 15.345820] systemd[1]: Stopped target Local File Systems.1020machine # activating the configuration...1021machine # setting up /etc...1022machine # [ 15.571723] systemd[1]: Reload requested from client PID 841 ('.switch-to-conf') (unit backdoor.service)...1023machine # [ 15.573428] systemd[1]: Reloading...1024machine # [ 15.813982] systemd[1]: Reloading finished in 238 ms.1025machine # reloading user units for usertest...1026machine # [ 15.842992] systemd[619]: Created slice User Core Session Slice.1027machine # [ 15.845415] systemd[619]: Starting D-Bus User Message Bus...1028machine # [ 15.874182] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.import1.service'1029machine # [ 15.877230] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1030machine # [ 15.880027] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1031machine # [ 15.882725] dbus-broker-launch[911]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1032machine # [ 15.885500] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1033machine # [ 15.887117] dbus-broker-launch[911]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1034machine # [ 15.889170] systemd[619]: Started D-Bus User Message Bus.1035machine # [ 15.893199] dbus-broker-launch[911]: Ready1036machine # [ 15.930075] systemd[619]: Reexecution requested from client PID 910 ('switch-to-confi')...1037machine # [ 15.932111] systemd[619]: Reexecuting.1038machine # [ 15.953253] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1039machine # [ 16.027754] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1040machine # [ 16.033152] systemd[619]: Reload requested from client PID 910 ('switch-to-confi')...1041machine # [ 16.034931] systemd[619]: Reloading...1042machine # [ 16.105809] systemd[619]: Reloading finished in 69 ms.1043machine # [ 16.110894] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1044machine # restarting the following user units: nixos-activation.service1045machine # [ 16.117672] systemd[619]: Stopped Run user-specific NixOS activation.1046machine # [ 16.119071] systemd[619]: Stopping Run user-specific NixOS activation...1047machine # [ 16.121184] systemd[619]: Starting Run user-specific NixOS activation...1048machine # [ 16.143313] systemd[619]: Finished Run user-specific NixOS activation.1049machine # restarting sysinit-reactivation.target1050machine # [ 16.152749] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1051machine # [ 16.219843] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1052machine # [ 16.221659] systemd[1]: Reached target Reactivate sysinit units.1053machine # [ 16.226642] systemd[1]: Reached target Remote File Systems.1054machine # [ 16.229829] systemd[1]: Starting linger-users.service...1055machine # [ 16.231433] systemd[1]: Reached target Local File Systems.1056machine # [ 16.240227] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1057machine # [ 16.241859] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1058machine # [ 16.245068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1059machine # [ 16.251488] systemd[1]: Starting Create SUID/SGID Wrappers...1060machine # [ 16.252342] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1061machine # [ 16.253517] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671062machine # [ 16.257134] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1063machine # [ 16.259665] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1064machine # [ 16.262208] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1065machine # [ 16.298489] systemd[1]: linger-users.service: Deactivated successfully.1066machine # [ 16.300511] systemd[1]: Finished linger-users.service.1067machine # [ 16.414843] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1068machine # [ 16.416709] systemd[1]: Finished Create SUID/SGID Wrappers.1069machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1070machine # [ 16.678557] nixos[841]: finished switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test1071machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test, in 1.57 seconds)1072machine: must succeed: rm /etc/NIXOS1073machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)1074machine: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test1075machine # this should succeed (config: /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test, action: test)1076machine # Checking switch inhibitors... done1077machine # [ 16.934078] nixos[988]: switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test1078machine # activating the configuration...1079machine # [ 16.937934] systemd[1]: Stopped target Remote File Systems.1080machine # [ 16.938954] systemd[1]: Stopped target Local File Systems.1081machine # removing user ‘usertest’1082machine # setting up /etc...1083machine # [ 17.164618] systemd[1]: Reload requested from client PID 988 ('.switch-to-conf') (unit backdoor.service)...1084machine # [ 17.166610] systemd[1]: Reloading...1085machine # [ 17.418856] systemd[1]: Reloading finished in 250 ms.1086machine # reloading user units for usertest...1087machine # [ 17.484360] systemd[619]: Reexecution requested from client PID 1059 ('switch-to-confi')...1088machine # [ 17.486943] systemd[619]: Reexecuting.1089machine # [ 17.508179] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1090machine # [ 17.582562] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1091machine # [ 17.587831] systemd[619]: Reload requested from client PID 1059 ('switch-to-confi')...1092machine # [ 17.589266] systemd[619]: Reloading...1093machine # [ 17.663750] systemd[619]: Reloading finished in 73 ms.1094machine # [ 17.668914] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1095machine # restarting the following user units: nixos-activation.service1096machine # [ 17.676168] systemd[619]: Stopped Run user-specific NixOS activation.1097machine # [ 17.677081] systemd[619]: Stopping Run user-specific NixOS activation...1098machine # [ 17.679227] systemd[619]: Starting Run user-specific NixOS activation...1099machine # [ 17.703828] systemd[619]: Finished Run user-specific NixOS activation.1100machine # restarting sysinit-reactivation.target1101machine # [ 17.707573] systemd[1]: Stopped target Reactivate sysinit units.1102machine # [ 17.708896] systemd[1]: Stopping Reactivate sysinit units...1103machine # [ 17.709913] systemd[1]: Reached target Reactivate sysinit units.1104machine # reloading the following units: dbus-broker.service1105machine # [ 17.713609] dbus-broker-launch[499]: Caught SIGHUP, trigger reload.1106machine # [ 17.714659] systemd[1]: Reloading D-Bus System Message Bus...1107machine # [ 17.719459] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'...1108machine # [ 17.721376] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync'1109machine # [ 17.722463] dbus-broker-launch[499]: Invalid user-name in /nix/store/2h842v43dq0mx3dmyabal4yr4l01qpk4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1110machine # [ 17.730938] systemd[1]: Reloaded D-Bus System Message Bus.1111machine # restarting the following units: network-addresses-eth1.service1112machine # [ 17.735899] systemd[1]: Stopping Address configuration of eth1...1113machine # [ 17.755732] network-addresses-eth1-pre-stop[1071]: deleting address 192.168.1.1/24... done1114machine # [ 17.760840] network-addresses-eth1-pre-stop[1071]: deleting address 2001:db8:1::1/64... done1115machine # [ 17.765985] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1116machine # [ 17.767641] systemd[1]: Stopped Address configuration of eth1.1117machine # [ 17.771062] systemd[1]: Starting Address configuration of eth1...1118machine # [ 17.799529] network-addresses-eth1-start[1076]: adding address 192.168.1.2/24... done1119machine # [ 17.805065] network-addresses-eth1-start[1076]: adding address 2001:db8:1::2/64... done1120machine # [ 17.812129] systemd[1]: Finished Address configuration of eth1.1121machine # [ 17.814637] systemd[1]: Reached target Remote File Systems.1122machine # [ 17.819891] systemd[1]: Reached target Local File Systems.1123machine # [ 17.822596] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 17.824666] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1125machine # [ 17.829737] systemd[1]: Starting linger-users.service...1126machine # [ 17.832070] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1127machine # [ 17.837792] systemd[1]: Starting Create SUID/SGID Wrappers...1128machine # [ 17.838580] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1129machine # [ 17.839739] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671130machine # [ 17.841669] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1131machine # [ 17.843150] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1132machine # [ 17.844322] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1133machine # [ 17.876174] linger-users-start[1091]: id: ‘usertest’: no such user1134machine # [ 17.878594] linger-users-start[1087]: Removing linger for missing user usertest1135machine # [ 17.885498] systemd[1]: linger-users.service: Deactivated successfully.1136machine # [ 17.888260] systemd[1]: Finished linger-users.service.1137machine # [ 18.012279] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1138machine # [ 18.014184] systemd[1]: Finished Create SUID/SGID Wrappers.1139machine # [ 18.272533] nixos[988]: finished switching to system configuration /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test1140machine: (finished: must succeed: /nix/store/3vbcjh6yz1i6lp352svzp92f3jmjppg7-stderr-runner /nix/store/cssmzw3iavz1v1impl73lvs2bann39bm-nixos-system-other-test/bin/switch-to-configuration test, in 1.56 seconds)1141machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&11142machine # [ 18.487857] nixos[1155]: switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test1143machine # [ 18.490328] systemd[1]: Stopped target Remote File Systems.1144machine # [ 18.491877] systemd[1]: Stopped target Local File Systems.1145machine # [ 18.708545] systemd[1]: Reload requested from client PID 1155 ('.switch-to-conf') (unit backdoor.service)...1146machine # [ 18.710376] systemd[1]: Reloading...1147machine # [ 18.940967] systemd[1]: Reloading finished in 229 ms.1148machine # [ 19.006403] systemd[619]: Reexecution requested from client PID 1224 ('switch-to-confi')...1149machine # [ 19.008153] systemd[619]: Reexecuting.1150machine # [ 19.028186] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1151machine # [ 19.100764] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1152machine # [ 19.105956] systemd[619]: Reload requested from client PID 1224 ('switch-to-confi')...1153machine # [ 19.107813] systemd[619]: Reloading...1154machine # [ 19.180829] systemd[619]: Reloading finished in 72 ms.1155machine # [ 19.185866] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1156machine # [ 19.192089] systemd[619]: Stopped Run user-specific NixOS activation.1157machine # [ 19.194027] systemd[619]: Stopping Run user-specific NixOS activation...1158machine # [ 19.195686] systemd[619]: Starting Run user-specific NixOS activation...1159machine # [ 19.216698] systemd[619]: Finished Run user-specific NixOS activation.1160machine # [ 19.220118] systemd[1]: Stopped target Reactivate sysinit units.1161machine # [ 19.221604] systemd[1]: Stopping Reactivate sysinit units...1162machine # [ 19.222550] systemd[1]: Reached target Reactivate sysinit units.1163machine # [ 19.225228] dbus-broker-launch[499]: Caught SIGHUP, trigger reload.1164machine # [ 19.227334] systemd[1]: Reloading D-Bus System Message Bus...1165machine # [ 19.230851] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'...1166machine # [ 19.232648] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync'1167machine # [ 19.233676] dbus-broker-launch[499]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1168machine # [ 19.242628] systemd[1]: Reloaded D-Bus System Message Bus.1169machine # [ 19.246698] systemd[1]: Stopping Address configuration of eth1...1170machine # [ 19.267388] network-addresses-eth1-pre-stop[1236]: deleting address 192.168.1.2/24... done1171machine # [ 19.272447] network-addresses-eth1-pre-stop[1236]: deleting address 2001:db8:1::2/64... done1172machine # [ 19.277590] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1173machine # [ 19.279516] systemd[1]: Stopped Address configuration of eth1.1174machine # [ 19.282525] systemd[1]: Starting Address configuration of eth1...1175machine # [ 19.311197] network-addresses-eth1-start[1241]: adding address 192.168.1.1/24... done1176machine # [ 19.316809] network-addresses-eth1-start[1241]: adding address 2001:db8:1::1/64... done1177machine # [ 19.323924] systemd[1]: Finished Address configuration of eth1.1178machine # [ 19.326420] systemd[1]: Reached target Remote File Systems.1179machine # [ 19.330865] systemd[1]: Starting linger-users.service...1180machine # [ 19.337056] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 19.338266] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1182machine # [ 19.342341] systemd[1]: Reached target Local File Systems.1183machine # [ 19.343197] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1184machine # [ 19.347148] systemd[1]: Starting Create SUID/SGID Wrappers...1185machine # [ 19.347946] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1186machine # [ 19.350125] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671187machine # [ 19.353482] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1188machine # [ 19.356233] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1189machine # [ 19.358430] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1190machine # [ 19.387227] systemd[1]: linger-users.service: Deactivated successfully.1191machine # [ 19.389084] systemd[1]: Finished linger-users.service.1192machine # [ 19.507248] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1193machine # [ 19.509623] systemd[1]: Finished Create SUID/SGID Wrappers.1194machine # [ 19.767438] nixos[1155]: finished switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test1195machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.49 seconds)1196subtest: socket-activated services1197machine: must fail: [ -S /run/test.sock ]1198machine: (finished: must fail: [ -S /run/test.sock ], in 0.02 seconds)1199machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11200machine # [ 20.022085] nixos[1313]: switching to system configuration /nix/store/zvflxjzs1abk2fs9im1h6955i4ha42xi-nixos-system-machine-test1201machine # [ 20.024339] systemd[1]: Stopped target Local File Systems.1202machine # [ 20.025908] systemd[1]: Stopped target Remote File Systems.1203machine # [ 20.264790] systemd[1]: Reload requested from client PID 1313 ('.switch-to-conf') (unit backdoor.service)...1204machine # [ 20.266526] systemd[1]: Reloading...1205machine # [ 20.523321] systemd[1]: Reloading finished in 254 ms.1206machine # [ 20.586160] systemd[619]: Reexecution requested from client PID 1382 ('switch-to-confi')...1207machine # [ 20.588736] systemd[619]: Reexecuting.1208machine # [ 20.610281] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1209machine # [ 20.683477] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1210machine # [ 20.688221] systemd[619]: Reload requested from client PID 1382 ('switch-to-confi')...1211machine # [ 20.690058] systemd[619]: Reloading...1212machine # [ 20.761477] systemd[619]: Reloading finished in 70 ms.1213machine # [ 20.765871] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1214machine # [ 20.772061] systemd[619]: Stopped Run user-specific NixOS activation.1215machine # [ 20.773061] systemd[619]: Stopping Run user-specific NixOS activation...1216machine # [ 20.775820] systemd[619]: Starting Run user-specific NixOS activation...1217machine # [ 20.798506] systemd[619]: Finished Run user-specific NixOS activation.1218machine # [ 20.801827] systemd[1]: Stopped target Reactivate sysinit units.1219machine # [ 20.803174] systemd[1]: Stopping Reactivate sysinit units...1220machine # [ 20.803963] systemd[1]: Reached target Reactivate sysinit units.1221machine # [ 20.807041] systemd[1]: Listening on socket-activated.socket.1222machine # [ 20.808687] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1223machine # [ 20.810451] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1224machine # [ 20.816588] systemd[1]: Reached target Remote File Systems.1225machine # [ 20.819828] systemd[1]: Starting linger-users.service...1226machine # [ 20.822787] systemd[1]: Reached target Local File Systems.1227machine # [ 20.825428] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1228machine # [ 20.830111] systemd[1]: Starting Create SUID/SGID Wrappers...1229machine # [ 20.830931] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1230machine # [ 20.832128] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671231machine # [ 20.834222] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1232machine # [ 20.835710] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1233machine # [ 20.836891] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1234machine # [ 20.882630] systemd[1]: linger-users.service: Deactivated successfully.1235machine # [ 20.884539] systemd[1]: Finished linger-users.service.1236machine # [ 20.991297] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1237machine # [ 20.992986] systemd[1]: Finished Create SUID/SGID Wrappers.1238machine # [ 21.251982] nixos[1313]: finished switching to system configuration /nix/store/zvflxjzs1abk2fs9im1h6955i4ha42xi-nixos-system-machine-test1239machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.46 seconds)1240machine: must succeed: [ -S /run/test.sock ]1241machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1242machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11243machine # [ 21.521937] nixos[1456]: switching to system configuration /nix/store/2n33j8p9d05878ljgpz3z3pxzva68gs7-nixos-system-machine-test1244machine # [ 21.525610] systemd[1]: Stopped target Local File Systems.1245machine # [ 21.526629] systemd[1]: Stopped target Remote File Systems.1246machine # [ 21.761602] systemd[1]: Reload requested from client PID 1456 ('.switch-to-conf') (unit backdoor.service)...1247machine # [ 21.763347] systemd[1]: Reloading...1248machine # [ 22.042945] systemd[1]: Reloading finished in 277 ms.1249machine # [ 22.103396] systemd[619]: Reexecution requested from client PID 1526 ('switch-to-confi')...1250machine # [ 22.105901] systemd[619]: Reexecuting.1251machine # [ 22.127450] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1252machine # [ 22.200516] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1253machine # [ 22.205908] systemd[619]: Reload requested from client PID 1526 ('switch-to-confi')...1254machine # [ 22.207473] systemd[619]: Reloading...1255machine # [ 22.279389] systemd[619]: Reloading finished in 71 ms.1256machine # [ 22.284886] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1257machine # [ 22.290955] systemd[619]: Stopped Run user-specific NixOS activation.1258machine # [ 22.292978] systemd[619]: Stopping Run user-specific NixOS activation...1259machine # [ 22.294533] systemd[619]: Starting Run user-specific NixOS activation...1260machine # [ 22.316164] systemd[619]: Finished Run user-specific NixOS activation.1261machine # [ 22.319281] systemd[1]: Stopped target Reactivate sysinit units.1262machine # [ 22.320636] systemd[1]: Stopping Reactivate sysinit units...1263machine # [ 22.321600] systemd[1]: Reached target Reactivate sysinit units.1264machine # [ 22.328443] systemd[1]: Reached target Remote File Systems.1265machine # [ 22.330231] systemd[1]: Reached target Local File Systems.1266machine # [ 22.332054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1267machine # [ 22.333734] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1268machine # [ 22.336738] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1269machine # [ 22.341177] systemd[1]: Starting Create SUID/SGID Wrappers...1270machine # [ 22.343605] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1271machine # [ 22.344943] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671272machine # [ 22.347726] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1273machine # [ 22.349163] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1274machine # [ 22.350331] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1275machine # [ 22.475408] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1276machine # [ 22.478080] systemd[1]: Finished Create SUID/SGID Wrappers.1277machine # [ 22.481064] systemd[1]: Starting linger-users.service...1278machine # [ 22.512874] systemd[1]: linger-users.service: Deactivated successfully.1279machine # [ 22.514416] systemd[1]: Finished linger-users.service.1280machine # [ 22.772555] nixos[1456]: finished switching to system configuration /nix/store/2n33j8p9d05878ljgpz3z3pxzva68gs7-nixos-system-machine-test1281machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.50 seconds)1282machine: must succeed: [ -S /run/test.sock ]1283machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1284machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1285machine # [ 22.836886] systemd[1]: Started A socket-activated service.1286machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.63 seconds)1287machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11288machine # [ 23.634829] nixos[1604]: switching to system configuration /nix/store/zvflxjzs1abk2fs9im1h6955i4ha42xi-nixos-system-machine-test1289machine # [ 23.638881] systemd[1]: Stopped target Local File Systems.1290machine # [ 23.639877] systemd[1]: Stopped target Remote File Systems.1291machine # [ 23.868874] systemd[1]: Reload requested from client PID 1604 ('.switch-to-conf') (unit backdoor.service)...1292machine # [ 23.870542] systemd[1]: Reloading...1293machine # [ 24.118907] systemd[1]: Reloading finished in 246 ms.1294machine # [ 24.183437] systemd[619]: Reexecution requested from client PID 1674 ('switch-to-confi')...1295machine # [ 24.184723] systemd[619]: Reexecuting.1296machine # [ 24.204222] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1297machine # [ 24.281204] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1298machine # [ 24.286863] systemd[619]: Reload requested from client PID 1674 ('switch-to-confi')...1299machine # [ 24.288358] systemd[619]: Reloading...1300machine # [ 24.360835] systemd[619]: Reloading finished in 71 ms.1301machine # [ 24.365867] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1302machine # [ 24.371917] systemd[619]: Stopped Run user-specific NixOS activation.1303machine # [ 24.373952] systemd[619]: Stopping Run user-specific NixOS activation...1304machine # [ 24.375643] systemd[619]: Starting Run user-specific NixOS activation...1305machine # [ 24.397687] systemd[619]: Finished Run user-specific NixOS activation.1306machine # [ 24.400984] systemd[1]: Stopped target Reactivate sysinit units.1307machine # [ 24.402495] systemd[1]: Stopping Reactivate sysinit units...1308machine # [ 24.403477] systemd[1]: Reached target Reactivate sysinit units.1309machine # [ 24.407265] systemd[1]: Stopping A socket-activated service...1310machine # [ 24.408935] systemd[1]: socket-activated.service: Deactivated successfully.1311machine # [ 24.410738] systemd[1]: Stopped A socket-activated service.1312machine # [ 24.413733] systemd[1]: Started A socket-activated service.1313machine # [ 24.423558] systemd[1]: Reached target Local File Systems.1314machine # [ 24.426768] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1315machine # [ 24.428781] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1316machine # [ 24.434111] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1317machine # [ 24.438645] systemd[1]: Starting Create SUID/SGID Wrappers...1318machine # [ 24.439524] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1319machine # [ 24.440702] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671320machine # [ 24.442712] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1321machine # [ 24.444215] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1322machine # [ 24.445478] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1323machine # [ 24.450989] systemd[1]: Reached target Remote File Systems.1324machine # [ 24.631891] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1325machine # [ 24.635182] systemd[1]: Finished Create SUID/SGID Wrappers.1326machine # [ 24.637626] systemd[1]: Starting linger-users.service...1327machine # [ 24.667383] systemd[1]: linger-users.service: Deactivated successfully.1328machine # [ 24.668934] systemd[1]: Finished linger-users.service.1329machine # [ 24.923671] nixos[1604]: finished switching to system configuration /nix/store/zvflxjzs1abk2fs9im1h6955i4ha42xi-nixos-system-machine-test1330machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.50 seconds)1331machine: must succeed: [ -S /run/test.sock ]1332machine: (finished: must succeed: [ -S /run/test.sock ], in 0.01 seconds)1333machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1334machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.52 seconds)1335machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11336machine # [ 25.689694] nixos[1754]: switching to system configuration /nix/store/kbm7r1src48s0k8846lp20h6f9syjal4-nixos-system-machine-test1337machine # [ 25.694363] systemd[1]: Stopping A socket-activated service...1338machine # [ 25.695891] systemd[1]: Stopped target Local File Systems.1339machine # [ 25.697812] systemd[1]: Stopped target Remote File Systems.1340machine # [ 25.699552] systemd[1]: socket-activated.service: Deactivated successfully.1341machine # [ 25.701090] systemd[1]: Stopped A socket-activated service.1342machine # [ 25.702955] systemd[1]: socket-activated.socket: Deactivated successfully.1343machine # [ 25.704322] systemd[1]: Closed socket-activated.socket.1344machine # [ 25.951222] systemd[1]: Reload requested from client PID 1754 ('.switch-to-conf') (unit backdoor.service)...1345machine # [ 25.952926] systemd[1]: Reloading...1346machine # [ 26.212820] systemd[1]: Reloading finished in 258 ms.1347machine # [ 26.281563] systemd[619]: Reexecution requested from client PID 1824 ('switch-to-confi')...1348machine # [ 26.282891] systemd[619]: Reexecuting.1349machine # [ 26.304428] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1350machine # [ 26.378740] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1351machine # [ 26.383912] systemd[619]: Reload requested from client PID 1824 ('switch-to-confi')...1352machine # [ 26.385176] systemd[619]: Reloading...1353machine # [ 26.458607] systemd[619]: Reloading finished in 71 ms.1354machine # [ 26.463921] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1355machine # [ 26.470066] systemd[619]: Stopped Run user-specific NixOS activation.1356machine # [ 26.472096] systemd[619]: Stopping Run user-specific NixOS activation...1357machine # [ 26.473731] systemd[619]: Starting Run user-specific NixOS activation...1358machine # [ 26.495701] systemd[619]: Finished Run user-specific NixOS activation.1359machine # [ 26.498883] systemd[1]: Stopped target Reactivate sysinit units.1360machine # [ 26.500256] systemd[1]: Stopping Reactivate sysinit units...1361machine # [ 26.501404] systemd[1]: Reached target Reactivate sysinit units.1362machine # [ 26.504675] systemd[1]: Reached target Remote File Systems.1363machine # [ 26.507855] systemd[1]: Starting linger-users.service...1364machine # [ 26.513458] systemd[1]: Reached target Local File Systems.1365machine # [ 26.516819] systemd[1]: Listening on socket-activated.socket.1366machine # [ 26.520894] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1367machine # [ 26.522957] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1368machine # [ 26.530077] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1369machine # [ 26.533841] systemd[1]: Starting Create SUID/SGID Wrappers...1370machine # [ 26.534973] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1371machine # [ 26.536170] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671372machine # [ 26.538275] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1373machine # [ 26.539745] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1374machine # [ 26.543201] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1375machine # [ 26.576384] systemd[1]: linger-users.service: Deactivated successfully.1376machine # [ 26.579799] systemd[1]: Finished linger-users.service.1377machine # [ 26.695430] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1378machine # [ 26.697393] systemd[1]: Finished Create SUID/SGID Wrappers.1379machine # [ 26.955446] nixos[1754]: finished switching to system configuration /nix/store/kbm7r1src48s0k8846lp20h6f9syjal4-nixos-system-machine-test1380machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.50 seconds)1381machine: must succeed: [ -S /run/test.sock ]1382machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1383machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1384machine # [ 27.014830] systemd[1]: Started A socket-activated service.1385machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.59 seconds)1386machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11387machine # [ 27.789256] nixos[1902]: switching to system configuration /nix/store/lj12kw29clbns4czp4f7l29fm9lcmbap-nixos-system-machine-test1388machine # [ 27.792789] systemd[1]: Stopped target Local File Systems.1389machine # [ 27.793780] systemd[1]: Stopped target Remote File Systems.1390machine # [ 28.023868] systemd[1]: Reload requested from client PID 1902 ('.switch-to-conf') (unit backdoor.service)...1391machine # [ 28.025598] systemd[1]: Reloading...1392machine # [ 28.281190] systemd[1]: Reloading finished in 253 ms.1393machine # [ 28.344933] systemd[619]: Reexecution requested from client PID 1973 ('switch-to-confi')...1394machine # [ 28.347484] systemd[619]: Reexecuting.1395machine # [ 28.369267] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1396machine # [ 28.445788] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1397machine # [ 28.450942] systemd[619]: Reload requested from client PID 1973 ('switch-to-confi')...1398machine # [ 28.452197] systemd[619]: Reloading...1399machine # [ 28.525593] systemd[619]: Reloading finished in 71 ms.1400machine # [ 28.530874] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1401machine # [ 28.537496] systemd[619]: Stopped Run user-specific NixOS activation.1402machine # [ 28.538621] systemd[619]: Stopping Run user-specific NixOS activation...1403machine # [ 28.541185] systemd[619]: Starting Run user-specific NixOS activation...1404machine # [ 28.562342] systemd[619]: Finished Run user-specific NixOS activation.1405machine # [ 28.565603] systemd[1]: Stopped target Reactivate sysinit units.1406machine # [ 28.566888] systemd[1]: Stopping Reactivate sysinit units...1407machine # [ 28.567930] systemd[1]: Reached target Reactivate sysinit units.1408machine # [ 28.570506] systemd[1]: Reloading A socket-activated service...1409machine # [ 28.584515] systemd[1]: Reloaded A socket-activated service.1410machine # [ 28.589404] systemd[1]: Reached target Remote File Systems.1411machine # [ 28.592829] systemd[1]: Starting linger-users.service...1412machine # [ 28.594652] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1413machine # [ 28.596338] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1414machine # [ 28.606475] systemd[1]: Reached target Local File Systems.1415machine # [ 28.607428] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1416machine # [ 28.610968] systemd[1]: Starting Create SUID/SGID Wrappers...1417machine # [ 28.612836] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1418machine # [ 28.614519] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671419machine # [ 28.617162] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1420machine # [ 28.619613] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1421machine # [ 28.622114] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1422machine # [ 28.656907] systemd[1]: linger-users.service: Deactivated successfully.1423machine # [ 28.658995] systemd[1]: Finished linger-users.service.1424machine # [ 28.772947] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1425machine # [ 28.775070] systemd[1]: Finished Create SUID/SGID Wrappers.1426machine # [ 29.032789] nixos[1902]: finished switching to system configuration /nix/store/lj12kw29clbns4czp4f7l29fm9lcmbap-nixos-system-machine-test1427machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 1.47 seconds)1428machine: must succeed: [ -S /run/test.sock ]1429machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1430machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1431machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1432machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11433machine # [ 29.795431] nixos[2052]: switching to system configuration /nix/store/bmpkl8a3praxngc205iiwpabv375zwrs-nixos-system-machine-test1434machine # [ 29.797955] systemd[1]: Stopped target Local File Systems.1435machine # [ 29.799691] systemd[1]: Stopped target Remote File Systems.1436machine # [ 29.801589] systemd[1]: socket-activated.socket: Deactivated successfully.1437machine # [ 29.802825] systemd[1]: Closed socket-activated.socket.1438machine # [ 30.039399] systemd[1]: Reload requested from client PID 2052 ('.switch-to-conf') (unit backdoor.service)...1439machine # [ 30.041155] systemd[1]: Reloading...1440machine # [ 30.301835] systemd[1]: Reloading finished in 259 ms.1441machine # [ 30.365583] systemd[619]: Reexecution requested from client PID 2122 ('switch-to-confi')...1442machine # [ 30.367612] systemd[619]: Reexecuting.1443machine # [ 30.389214] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1444machine # [ 30.462282] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1445machine # [ 30.467355] systemd[619]: Reload requested from client PID 2122 ('switch-to-confi')...1446machine # [ 30.469195] systemd[619]: Reloading...1447machine # [ 30.541573] systemd[619]: Reloading finished in 71 ms.1448machine # [ 30.546856] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1449machine # [ 30.553112] systemd[619]: Stopped Run user-specific NixOS activation.1450machine # [ 30.555124] systemd[619]: Stopping Run user-specific NixOS activation...1451machine # [ 30.556730] systemd[619]: Starting Run user-specific NixOS activation...1452machine # [ 30.579175] systemd[619]: Finished Run user-specific NixOS activation.1453machine # [ 30.582399] systemd[1]: Stopped target Reactivate sysinit units.1454machine # [ 30.583772] systemd[1]: Stopping Reactivate sysinit units...1455machine # [ 30.585155] systemd[1]: Reached target Reactivate sysinit units.1456machine # [ 30.588567] systemd[1]: Stopping A socket-activated service...1457machine # [ 30.590251] systemd[1]: socket-activated.service: Deactivated successfully.1458machine # [ 30.591693] systemd[1]: Stopped A socket-activated service.1459machine # [ 30.595071] systemd[1]: Started A socket-activated service.1460machine # [ 30.601415] systemd[1]: Reached target Local File Systems.1461machine # [ 30.602278] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1462machine # [ 30.605147] systemd[1]: Starting Create SUID/SGID Wrappers...1463machine # [ 30.605953] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1464machine # [ 30.609146] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671465machine # [ 30.612196] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1466machine # [ 30.614364] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1467machine # [ 30.617153] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1468machine # [ 30.618266] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1469machine # [ 30.619471] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1470machine # [ 30.625451] systemd[1]: Reached target Remote File Systems.1471machine # [ 30.784475] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1472machine # [ 30.786232] systemd[1]: Finished Create SUID/SGID Wrappers.1473machine # [ 30.790565] systemd[1]: Starting linger-users.service...1474machine # [ 30.819946] systemd[1]: linger-users.service: Deactivated successfully.1475machine # [ 30.822341] systemd[1]: Finished linger-users.service.1476machine # [ 31.076258] nixos[2052]: finished switching to system configuration /nix/store/bmpkl8a3praxngc205iiwpabv375zwrs-nixos-system-machine-test1477machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 1.49 seconds)1478machine: must succeed: systemctl is-active socket-activated.service1479machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.01 seconds)1480machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11481machine # [ 31.287444] nixos[2199]: switching to system configuration /nix/store/kbm7r1src48s0k8846lp20h6f9syjal4-nixos-system-machine-test1482machine # [ 31.289844] systemd[1]: Stopped target Local File Systems.1483machine # [ 31.294044] systemd[1]: Stopping A socket-activated service...1484machine # [ 31.294911] systemd[1]: Stopped target Remote File Systems.1485machine # [ 31.298070] systemd[1]: socket-activated.service: Deactivated successfully.1486machine # [ 31.299546] systemd[1]: Stopped A socket-activated service.1487machine # [ 31.518110] systemd[1]: Reload requested from client PID 2199 ('.switch-to-conf') (unit backdoor.service)...1488machine # [ 31.519890] systemd[1]: Reloading...1489machine # [ 31.769771] systemd[1]: Reloading finished in 247 ms.1490machine # [ 31.836125] systemd[619]: Reexecution requested from client PID 2269 ('switch-to-confi')...1491machine # [ 31.838105] systemd[619]: Reexecuting.1492machine # [ 31.858324] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1493machine # [ 31.936933] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1494machine # [ 31.942969] systemd[619]: Reload requested from client PID 2269 ('switch-to-confi')...1495machine # [ 31.944760] systemd[619]: Reloading...1496machine # [ 32.016775] systemd[619]: Reloading finished in 71 ms.1497machine # [ 32.021912] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1498machine # [ 32.028887] systemd[619]: Stopped Run user-specific NixOS activation.1499machine # [ 32.029751] systemd[619]: Stopping Run user-specific NixOS activation...1500machine # [ 32.031294] systemd[619]: Starting Run user-specific NixOS activation...1501machine # [ 32.052422] systemd[619]: Finished Run user-specific NixOS activation.1502machine # [ 32.055871] systemd[1]: Stopped target Reactivate sysinit units.1503machine # [ 32.057487] systemd[1]: Stopping Reactivate sysinit units...1504machine # [ 32.058575] systemd[1]: Reached target Reactivate sysinit units.1505machine # [ 32.060072] systemd[1]: Reached target Remote File Systems.1506machine # [ 32.064684] systemd[1]: Reached target Local File Systems.1507machine # [ 32.066152] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1508machine # [ 32.070201] systemd[1]: Starting Create SUID/SGID Wrappers...1509machine # [ 32.070995] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1510machine # [ 32.073069] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671511machine # [ 32.075189] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1512machine # [ 32.076685] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1513machine # [ 32.077866] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1514machine # [ 32.086028] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1515machine # [ 32.089269] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1516machine # [ 32.219124] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1517machine # [ 32.222096] systemd[1]: Finished Create SUID/SGID Wrappers.1518machine # [ 32.223577] systemd[1]: Listening on socket-activated.socket.1519machine # [ 32.226480] systemd[1]: Starting linger-users.service...1520machine # [ 32.257297] systemd[1]: linger-users.service: Deactivated successfully.1521machine # [ 32.258860] systemd[1]: Finished linger-users.service.1522machine # [ 32.516977] nixos[2199]: finished switching to system configuration /nix/store/kbm7r1src48s0k8846lp20h6f9syjal4-nixos-system-machine-test1523machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1524machine: must succeed: [ -S /run/test.sock ]1525machine: (finished: must succeed: [ -S /run/test.sock ], in 0.01 seconds)1526machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1527machine # [ 32.549727] systemd[1]: Started A socket-activated service.1528machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.57 seconds)1529(finished: subtest: socket-activated services, in 13.33 seconds)1530subtest: socket-activated services with Accept=yes1531machine: must fail: [ -S /run/accept-test.sock ]1532machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.02 seconds)1533machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11534machine # [ 33.352785] nixos[2350]: switching to system configuration /nix/store/kl8mrian8yj3za3vh2qdxcpp5a230kiw-nixos-system-machine-test1535machine # [ 33.355316] systemd[1]: socket-activated.socket: Deactivated successfully.1536machine # [ 33.357758] systemd[1]: Closed socket-activated.socket.1537machine # [ 33.360058] systemd[1]: Stopping A socket-activated service...1538machine # [ 33.362758] systemd[1]: socket-activated.service: Deactivated successfully.1539machine # [ 33.364567] systemd[1]: Stopped A socket-activated service.1540machine # [ 33.367832] systemd[1]: Stopped target Local File Systems.1541machine # [ 33.368762] systemd[1]: Stopped target Remote File Systems.1542machine # [ 33.603326] systemd[1]: Reload requested from client PID 2350 ('.switch-to-conf') (unit backdoor.service)...1543machine # [ 33.605121] systemd[1]: Reloading...1544machine # [ 33.865748] systemd[1]: Reloading finished in 258 ms.1545machine # [ 33.926551] systemd[619]: Reexecution requested from client PID 2420 ('switch-to-confi')...1546machine # [ 33.928275] systemd[619]: Reexecuting.1547machine # [ 33.949269] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1548machine # [ 34.021341] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1549machine # [ 34.026494] systemd[619]: Reload requested from client PID 2420 ('switch-to-confi')...1550machine # [ 34.028415] systemd[619]: Reloading...1551machine # [ 34.100379] systemd[619]: Reloading finished in 71 ms.1552machine # [ 34.105865] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1553machine # [ 34.112923] systemd[619]: Stopped Run user-specific NixOS activation.1554machine # [ 34.113782] systemd[619]: Stopping Run user-specific NixOS activation...1555machine # [ 34.115317] systemd[619]: Starting Run user-specific NixOS activation...1556machine # [ 34.140566] systemd[619]: Finished Run user-specific NixOS activation.1557machine # [ 34.144916] systemd[1]: Stopped target Reactivate sysinit units.1558machine # [ 34.146439] systemd[1]: Stopping Reactivate sysinit units...1559machine # [ 34.148145] systemd[1]: Reached target Reactivate sysinit units.1560machine # [ 34.152418] systemd[1]: Reached target Local File Systems.1561machine # [ 34.154441] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1562machine # [ 34.157057] systemd[1]: Starting Create SUID/SGID Wrappers...1563machine # [ 34.159059] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1564machine # [ 34.160622] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671565machine # [ 34.162548] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1566machine # [ 34.164033] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1567machine # [ 34.165166] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1568machine # [ 34.174198] systemd[1]: Reached target Remote File Systems.1569machine # [ 34.179492] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1570machine # [ 34.181291] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1571machine # [ 34.334889] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1572machine # [ 34.337174] systemd[1]: Finished Create SUID/SGID Wrappers.1573machine # [ 34.341274] systemd[1]: Listening on accept-socket.socket.1574machine # [ 34.343330] systemd[1]: Starting linger-users.service...1575machine # [ 34.371699] systemd[1]: linger-users.service: Deactivated successfully.1576machine # [ 34.373651] systemd[1]: Finished linger-users.service.1577machine # [ 34.630784] nixos[2350]: finished switching to system configuration /nix/store/kl8mrian8yj3za3vh2qdxcpp5a230kiw-nixos-system-machine-test1578machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 1.52 seconds)1579machine: must succeed: [ -S /run/accept-test.sock ]1580machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.02 seconds)1581machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1582machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.02 seconds)1583machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1584machine # [ 34.699763] systemd[1]: Created slice Slice /system/accept-socket.1585machine # [ 34.703675] systemd[1]: Started A per-connection socket-activated service (PID 2495/UID 0).1586machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.05 seconds)1587machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11588machine # [ 34.941060] nixos[2507]: switching to system configuration /nix/store/gp466fnw5hp44516q8axy6pqxym2m2a9-nixos-system-machine-test1589machine # [ 34.943343] systemd[1]: accept-socket.socket: Deactivated successfully.1590machine # [ 34.944906] systemd[1]: Closed accept-socket.socket.1591machine # [ 34.947235] systemd[1]: Stopping A per-connection socket-activated service (PID 2495/UID 0)...1592machine # [ 34.950104] systemd[1]: accept-socket@0-1-2495_2496-0.service: Deactivated successfully.1593machine # [ 34.951718] systemd[1]: Stopped A per-connection socket-activated service (PID 2495/UID 0).1594machine # [ 34.956484] systemd[1]: Stopped target Local File Systems.1595machine # [ 34.957788] systemd[1]: Stopped target Remote File Systems.1596machine # [ 35.190585] systemd[1]: Reload requested from client PID 2507 ('.switch-to-conf') (unit backdoor.service)...1597machine # [ 35.192427] systemd[1]: Reloading...1598machine # [ 35.441264] systemd[1]: Reloading finished in 246 ms.1599machine # [ 35.506896] systemd[619]: Reexecution requested from client PID 2578 ('switch-to-confi')...1600machine # [ 35.509144] systemd[619]: Reexecuting.1601machine # [ 35.531233] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1602machine # [ 35.604509] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1603machine # [ 35.609256] systemd[619]: Reload requested from client PID 2578 ('switch-to-confi')...1604machine # [ 35.610994] systemd[619]: Reloading...1605machine # [ 35.682728] systemd[619]: Reloading finished in 70 ms.1606machine # [ 35.687864] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1607machine # [ 35.694245] systemd[619]: Stopped Run user-specific NixOS activation.1608machine # [ 35.696060] systemd[619]: Stopping Run user-specific NixOS activation...1609machine # [ 35.697861] systemd[619]: Starting Run user-specific NixOS activation...1610machine # [ 35.718586] systemd[619]: Finished Run user-specific NixOS activation.1611machine # [ 35.721853] systemd[1]: Stopped target Reactivate sysinit units.1612machine # [ 35.723160] systemd[1]: Stopping Reactivate sysinit units...1613machine # [ 35.723950] systemd[1]: Reached target Reactivate sysinit units.1614machine # [ 35.727260] systemd[1]: Listening on accept-socket.socket.1615machine # [ 35.729421] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1616machine # [ 35.731172] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1617machine # [ 35.733193] systemd[1]: Reached target Local File Systems.1618machine # [ 35.737964] systemd[1]: Reached target Remote File Systems.1619machine # [ 35.741129] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1620machine # [ 35.745062] systemd[1]: Starting Create SUID/SGID Wrappers...1621machine # [ 35.745835] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1622machine # [ 35.747542] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671623machine # [ 35.749538] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1624machine # [ 35.751075] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1625machine # [ 35.752266] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1626machine # [ 35.885806] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1627machine # [ 35.889149] systemd[1]: Finished Create SUID/SGID Wrappers.1628machine # [ 35.891608] systemd[1]: Starting linger-users.service...1629machine # [ 35.920842] systemd[1]: linger-users.service: Deactivated successfully.1630machine # [ 35.923123] systemd[1]: Finished linger-users.service.1631machine # [ 36.180652] nixos[2507]: finished switching to system configuration /nix/store/gp466fnw5hp44516q8axy6pqxym2m2a9-nixos-system-machine-test1632machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.46 seconds)1633machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1634machine # [ 36.223842] systemd[1]: Started A per-connection socket-activated service (PID 2649/UID 0).1635machine # [ 36.253071] systemd[1]: accept-socket@1-2-2649_2650-0.service: Deactivated successfully.1636machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.07 seconds)1637(finished: subtest: socket-activated services with Accept=yes, in 3.16 seconds)1638subtest: mounts1639machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11640machine # [ 36.455899] nixos[2656]: switching to system configuration /nix/store/2wr9jxcfisgrs0y1w1yi6qm3hknhgwxl-nixos-system-machine-test1641machine # [ 36.458446] systemd[1]: Stopped target Local File Systems.1642machine # [ 36.460622] systemd[1]: accept-socket.socket: Deactivated successfully.1643machine # [ 36.461750] systemd[1]: Closed accept-socket.socket.1644machine # [ 36.462767] systemd[1]: Stopped target Remote File Systems.1645machine # [ 36.691348] systemd[1]: Reload requested from client PID 2656 ('.switch-to-conf') (unit backdoor.service)...1646machine # [ 36.693127] systemd[1]: Reloading...1647machine # [ 36.967196] systemd[1]: Reloading finished in 272 ms.1648machine # [ 37.031311] systemd[619]: Reexecution requested from client PID 2725 ('switch-to-confi')...1649machine # [ 37.033442] systemd[619]: Reexecuting.1650machine # [ 37.055258] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1651machine # [ 37.131282] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1652machine # [ 37.136605] systemd[619]: Reload requested from client PID 2725 ('switch-to-confi')...1653machine # [ 37.138901] systemd[619]: Reloading...1654machine # [ 37.208568] systemd[619]: Reloading finished in 70 ms.1655machine # [ 37.214872] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1656machine # [ 37.222036] systemd[619]: Stopped Run user-specific NixOS activation.1657machine # [ 37.222879] systemd[619]: Stopping Run user-specific NixOS activation...1658machine # [ 37.226286] systemd[619]: Starting Run user-specific NixOS activation...1659machine # [ 37.247073] systemd[619]: Finished Run user-specific NixOS activation.1660machine # [ 37.250524] systemd[1]: Stopped target Reactivate sysinit units.1661machine # [ 37.251585] systemd[1]: Stopping Reactivate sysinit units...1662machine # [ 37.252550] systemd[1]: Reached target Reactivate sysinit units.1663machine # [ 37.256529] systemd[1]: Reached target Remote File Systems.1664machine # [ 37.259357] systemd[1]: Starting linger-users.service...1665machine # [ 37.268651] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1666machine # [ 37.273845] systemd[1]: Starting Create SUID/SGID Wrappers...1667machine # [ 37.275356] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671668machine # [ 37.277960] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1669machine # [ 37.280564] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1670machine # [ 37.282777] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1671machine # [ 37.285750] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1672machine # [ 37.287030] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1673machine # [ 37.320269] systemd[1]: linger-users.service: Deactivated successfully.1674machine # [ 37.321738] systemd[1]: Finished linger-users.service.1675machine # [ 37.466547] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1676machine # [ 37.469418] systemd[1]: Finished Create SUID/SGID Wrappers.1677machine # [ 37.562219] systemd[1]: Mounting Testmount...1678machine # [ 37.579399] systemd[1]: Mounted Testmount.1679machine # [ 37.582065] systemd[1]: Reached target Local File Systems.1680machine # [ 37.582880] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1681machine # [ 37.841591] nixos[2656]: finished switching to system configuration /nix/store/2wr9jxcfisgrs0y1w1yi6qm3hknhgwxl-nixos-system-machine-test1682machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.59 seconds)1683machine: must succeed: mount | grep 'on /testmount'1684machine: (finished: must succeed: mount | grep 'on /testmount', in 0.04 seconds)1685machine: must succeed: systemctl stop testmount.mount1686machine # [ 37.919554] systemd[1]: Unmounting Testmount...1687machine # [ 37.940141] systemd[1]: testmount.mount: Deactivated successfully.1688machine # [ 37.941699] systemd[1]: Unmounted Testmount.1689machine: (finished: must succeed: systemctl stop testmount.mount, in 0.06 seconds)1690machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11691machine # [ 38.111629] nixos[2810]: switching to system configuration /nix/store/2wr9jxcfisgrs0y1w1yi6qm3hknhgwxl-nixos-system-machine-test1692machine # [ 38.113966] systemd[1]: Stopped target Local File Systems.1693machine # [ 38.115639] systemd[1]: Stopped target Remote File Systems.1694machine # [ 38.316102] systemd[1]: Reload requested from client PID 2810 ('.switch-to-conf') (unit backdoor.service)...1695machine # [ 38.317836] systemd[1]: Reloading...1696machine # [ 38.559035] systemd[1]: Reloading finished in 238 ms.1697machine # [ 38.619529] systemd[619]: Reexecution requested from client PID 2879 ('switch-to-confi')...1698machine # [ 38.621416] systemd[619]: Reexecuting.1699machine # [ 38.642146] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1700machine # [ 38.717332] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1701machine # [ 38.722738] systemd[619]: Reload requested from client PID 2879 ('switch-to-confi')...1702machine # [ 38.724056] systemd[619]: Reloading...1703machine # [ 38.799174] systemd[619]: Reloading finished in 72 ms.1704machine # [ 38.802868] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1705machine # [ 38.809706] systemd[619]: Stopped Run user-specific NixOS activation.1706machine # [ 38.811198] systemd[619]: Stopping Run user-specific NixOS activation...1707machine # [ 38.813848] systemd[619]: Starting Run user-specific NixOS activation...1708machine # [ 38.833718] systemd[619]: Finished Run user-specific NixOS activation.1709machine # [ 38.836992] systemd[1]: Stopped target Reactivate sysinit units.1710machine # [ 38.838488] systemd[1]: Stopping Reactivate sysinit units...1711machine # [ 38.839467] systemd[1]: Reached target Reactivate sysinit units.1712machine # [ 38.842749] systemd[1]: Reached target Remote File Systems.1713machine # [ 38.845835] systemd[1]: Starting linger-users.service...1714machine # [ 38.852647] systemd[1]: Mounting Testmount...1715machine # [ 38.853372] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1716machine # [ 38.857058] systemd[1]: Starting Create SUID/SGID Wrappers...1717machine # [ 38.857864] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671718machine # [ 38.859806] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1719machine # [ 38.863139] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1720machine # [ 38.865282] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1721machine # [ 38.871564] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1722machine # [ 38.872871] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1723machine # [ 38.907479] systemd[1]: Mounted Testmount.1724machine # [ 38.908547] systemd[1]: Reached target Local File Systems.1725machine # [ 38.909375] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1726machine # [ 38.931580] systemd[1]: linger-users.service: Deactivated successfully.1727machine # [ 38.934121] systemd[1]: Finished linger-users.service.1728machine # [ 39.041282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1729machine # [ 39.042960] systemd[1]: Finished Create SUID/SGID Wrappers.1730machine # [ 39.302197] nixos[2810]: finished switching to system configuration /nix/store/2wr9jxcfisgrs0y1w1yi6qm3hknhgwxl-nixos-system-machine-test1731machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.36 seconds)1732machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11733machine # [ 39.540296] nixos[2952]: switching to system configuration /nix/store/9rsb0v0n4ln3z15ravgdjhkrgyarzj2y-nixos-system-machine-test1734machine # [ 39.542356] systemd[1]: Stopped target Remote File Systems.1735machine # [ 39.544053] systemd[1]: Stopped target Local File Systems.1736machine # [ 39.771257] systemd[1]: Reload requested from client PID 2952 ('.switch-to-conf') (unit backdoor.service)...1737machine # [ 39.773095] systemd[1]: Reloading...1738machine # [ 40.027339] systemd[1]: Reloading finished in 253 ms.1739machine # [ 40.091461] systemd[619]: Reexecution requested from client PID 3021 ('switch-to-confi')...1740machine # [ 40.092799] systemd[619]: Reexecuting.1741machine # [ 40.113215] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1742machine # [ 40.187339] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1743machine # [ 40.192934] systemd[619]: Reload requested from client PID 3021 ('switch-to-confi')...1744machine # [ 40.194908] systemd[619]: Reloading...1745machine # [ 40.271722] systemd[619]: Reloading finished in 76 ms.1746machine # [ 40.276889] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1747machine # [ 40.283667] systemd[619]: Stopped Run user-specific NixOS activation.1748machine # [ 40.285189] systemd[619]: Stopping Run user-specific NixOS activation...1749machine # [ 40.287242] systemd[619]: Starting Run user-specific NixOS activation...1750machine # [ 40.308821] systemd[619]: Finished Run user-specific NixOS activation.1751machine # [ 40.311979] systemd[1]: Stopped target Reactivate sysinit units.1752machine # [ 40.313373] systemd[1]: Stopping Reactivate sysinit units...1753machine # [ 40.314911] systemd[1]: Reached target Reactivate sysinit units.1754machine # [ 40.317229] systemd[1]: Reloading Testmount...1755machine # [ 40.333261] systemd[1]: Reloaded Testmount.1756machine # [ 40.336982] systemd[1]: Reached target Remote File Systems.1757machine # [ 40.340325] systemd[1]: Reached target Local File Systems.1758machine # [ 40.344940] systemd[1]: Starting linger-users.service...1759machine # [ 40.351820] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1760machine # [ 40.354321] systemd[1]: Starting Create SUID/SGID Wrappers...1761machine # [ 40.355980] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1762machine # [ 40.357204] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671763machine # [ 40.360196] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1764machine # [ 40.362699] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1765machine # [ 40.365628] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1766machine # [ 40.367116] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1767machine # [ 40.368407] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1768machine # [ 40.405358] systemd[1]: linger-users.service: Deactivated successfully.1769machine # [ 40.406994] systemd[1]: Finished linger-users.service.1770machine # [ 40.519731] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1771machine # [ 40.521582] systemd[1]: Finished Create SUID/SGID Wrappers.1772machine # [ 40.778797] nixos[2952]: finished switching to system configuration /nix/store/9rsb0v0n4ln3z15ravgdjhkrgyarzj2y-nixos-system-machine-test1773machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 1.48 seconds)1774machine: must succeed: mount | grep 'on /testmount'1775machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1776machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11777machine # [ 41.031185] nixos[3100]: switching to system configuration /nix/store/2hswzbl0gimbwvfbif7b5w96h6m9dnfx-nixos-system-machine-test1778machine # [ 41.033635] systemd[1]: Stopped target Remote File Systems.1779machine # [ 41.035276] systemd[1]: Stopped target Local File Systems.1780machine # [ 41.275655] systemd[1]: Reload requested from client PID 3100 ('.switch-to-conf') (unit backdoor.service)...1781machine # [ 41.277491] systemd[1]: Reloading...1782machine # [ 41.531848] systemd[1]: Reloading finished in 252 ms.1783machine # [ 41.594864] systemd[619]: Reexecution requested from client PID 3169 ('switch-to-confi')...1784machine # [ 41.596739] systemd[619]: Reexecuting.1785machine # [ 41.617186] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1786machine # [ 41.689475] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1787machine # [ 41.694840] systemd[619]: Reload requested from client PID 3169 ('switch-to-confi')...1788machine # [ 41.697060] systemd[619]: Reloading...1789machine # [ 41.768070] systemd[619]: Reloading finished in 71 ms.1790machine # [ 41.773877] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1791machine # [ 41.780913] systemd[619]: Stopped Run user-specific NixOS activation.1792machine # [ 41.781787] systemd[619]: Stopping Run user-specific NixOS activation...1793machine # [ 41.783329] systemd[619]: Starting Run user-specific NixOS activation...1794machine # [ 41.805692] systemd[619]: Finished Run user-specific NixOS activation.1795machine # [ 41.808849] systemd[1]: Stopped target Reactivate sysinit units.1796machine # [ 41.810226] systemd[1]: Stopping Reactivate sysinit units...1797machine # [ 41.811240] systemd[1]: Reached target Reactivate sysinit units.1798machine # [ 41.813716] systemd[1]: Unmounting Testmount...1799machine # [ 41.830872] systemd[1]: testmount.mount: Deactivated successfully.1800machine # [ 41.832282] systemd[1]: Unmounted Testmount.1801machine # [ 41.835626] systemd[1]: Mounting Testmount...1802machine # [ 41.853253] systemd[1]: Mounted Testmount.1803machine # [ 41.859282] systemd[1]: Reached target Remote File Systems.1804machine # [ 41.860928] systemd[1]: Reached target Local File Systems.1805machine # [ 41.865993] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1806machine # [ 41.869767] systemd[1]: Starting Create SUID/SGID Wrappers...1807machine # [ 41.870722] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1808machine # [ 41.871937] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671809machine # [ 41.873931] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1810machine # [ 41.875406] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1811machine # [ 41.876609] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1812machine # [ 41.882054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1813machine # [ 41.883309] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1814machine # [ 42.010848] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1815machine # [ 42.013723] systemd[1]: Finished Create SUID/SGID Wrappers.1816machine # [ 42.016721] systemd[1]: Starting linger-users.service...1817machine # [ 42.045958] systemd[1]: linger-users.service: Deactivated successfully.1818machine # [ 42.047523] systemd[1]: Finished linger-users.service.1819machine # [ 42.305783] nixos[3100]: finished switching to system configuration /nix/store/2hswzbl0gimbwvfbif7b5w96h6m9dnfx-nixos-system-machine-test1820machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 1.49 seconds)1821machine: must succeed: mount | grep 'on /testmount'1822machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1823(finished: subtest: mounts, in 6.09 seconds)1824subtest: timers1825machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11826machine # [ 42.552719] nixos[3249]: switching to system configuration /nix/store/ap8qgiqak9c5rg283s875nvgg0zcscj1-nixos-system-machine-test1827machine # [ 42.555037] systemd[1]: Stopped target Remote File Systems.1828machine # [ 42.556838] systemd[1]: Stopped target Local File Systems.1829machine # [ 42.559649] systemd[1]: Unmounting Testmount...1830machine # [ 42.578077] systemd[1]: testmount.mount: Deactivated successfully.1831machine # [ 42.579690] systemd[1]: Unmounted Testmount.1832machine # [ 42.807736] systemd[1]: Reload requested from client PID 3249 ('.switch-to-conf') (unit backdoor.service)...1833machine # [ 42.809425] systemd[1]: Reloading...1834machine # [ 43.070227] systemd[1]: Reloading finished in 258 ms.1835machine # [ 43.133132] systemd[619]: Reexecution requested from client PID 3320 ('switch-to-confi')...1836machine # [ 43.135156] systemd[619]: Reexecuting.1837machine # [ 43.156167] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1838machine # [ 43.228516] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1839machine # [ 43.233942] systemd[619]: Reload requested from client PID 3320 ('switch-to-confi')...1840machine # [ 43.235175] systemd[619]: Reloading...1841machine # [ 43.309072] systemd[619]: Reloading finished in 72 ms.1842machine # [ 43.314888] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1843machine # [ 43.321102] systemd[619]: Stopped Run user-specific NixOS activation.1844machine # [ 43.323040] systemd[619]: Stopping Run user-specific NixOS activation...1845machine # [ 43.324581] systemd[619]: Starting Run user-specific NixOS activation...1846machine # [ 43.343993] systemd[619]: Finished Run user-specific NixOS activation.1847machine # [ 43.347095] systemd[1]: Stopped target Reactivate sysinit units.1848machine # [ 43.348610] systemd[1]: Stopping Reactivate sysinit units...1849machine # [ 43.349595] systemd[1]: Reached target Reactivate sysinit units.1850machine # [ 43.351513] systemd[1]: Reached target Remote File Systems.1851machine # [ 43.356923] systemd[1]: Reached target Local File Systems.1852machine # [ 43.359151] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1853machine # [ 43.360425] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1854machine # [ 43.361668] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1855machine # [ 43.365059] systemd[1]: Starting Create SUID/SGID Wrappers...1856machine # [ 43.365942] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1857machine # [ 43.367124] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671858machine # [ 43.370183] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1859machine # [ 43.372420] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1860machine # [ 43.373709] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1861machine # [ 43.491656] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1862machine # [ 43.493410] systemd[1]: Finished Create SUID/SGID Wrappers.1863machine # [ 43.495967] systemd[1]: Started test-timer.timer.1864machine # [ 43.498624] systemd[1]: Starting linger-users.service...1865machine # [ 43.525816] systemd[1]: linger-users.service: Deactivated successfully.1866machine # [ 43.527331] systemd[1]: Finished linger-users.service.1867machine # [ 43.785466] nixos[3249]: finished switching to system configuration /nix/store/ap8qgiqak9c5rg283s875nvgg0zcscj1-nixos-system-machine-test1868machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 1.45 seconds)1869machine: must succeed: systemctl show test-timer.timer1870machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1871machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11872machine # [ 44.046376] nixos[3394]: switching to system configuration /nix/store/6j187f0c564naxml0fa4wqp8p5s2qaj0-nixos-system-machine-test1873machine # [ 44.048853] systemd[1]: Stopped target Local File Systems.1874machine # [ 44.050244] systemd[1]: Stopped target Remote File Systems.1875machine # [ 44.277284] systemd[1]: Reload requested from client PID 3394 ('.switch-to-conf') (unit backdoor.service)...1876machine # [ 44.279050] systemd[1]: Reloading...1877machine # [ 44.540167] systemd[1]: Reloading finished in 259 ms.1878machine # [ 44.604336] systemd[619]: Reexecution requested from client PID 3463 ('switch-to-confi')...1879machine # [ 44.607046] systemd[619]: Reexecuting.1880machine # [ 44.629378] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1881machine # [ 44.704460] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1882machine # [ 44.709864] systemd[619]: Reload requested from client PID 3463 ('switch-to-confi')...1883machine # [ 44.712099] systemd[619]: Reloading...1884machine # [ 44.791702] systemd[619]: Reloading finished in 79 ms.1885machine # [ 44.798027] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1886machine # [ 44.805375] systemd[619]: Stopped Run user-specific NixOS activation.1887machine # [ 44.807147] systemd[619]: Stopping Run user-specific NixOS activation...1888machine # [ 44.809297] systemd[619]: Starting Run user-specific NixOS activation...1889machine # [ 44.833995] systemd[619]: Finished Run user-specific NixOS activation.1890machine # [ 44.837768] systemd[1]: Stopped target Reactivate sysinit units.1891machine # [ 44.839224] systemd[1]: Stopping Reactivate sysinit units...1892machine # [ 44.840750] systemd[1]: Reached target Reactivate sysinit units.1893machine # [ 44.842689] systemd[1]: test-timer.timer: Deactivated successfully.1894machine # [ 44.844328] systemd[1]: Stopped test-timer.timer.1895machine # [ 44.845295] systemd[1]: Stopping test-timer.timer...1896machine # [ 44.846260] systemd[1]: Started test-timer.timer.1897machine # [ 44.850398] systemd[1]: Reached target Remote File Systems.1898machine # [ 44.853166] systemd[1]: Reached target Local File Systems.1899machine # [ 44.855206] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1900machine # [ 44.859137] systemd[1]: Starting Create SUID/SGID Wrappers...1901machine # [ 44.861186] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1902machine # [ 44.862419] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671903machine # [ 44.866105] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1904machine # [ 44.867560] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1905machine # [ 44.870156] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1906machine # [ 44.872162] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1907machine # [ 44.873546] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1908machine # [ 45.022635] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1909machine # [ 45.024377] systemd[1]: Finished Create SUID/SGID Wrappers.1910machine # [ 45.028324] systemd[1]: Starting linger-users.service...1911machine # [ 45.057934] systemd[1]: linger-users.service: Deactivated successfully.1912machine # [ 45.059433] systemd[1]: Finished linger-users.service.1913machine # [ 45.317917] nixos[3394]: finished switching to system configuration /nix/store/6j187f0c564naxml0fa4wqp8p5s2qaj0-nixos-system-machine-test1914machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.49 seconds)1915machine: must succeed: systemctl show test-timer.timer1916machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1917(finished: subtest: timers, in 3.02 seconds)1918subtest: targets1919machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11920machine # [ 45.569190] nixos[3537]: switching to system configuration /nix/store/anpgm4dg5f2wi7paqkv08rafcqr6ykp5-nixos-system-machine-test1921machine # [ 45.571975] systemd[1]: Stopped target Local File Systems.1922machine # [ 45.573466] systemd[1]: Stopped target Remote File Systems.1923machine # [ 45.575051] systemd[1]: test-timer.timer: Deactivated successfully.1924machine # [ 45.576639] systemd[1]: Stopped test-timer.timer.1925machine # [ 45.804390] systemd[1]: Reload requested from client PID 3537 ('.switch-to-conf') (unit backdoor.service)...1926machine # [ 45.806148] systemd[1]: Reloading...1927machine # [ 46.051754] systemd[1]: Reloading finished in 243 ms.1928machine # [ 46.113915] systemd[619]: Reexecution requested from client PID 3606 ('switch-to-confi')...1929machine # [ 46.115227] systemd[619]: Reexecuting.1930machine # [ 46.137194] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1931machine # [ 46.208893] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1932machine # [ 46.215037] systemd[619]: Reload requested from client PID 3606 ('switch-to-confi')...1933machine # [ 46.216294] systemd[619]: Reloading...1934machine # [ 46.290436] systemd[619]: Reloading finished in 72 ms.1935machine # [ 46.294873] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1936machine # [ 46.300957] systemd[619]: Stopped Run user-specific NixOS activation.1937machine # [ 46.303110] systemd[619]: Stopping Run user-specific NixOS activation...1938machine # [ 46.304704] systemd[619]: Starting Run user-specific NixOS activation...1939machine # [ 46.325546] systemd[619]: Finished Run user-specific NixOS activation.1940machine # [ 46.328920] systemd[1]: Stopped target Reactivate sysinit units.1941machine # [ 46.330221] systemd[1]: Stopping Reactivate sysinit units...1942machine # [ 46.331287] systemd[1]: Reached target Reactivate sysinit units.1943machine # [ 46.334373] systemd[1]: Reached target Local File Systems.1944machine # [ 46.336949] systemd[1]: Reached target Remote File Systems.1945machine # [ 46.339984] systemd[1]: Starting linger-users.service...1946machine # [ 46.346028] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1947machine # [ 46.350451] systemd[1]: Starting Create SUID/SGID Wrappers...1948machine # [ 46.351276] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1949machine # [ 46.352456] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671950machine # [ 46.354447] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1951machine # [ 46.355873] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1952machine # [ 46.357055] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1953machine # [ 46.362840] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1954machine # [ 46.364033] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1955machine # [ 46.405089] systemd[1]: linger-users.service: Deactivated successfully.1956machine # [ 46.406947] systemd[1]: Finished linger-users.service.1957machine # [ 46.515890] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1958machine # [ 46.517786] systemd[1]: Finished Create SUID/SGID Wrappers.1959machine # [ 46.774648] nixos[3537]: finished switching to system configuration /nix/store/anpgm4dg5f2wi7paqkv08rafcqr6ykp5-nixos-system-machine-test1960machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1961machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11962machine # [ 47.016906] nixos[3677]: switching to system configuration /nix/store/mx4rm2dw8dlb4wqsvaz42i3hx35hj94p-nixos-system-machine-test1963machine # [ 47.019393] systemd[1]: Stopped target Remote File Systems.1964machine # [ 47.021129] systemd[1]: Stopped target Local File Systems.1965machine # [ 47.250693] systemd[1]: Reload requested from client PID 3677 ('.switch-to-conf') (unit backdoor.service)...1966machine # [ 47.252431] systemd[1]: Reloading...1967machine # [ 47.502154] systemd[1]: Reloading finished in 248 ms.1968machine # [ 47.564591] systemd[619]: Reexecution requested from client PID 3746 ('switch-to-confi')...1969machine # [ 47.566642] systemd[619]: Reexecuting.1970machine # [ 47.588237] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1971machine # [ 47.661471] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1972machine # [ 47.666859] systemd[619]: Reload requested from client PID 3746 ('switch-to-confi')...1973machine # [ 47.669130] systemd[619]: Reloading...1974machine # [ 47.742359] systemd[619]: Reloading finished in 73 ms.1975machine # [ 47.748928] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1976machine # [ 47.756114] systemd[619]: Stopped Run user-specific NixOS activation.1977machine # [ 47.756994] systemd[619]: Stopping Run user-specific NixOS activation...1978machine # [ 47.758690] systemd[619]: Starting Run user-specific NixOS activation...1979machine # [ 47.779447] systemd[619]: Finished Run user-specific NixOS activation.1980machine # [ 47.782560] systemd[1]: Stopped target Reactivate sysinit units.1981machine # [ 47.783820] systemd[1]: Stopping Reactivate sysinit units...1982machine # [ 47.784849] systemd[1]: Reached target Reactivate sysinit units.1983machine # [ 47.787065] systemd[1]: Reached target Remote File Systems.1984machine # [ 47.789333] systemd[1]: Reached target test-target.target.1985machine # [ 47.791298] systemd[1]: Starting linger-users.service...1986machine # [ 47.797374] systemd[1]: Reached target Local File Systems.1987machine # [ 47.798169] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1988machine # [ 47.802539] systemd[1]: Starting Create SUID/SGID Wrappers...1989machine # [ 47.803364] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1990machine # [ 47.804494] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671991machine # [ 47.806416] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1992machine # [ 47.807854] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1993machine # [ 47.808978] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1994machine # [ 47.821790] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1995machine # [ 47.823416] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1996machine # [ 47.856736] systemd[1]: linger-users.service: Deactivated successfully.1997machine # [ 47.858479] systemd[1]: Finished linger-users.service.1998machine # [ 47.965980] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1999machine # [ 47.967863] systemd[1]: Finished Create SUID/SGID Wrappers.2000machine # [ 48.226467] nixos[3677]: finished switching to system configuration /nix/store/mx4rm2dw8dlb4wqsvaz42i3hx35hj94p-nixos-system-machine-test2001machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 1.45 seconds)2002machine # [ 48.281354] systemd[1]: Started test-service.service.2003machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&12004machine # [ 48.464702] nixos[3823]: switching to system configuration /nix/store/yjv31dcp6h4yxh9ibq780lalqnag17cl-nixos-system-machine-test2005machine # [ 48.466993] systemd[1]: Stopped target Local File Systems.2006machine # [ 48.468661] systemd[1]: Stopped target Remote File Systems.2007machine # [ 48.684453] systemd[1]: Reload requested from client PID 3823 ('.switch-to-conf') (unit backdoor.service)...2008machine # [ 48.686140] systemd[1]: Reloading...2009machine # [ 48.947811] systemd[1]: Reloading finished in 260 ms.2010machine # [ 49.017215] systemd[619]: Reexecution requested from client PID 3893 ('switch-to-confi')...2011machine # [ 49.019242] systemd[619]: Reexecuting.2012machine # [ 49.042421] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2013machine # [ 49.117383] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2014machine # [ 49.122769] systemd[619]: Reload requested from client PID 3893 ('switch-to-confi')...2015machine # [ 49.124530] systemd[619]: Reloading...2016machine # [ 49.196096] systemd[619]: Reloading finished in 70 ms.2017machine # [ 49.201934] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2018machine # [ 49.208261] systemd[619]: Stopped Run user-specific NixOS activation.2019machine # [ 49.209319] systemd[619]: Stopping Run user-specific NixOS activation...2020machine # [ 49.211984] systemd[619]: Starting Run user-specific NixOS activation...2021machine # [ 49.234268] systemd[619]: Finished Run user-specific NixOS activation.2022machine # [ 49.237511] systemd[1]: Stopped target Reactivate sysinit units.2023machine # [ 49.238826] systemd[1]: Stopping Reactivate sysinit units...2024machine # [ 49.239865] systemd[1]: Reached target Reactivate sysinit units.2025machine # [ 49.242516] systemd[1]: Reached target Local File Systems.2026machine # [ 49.246071] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2027machine # [ 49.249221] systemd[1]: Starting Create SUID/SGID Wrappers...2028machine # [ 49.250376] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2029machine # [ 49.251558] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672030machine # [ 49.254469] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2031machine # [ 49.256987] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2032machine # [ 49.259419] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2033machine # [ 49.260565] systemd[1]: Reached target Remote File Systems.2034machine # [ 49.266852] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2035machine # [ 49.268669] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2036machine # [ 49.394498] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2037machine # [ 49.396247] systemd[1]: Finished Create SUID/SGID Wrappers.2038machine # [ 49.400183] systemd[1]: Starting linger-users.service...2039machine # [ 49.430684] systemd[1]: linger-users.service: Deactivated successfully.2040machine # [ 49.432358] systemd[1]: Finished linger-users.service.2041machine # [ 49.690387] nixos[3823]: finished switching to system configuration /nix/store/yjv31dcp6h4yxh9ibq780lalqnag17cl-nixos-system-machine-test2042machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 1.41 seconds)2043machine: must succeed: systemctl is-active test-service.service2044machine: (finished: must succeed: systemctl is-active test-service.service, in 0.03 seconds)2045machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&12046machine # [ 49.941858] nixos[3967]: switching to system configuration /nix/store/f9j7w7n9qhv2ca8czi4cgjn2fw75wjys-nixos-system-machine-test2047machine # [ 49.944106] systemd[1]: Stopped target Remote File Systems.2048machine # [ 49.945850] systemd[1]: Stopped target test-target.target.2049machine # [ 49.947814] systemd[1]: Stopping test-service.service...2050machine # [ 49.950194] systemd[1]: Stopped target Local File Systems.2051machine # [ 49.951605] systemd[1]: test-service.service: Deactivated successfully.2052machine # [ 49.953739] systemd[1]: Stopped test-service.service.2053machine # [ 50.181496] systemd[1]: Reload requested from client PID 3967 ('.switch-to-conf') (unit backdoor.service)...2054machine # [ 50.183321] systemd[1]: Reloading...2055machine # [ 50.433696] systemd[1]: Reloading finished in 248 ms.2056machine # [ 50.497256] systemd[619]: Reexecution requested from client PID 4037 ('switch-to-confi')...2057machine # [ 50.499219] systemd[619]: Reexecuting.2058machine # [ 50.519283] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2059machine # [ 50.592390] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2060machine # [ 50.597946] systemd[619]: Reload requested from client PID 4037 ('switch-to-confi')...2061machine # [ 50.600042] systemd[619]: Reloading...2062machine # [ 50.672554] systemd[619]: Reloading finished in 72 ms.2063machine # [ 50.677886] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2064machine # [ 50.684223] systemd[619]: Stopped Run user-specific NixOS activation.2065machine # [ 50.686056] systemd[619]: Stopping Run user-specific NixOS activation...2066machine # [ 50.687838] systemd[619]: Starting Run user-specific NixOS activation...2067machine # [ 50.710958] systemd[619]: Finished Run user-specific NixOS activation.2068machine # [ 50.714284] systemd[1]: Stopped target Reactivate sysinit units.2069machine # [ 50.715501] systemd[1]: Stopping Reactivate sysinit units...2070machine # [ 50.716540] systemd[1]: Reached target Reactivate sysinit units.2071machine # [ 50.719369] systemd[1]: Reached target Remote File Systems.2072machine # [ 50.723352] systemd[1]: Reached target Local File Systems.2073machine # [ 50.725423] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2074machine # [ 50.728145] systemd[1]: Starting Create SUID/SGID Wrappers...2075machine # [ 50.728931] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2076machine # [ 50.731491] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672077machine # [ 50.734094] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2078machine # [ 50.735604] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2079machine # [ 50.736804] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2080machine # [ 50.743687] systemd[1]: Reached target test-target.target.2081machine # [ 50.750063] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2082machine # [ 50.751251] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2083machine # [ 50.876222] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2084machine # [ 50.878038] systemd[1]: Finished Create SUID/SGID Wrappers.2085machine # [ 50.882548] systemd[1]: Starting linger-users.service...2086machine # [ 50.912962] systemd[1]: linger-users.service: Deactivated successfully.2087machine # [ 50.914525] systemd[1]: Finished linger-users.service.2088machine # [ 51.172714] nixos[3967]: finished switching to system configuration /nix/store/f9j7w7n9qhv2ca8czi4cgjn2fw75wjys-nixos-system-machine-test2089machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 1.45 seconds)2090machine: must fail: systemctl is-active test-service.servce2091machine: (finished: must fail: systemctl is-active test-service.servce, in 0.03 seconds)2092machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&12093machine # [ 51.409465] nixos[4111]: switching to system configuration /nix/store/6j187f0c564naxml0fa4wqp8p5s2qaj0-nixos-system-machine-test2094machine # [ 51.411929] systemd[1]: Stopped target Local File Systems.2095machine # [ 51.413519] systemd[1]: Stopped target test-target.target.2096machine # [ 51.415064] systemd[1]: Stopped target Remote File Systems.2097machine # [ 51.622404] systemd[1]: Reload requested from client PID 4111 ('.switch-to-conf') (unit backdoor.service)...2098machine # [ 51.624391] systemd[1]: Reloading...2099machine # [ 51.862869] systemd[1]: Reloading finished in 237 ms.2100machine # [ 51.923953] systemd[619]: Reexecution requested from client PID 4180 ('switch-to-confi')...2101machine # [ 51.926565] systemd[619]: Reexecuting.2102machine # [ 51.947207] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2103machine # [ 52.019334] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2104machine # [ 52.024941] systemd[619]: Reload requested from client PID 4180 ('switch-to-confi')...2105machine # [ 52.027106] systemd[619]: Reloading...2106machine # [ 52.101549] systemd[619]: Reloading finished in 74 ms.2107machine # [ 52.106906] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2108machine # [ 52.113927] systemd[619]: Stopped Run user-specific NixOS activation.2109machine # [ 52.114836] systemd[619]: Stopping Run user-specific NixOS activation...2110machine # [ 52.116575] systemd[619]: Starting Run user-specific NixOS activation...2111machine # [ 52.137796] systemd[619]: Finished Run user-specific NixOS activation.2112machine # [ 52.140987] systemd[1]: Stopped target Reactivate sysinit units.2113machine # [ 52.142468] systemd[1]: Stopping Reactivate sysinit units...2114machine # [ 52.143576] systemd[1]: Reached target Reactivate sysinit units.2115machine # [ 52.146811] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2116machine # [ 52.148617] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2117machine # [ 52.151253] systemd[1]: Reached target Local File Systems.2118machine # [ 52.153902] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2119machine # [ 52.157427] systemd[1]: Starting Create SUID/SGID Wrappers...2120machine # [ 52.159591] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2121machine # [ 52.162078] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672122machine # [ 52.164104] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2123machine # [ 52.165564] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2124machine # [ 52.166761] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2125machine # [ 52.170152] systemd[1]: Reached target Remote File Systems.2126machine # [ 52.298965] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2127machine # [ 52.301072] systemd[1]: Finished Create SUID/SGID Wrappers.2128machine # [ 52.302854] systemd[1]: Started test-timer.timer.2129machine # [ 52.305842] systemd[1]: Starting linger-users.service...2130machine # [ 52.335925] systemd[1]: linger-users.service: Deactivated successfully.2131machine # [ 52.337886] systemd[1]: Finished linger-users.service.2132machine # [ 52.595601] nixos[4111]: finished switching to system configuration /nix/store/6j187f0c564naxml0fa4wqp8p5s2qaj0-nixos-system-machine-test2133machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.39 seconds)2134(finished: subtest: targets, in 7.24 seconds)2135subtest: paths2136machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&12137machine # [ 52.829714] nixos[4251]: switching to system configuration /nix/store/aawvxd5h9gm0w02i5hzpawy46zby138m-nixos-system-machine-test2138machine # [ 52.832497] systemd[1]: Stopped target Remote File Systems.2139machine # [ 52.834434] systemd[1]: Stopped target Local File Systems.2140machine # [ 52.836119] systemd[1]: test-timer.timer: Deactivated successfully.2141machine # [ 52.836991] systemd[1]: Stopped test-timer.timer.2142machine # [ 53.071273] systemd[1]: Reload requested from client PID 4251 ('.switch-to-conf') (unit backdoor.service)...2143machine # [ 53.073137] systemd[1]: Reloading...2144machine # [ 53.329187] systemd[1]: Reloading finished in 254 ms.2145machine # [ 53.396602] systemd[619]: Reexecution requested from client PID 4320 ('switch-to-confi')...2146machine # [ 53.398562] systemd[619]: Reexecuting.2147machine # [ 53.420328] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2148machine # [ 53.499861] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2149machine # [ 53.505046] systemd[619]: Reload requested from client PID 4320 ('switch-to-confi')...2150machine # [ 53.506386] systemd[619]: Reloading...2151machine # [ 53.586047] systemd[619]: Reloading finished in 77 ms.2152machine # [ 53.590080] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2153machine # [ 53.600095] systemd[619]: Stopped Run user-specific NixOS activation.2154machine # [ 53.601191] systemd[619]: Stopping Run user-specific NixOS activation...2155machine # [ 53.603938] systemd[619]: Starting Run user-specific NixOS activation...2156machine # [ 53.626358] systemd[619]: Finished Run user-specific NixOS activation.2157machine # [ 53.629777] systemd[1]: Stopped target Reactivate sysinit units.2158machine # [ 53.630811] systemd[1]: Stopping Reactivate sysinit units...2159machine # [ 53.632173] systemd[1]: Reached target Reactivate sysinit units.2160machine # [ 53.635082] systemd[1]: Started test-watch.path.2161machine # [ 53.637841] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2162machine # [ 53.639579] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2163machine # [ 53.643763] systemd[1]: Reached target Local File Systems.2164machine # [ 53.646202] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2165machine # [ 53.649268] systemd[1]: Starting Create SUID/SGID Wrappers...2166machine # [ 53.650675] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2167machine # [ 53.651851] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672168machine # [ 53.653720] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2169machine # [ 53.655124] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2170machine # [ 53.656373] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2171machine # [ 53.660353] systemd[1]: Reached target Remote File Systems.2172machine # [ 53.793976] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2173machine # [ 53.797131] systemd[1]: Finished Create SUID/SGID Wrappers.2174machine # [ 53.799983] systemd[1]: Starting linger-users.service...2175machine # [ 53.830675] systemd[1]: linger-users.service: Deactivated successfully.2176machine # [ 53.832330] systemd[1]: Finished linger-users.service.2177machine # [ 54.090963] nixos[4251]: finished switching to system configuration /nix/store/aawvxd5h9gm0w02i5hzpawy46zby138m-nixos-system-machine-test2178machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 1.49 seconds)2179machine: must fail: test -f /testpath-modified2180machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2181machine: must succeed: touch /testpath2182machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2183machine: waiting for success: test -f /testpath-modified2184machine # [ 54.152683] systemd[1]: Starting test-watch.service...2185machine # [ 54.175791] systemd[1]: Finished test-watch.service.2186machine: (finished: waiting for success: test -f /testpath-modified, in 1.05 seconds)2187machine: must succeed: rm /testpath /testpath-modified2188machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.03 seconds)2189machine # [ 55.251770] systemd[1]: test-watch.service: Deactivated successfully.2190machine # [ 55.253909] systemd[1]: Stopped test-watch.service.2191machine # Stopping 'test-watch.service', but its triggering units are still active:2192machine # test-watch.path2193machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12194machine # [ 55.455873] nixos[4416]: switching to system configuration /nix/store/gg56j02isz296q9q3amnr5pd6qm9wxqi-nixos-system-machine-test2195machine # [ 55.458337] systemd[1]: Stopped target Remote File Systems.2196machine # [ 55.459836] systemd[1]: Stopped target Local File Systems.2197machine # [ 55.687391] systemd[1]: Reload requested from client PID 4416 ('.switch-to-conf') (unit backdoor.service)...2198machine # [ 55.689247] systemd[1]: Reloading...2199machine # [ 55.944972] systemd[1]: Reloading finished in 253 ms.2200machine # [ 56.008324] systemd[619]: Reexecution requested from client PID 4486 ('switch-to-confi')...2201machine # [ 56.010801] systemd[619]: Reexecuting.2202machine # [ 56.032581] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2203machine # [ 56.108317] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2204machine # [ 56.113597] systemd[619]: Reload requested from client PID 4486 ('switch-to-confi')...2205machine # [ 56.114920] systemd[619]: Reloading...2206machine # [ 56.189561] systemd[619]: Reloading finished in 73 ms.2207machine # [ 56.194881] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2208machine # [ 56.202110] systemd[619]: Stopped Run user-specific NixOS activation.2209machine # [ 56.202963] systemd[619]: Stopping Run user-specific NixOS activation...2210machine # [ 56.204946] systemd[619]: Starting Run user-specific NixOS activation...2211machine # [ 56.227892] systemd[619]: Finished Run user-specific NixOS activation.2212machine # [ 56.231123] systemd[1]: Stopped target Reactivate sysinit units.2213machine # [ 56.232641] systemd[1]: Stopping Reactivate sysinit units...2214machine # [ 56.233748] systemd[1]: Reached target Reactivate sysinit units.2215machine # [ 56.234977] systemd[1]: Reached target Local File Systems.2216machine # [ 56.238261] systemd[1]: Reached target Remote File Systems.2217machine # [ 56.242570] systemd[1]: Starting linger-users.service...2218machine # [ 56.248033] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2219machine # [ 56.249263] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2220machine # [ 56.253921] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2221machine # [ 56.258815] systemd[1]: Starting Create SUID/SGID Wrappers...2222machine # [ 56.260143] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2223machine # [ 56.262648] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672224machine # [ 56.264694] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2225machine # [ 56.268119] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2226machine # [ 56.270059] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2227machine # [ 56.305225] systemd[1]: linger-users.service: Deactivated successfully.2228machine # [ 56.306697] systemd[1]: Finished linger-users.service.2229machine # [ 56.422855] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2230machine # [ 56.424604] systemd[1]: Finished Create SUID/SGID Wrappers.2231machine # [ 56.681682] nixos[4416]: finished switching to system configuration /nix/store/gg56j02isz296q9q3amnr5pd6qm9wxqi-nixos-system-machine-test2232machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 1.43 seconds)2233machine: must succeed: touch /testpath2234machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2235machine: must fail: test -f /testpath-modified2236machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2237machine: must succeed: touch /testpath22238machine: (finished: must succeed: touch /testpath2, in 0.02 seconds)2239machine: waiting for success: test -f /testpath-modified2240machine # [ 56.761077] systemd[1]: Starting test-watch.service...2241machine # [ 56.786802] systemd[1]: Finished test-watch.service.2242machine: (finished: waiting for success: test -f /testpath-modified, in 1.05 seconds)2243(finished: subtest: paths, in 5.20 seconds)2244subtest: slices2245machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2246machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2247machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12248machine # [ 58.054984] nixos[4581]: switching to system configuration /nix/store/b8dvamkhzp7cncgnlh30q23nm6ihdmpp-nixos-system-machine-test2249machine # [ 58.057520] systemd[1]: Stopped target Local File Systems.2250machine # [ 58.059242] systemd[1]: test-watch.service: Deactivated successfully.2251machine # [ 58.060779] systemd[1]: Stopped test-watch.service.2252machine # [ 58.064054] systemd[1]: test-watch.path: Deactivated successfully.2253machine # [ 58.064955] systemd[1]: Stopped test-watch.path.2254machine # [ 58.066742] systemd[1]: Starting test-watch.service...2255machine # [ 58.068345] systemd[1]: Stopped target Remote File Systems.2256machine # [ 58.093292] systemd[1]: Finished test-watch.service.2257machine # [ 58.317795] systemd[1]: Reload requested from client PID 4581 ('.switch-to-conf') (unit backdoor.service)...2258machine # [ 58.319602] systemd[1]: Reloading...2259machine # [ 58.576837] systemd[1]: Reloading finished in 255 ms.2260machine # [ 58.642334] systemd[619]: Reexecution requested from client PID 4652 ('switch-to-confi')...2261machine # [ 58.644184] systemd[619]: Reexecuting.2262machine # [ 58.666397] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2263machine # [ 58.742186] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2264machine # [ 58.747699] systemd[619]: Reload requested from client PID 4652 ('switch-to-confi')...2265machine # [ 58.749467] systemd[619]: Reloading...2266machine # [ 58.823895] systemd[619]: Reloading finished in 73 ms.2267machine # [ 58.829930] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2268machine # [ 58.837134] systemd[619]: Stopped Run user-specific NixOS activation.2269machine # [ 58.838108] systemd[619]: Stopping Run user-specific NixOS activation...2270machine # [ 58.840230] systemd[619]: Starting Run user-specific NixOS activation...2271machine # [ 58.861629] systemd[619]: Finished Run user-specific NixOS activation.2272machine # [ 58.864860] systemd[1]: Stopped target Reactivate sysinit units.2273machine # [ 58.866217] systemd[1]: Stopping Reactivate sysinit units...2274machine # [ 58.866993] systemd[1]: Reached target Reactivate sysinit units.2275machine # [ 58.871136] systemd[1]: Reached target Local File Systems.2276machine # [ 58.871926] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2277machine # [ 58.875368] systemd[1]: Starting Create SUID/SGID Wrappers...2278machine # [ 58.876297] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2279machine # [ 58.878359] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672280machine # [ 58.881345] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2281machine # [ 58.882855] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2282machine # [ 58.886214] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2283machine # [ 58.889715] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2284machine # [ 58.890943] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2285machine # [ 58.893272] systemd[1]: Reached target Remote File Systems.2286machine # [ 59.028814] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2287machine # [ 59.030555] systemd[1]: Finished Create SUID/SGID Wrappers.2288machine # [ 59.034652] systemd[1]: Starting linger-users.service...2289machine # [ 59.064722] systemd[1]: linger-users.service: Deactivated successfully.2290machine # [ 59.067081] systemd[1]: Finished linger-users.service.2291machine # [ 59.323843] nixos[4581]: finished switching to system configuration /nix/store/b8dvamkhzp7cncgnlh30q23nm6ihdmpp-nixos-system-machine-test2292machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 1.50 seconds)2293machine: must fail: systemctl start testservice.service2294machine # [ 59.372084] systemd[1]: Created slice Slice /testslice.2295machine # [ 59.518527] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02296machine # [ 59.520542] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.56 #1-NixOS PREEMPT(lazy)2297machine # [ 59.520547] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/20142298machine # [ 59.520555] Call Trace:2299machine # [ 59.520577] <TASK>2300machine # [ 59.520581] dump_stack_lvl+0x5d/0x802301machine # [ 59.520617] dump_header+0x43/0x1aa2302machine # [ 59.520644] out_of_memory.cold+0x35/0x862303machine # [ 59.520650] ? psi_group_change+0x11f/0x2f02304machine # [ 59.520671] ? srso_alias_return_thunk+0x5/0xfbef52305machine # [ 59.520685] mem_cgroup_out_of_memory+0xe1/0xf02306machine # [ 59.520726] try_charge_memcg+0x46e/0x6b02307machine # [ 59.520746] obj_cgroup_charge_pages+0x3e/0x1002308machine # [ 59.520751] obj_cgroup_charge_account+0x92/0x1602309machine # [ 59.520757] __memcg_slab_post_alloc_hook+0x119/0x3a02310machine # [ 59.520767] ? mem_cgroup_css_alloc+0x2a2/0x9102311machine # [ 59.520803] __kmalloc_cache_noprof+0x4e7/0x5f02312machine # [ 59.520819] ? __xa_alloc+0xd4/0x1902313machine # [ 59.520831] ? mem_cgroup_css_alloc+0x2a2/0x9102314machine # [ 59.520834] mem_cgroup_css_alloc+0x2a2/0x9102315machine # [ 59.520840] ? srso_alias_return_thunk+0x5/0xfbef52316machine # [ 59.520843] cgroup_apply_control_enable+0x15b/0x3702317machine # [ 59.520872] cgroup_mkdir+0x20f/0x5c02318machine # [ 59.520879] kernfs_iop_mkdir+0x6f/0xf02319machine # [ 59.520902] vfs_mkdir+0x1c7/0x2c02320machine # [ 59.520918] do_mkdirat+0x144/0x1802321machine # [ 59.520926] __x64_sys_mkdir+0x47/0x702322machine # [ 59.520935] do_syscall_64+0xd6/0x7f02323machine # [ 59.520946] ? srso_alias_return_thunk+0x5/0xfbef52324machine # [ 59.520949] ? do_syscall_64+0x110/0x7f02325machine # [ 59.520953] ? srso_alias_return_thunk+0x5/0xfbef52326machine # [ 59.520956] ? kmem_cache_free+0xc8/0x4b02327machine # [ 59.520960] ? do_sys_openat2+0xa2/0xe02328machine # [ 59.520980] ? srso_alias_return_thunk+0x5/0xfbef52329machine # [ 59.520983] ? do_sys_openat2+0xa2/0xe02330machine # [ 59.520992] ? srso_alias_return_thunk+0x5/0xfbef52331machine # [ 59.520995] ? __x64_sys_openat+0x61/0xa02332machine # [ 59.520999] ? srso_alias_return_thunk+0x5/0xfbef52333machine # [ 59.521002] ? do_syscall_64+0x110/0x7f02334machine # [ 59.521009] ? srso_alias_return_thunk+0x5/0xfbef52335machine # [ 59.521012] ? __sys_sendmsg+0xad/0x1102336machine # [ 59.521031] ? srso_alias_return_thunk+0x5/0xfbef52337machine # [ 59.521034] ? ktime_get_ts64+0xfb/0x1602338machine # [ 59.521050] ? srso_alias_return_thunk+0x5/0xfbef52339machine # [ 59.521053] ? srso_alias_return_thunk+0x5/0xfbef52340machine # [ 59.521056] ? put_timespec64+0x3a/0x602341machine # [ 59.521069] ? srso_alias_return_thunk+0x5/0xfbef52342machine # [ 59.521072] ? __x64_sys_clock_gettime+0x9f/0xf02343machine # [ 59.521086] ? srso_alias_return_thunk+0x5/0xfbef52344machine # [ 59.521089] ? do_syscall_64+0x110/0x7f02345machine # [ 59.521092] ? srso_alias_return_thunk+0x5/0xfbef52346machine # [ 59.521095] ? do_syscall_64+0x110/0x7f02347machine # [ 59.521098] ? srso_alias_return_thunk+0x5/0xfbef52348machine # [ 59.521101] ? do_syscall_64+0x110/0x7f02349machine # [ 59.521105] ? srso_alias_return_thunk+0x5/0xfbef52350machine # [ 59.521108] ? srso_alias_return_thunk+0x5/0xfbef52351machine # [ 59.521112] entry_SYSCALL_64_after_hwframe+0x77/0x7f2352machine # [ 59.521116] RIP: 0033:0x7f436dbea39b2353machine # [ 59.521149] Code: 0f 1e fa 48 89 f2 b9 00 01 00 00 48 89 fe bf 9c ff ff ff e9 f7 a9 ff ff 0f 1f 80 00 00 00 00 f3 0f 1e fa b8 53 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 0d 31 d2 31 c9 31 f6 31 ff 45 31 db c3 90 482354machine # [ 59.521151] RSP: 002b:00007ffd0408eb78 EFLAGS: 00000246 ORIG_RAX: 00000000000000532355machine # [ 59.521157] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f436dbea39b2356machine # [ 59.521165] RDX: 0000000000000000 RSI: 00000000000001ed RDI: 000056485b8e73802357machine # [ 59.521166] RBP: 00007ffd0408eba0 R08: 0000000000000000 R09: 00000000000000002358machine # [ 59.521168] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000000002359machine # [ 59.521170] R13: 00000000000001a8 R14: 0000000000000000 R15: 00000000000000002360machine # [ 59.521178] </TASK>2361machine # [ 59.521180] memory: usage 0kB, limit 0kB, failcnt 182362machine # [ 59.568461] swap: usage 0kB, limit 9007199254740988kB, failcnt 02363machine # [ 59.569745] Memory cgroup stats for /testslice.slice:2364machine # [ 59.569781] anon 02365machine # [ 59.571181] file 02366machine # [ 59.571758] kernel 02367machine # [ 59.572160] kernel_stack 02368machine # [ 59.572840] pagetables 02369machine # [ 59.573461] sec_pagetables 02370machine # [ 59.573899] percpu 02371machine # [ 59.574515] sock 02372machine # [ 59.574879] vmalloc 02373machine # [ 59.575512] shmem 02374machine # [ 59.575882] zswap 02375machine # [ 59.576507] zswapped 02376machine # [ 59.576895] file_mapped 02377machine # [ 59.577557] file_dirty 02378machine # [ 59.577987] file_writeback 02379machine # [ 59.578661] swapcached 02380machine # [ 59.579123] anon_thp 02381machine # [ 59.579778] file_thp 02382machine # [ 59.580450] shmem_thp 02383machine # [ 59.580841] inactive_anon 02384machine # [ 59.581489] active_anon 02385machine # [ 59.581893] inactive_file 02386machine # [ 59.582544] active_file 02387machine # [ 59.582939] unevictable 02388machine # [ 59.583579] slab_reclaimable 02389machine # [ 59.584063] slab_unreclaimable 02390machine # [ 59.584787] slab 02391machine # [ 59.585159] hugetlb 02392machine # [ 59.585788] workingset_refault_anon 02393machine # [ 59.586504] workingset_refault_file 02394machine # [ 59.587022] workingset_activate_anon 02395machine # [ 59.587762] workingset_activate_file 02396machine # [ 59.588486] workingset_restore_anon 02397machine # [ 59.589016] workingset_restore_file 02398machine # [ 59.589760] workingset_nodereclaim 02399machine # [ 59.590504] pgdemote_kswapd 02400machine # [ 59.590934] pgdemote_direct 02401machine # [ 59.591624] pgdemote_khugepaged 02402machine # [ 59.592095] pgdemote_proactive 02403machine # [ 59.592776] pgpromote_success 02404machine # [ 59.593455] pgscan 02405machine # [ 59.593791] pgsteal 02406machine # [ 59.594146] pswpin 02407machine # [ 59.594809] pswpout 02408machine # [ 59.595168] pgscan_kswapd 02409machine # [ 59.595795] pgscan_direct 02410machine # [ 59.596201] pgscan_khugepaged 02411machine # [ 59.596856] pgscan_proactive 02412machine # [ 59.597454] pgsteal_kswapd 02413machine # [ 59.597840] pgsteal_direct 02414machine # [ 59.598229] pgsteal_khugepaged 02415machine # [ 59.598992] pgsteal_proactive 02416machine # [ 59.599637] pgfault 02417machine # [ 59.600007] pgmajfault 02418machine # [ 59.600602] pgrefill 02419machine # [ 59.600934] pgactivate 02420machine # [ 59.601515] pgdeactivate 02421machine # [ 59.601883] pglazyfree 02422machine # [ 59.602220] pglazyfreed 02423machine # [ 59.602859] swpin_zero 02424machine # [ 59.603205] swpout_zero 02425machine # [ 59.603825] zswpin 02426machine # [ 59.604170] zswpout 02427machine # [ 59.604744] zswpwb 02428machine # [ 59.605088] thp_fault_alloc 02429machine # [ 59.605718] thp_collapse_alloc 02430machine # [ 59.606154] thp_swpout 02431machine # [ 59.606746] thp_swpout_fallback 02432machine # [ 59.607182] numa_pages_migrated 02433machine # [ 59.607845] numa_pte_updates 02434machine # [ 59.608452] numa_hint_faults 02435machine # [ 59.608849] Tasks state (memory values in pages):2436machine # [ 59.609665] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2437machine # [ 59.611001] Out of memory and no killable processes...2438machine # [ 59.467718] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2439machine # [ 59.469441] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2440machine # [ 59.470819] systemd[1]: testservice.service: Failed with result 'resources'.2441machine # [ 59.472715] systemd[1]: Failed to start testservice.service.2442machine # Job for testservice.service failed because of unavailable resources or another system error.2443machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2444machine: (finished: must fail: systemctl start testservice.service, in 0.15 seconds)2445machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12446machine # [ 59.703949] nixos[4727]: switching to system configuration /nix/store/rr2m88bjbs7yf06dxgsad3c0g2wqm856-nixos-system-machine-test2447machine # [ 59.706435] systemd[1]: Stopped target Local File Systems.2448machine # [ 59.708042] systemd[1]: Stopped target Remote File Systems.2449machine # [ 59.946183] systemd[1]: Reload requested from client PID 4727 ('.switch-to-conf') (unit backdoor.service)...2450machine # [ 59.947909] systemd[1]: Reloading...2451machine # [ 60.204181] systemd[1]: Reloading finished in 254 ms.2452machine # [ 60.270757] systemd[619]: Reexecution requested from client PID 4796 ('switch-to-confi')...2453machine # [ 60.272668] systemd[619]: Reexecuting.2454machine # [ 60.291092] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2455machine # [ 60.359902] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2456machine # [ 60.365103] systemd[619]: Reload requested from client PID 4796 ('switch-to-confi')...2457machine # [ 60.366309] systemd[619]: Reloading...2458machine # [ 60.435706] systemd[619]: Reloading finished in 67 ms.2459machine # [ 60.440100] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2460machine # [ 60.448066] systemd[619]: Stopped Run user-specific NixOS activation.2461machine # [ 60.448911] systemd[619]: Stopping Run user-specific NixOS activation...2462machine # [ 60.451116] systemd[619]: Starting Run user-specific NixOS activation...2463machine # [ 60.472736] systemd[619]: Finished Run user-specific NixOS activation.2464machine # [ 60.475908] systemd[1]: Stopped target Reactivate sysinit units.2465machine # [ 60.477264] systemd[1]: Stopping Reactivate sysinit units...2466machine # [ 60.478168] systemd[1]: Reached target Reactivate sysinit units.2467machine # [ 60.481386] systemd[1]: Reached target Local File Systems.2468machine # [ 60.482199] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2469machine # [ 60.485056] systemd[1]: Starting Create SUID/SGID Wrappers...2470machine # [ 60.486893] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2471machine # [ 60.488426] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672472machine # [ 60.490420] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2473machine # [ 60.491848] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2474machine # [ 60.496340] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2475machine # [ 60.497493] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2476machine # [ 60.498627] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2477machine # [ 60.503118] systemd[1]: Reached target Remote File Systems.2478machine # [ 60.638402] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2479machine # [ 60.640199] systemd[1]: Finished Create SUID/SGID Wrappers.2480machine # [ 60.644390] systemd[1]: Starting linger-users.service...2481machine # [ 60.673780] systemd[1]: linger-users.service: Deactivated successfully.2482machine # [ 60.675356] systemd[1]: Finished linger-users.service.2483machine # [ 60.933288] nixos[4727]: finished switching to system configuration /nix/store/rr2m88bjbs7yf06dxgsad3c0g2wqm856-nixos-system-machine-test2484machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 1.46 seconds)2485machine: must succeed: systemctl start testservice.service2486machine # [ 60.984122] systemd[1]: Starting testservice.service...2487machine # [ 61.008891] systemd[1]: Finished testservice.service.2488machine: (finished: must succeed: systemctl start testservice.service, in 0.07 seconds)2489machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2490machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.01 seconds)2491(finished: subtest: slices, in 3.21 seconds)2492subtest: dbus reloads2493machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12494machine # [ 61.213435] nixos[4876]: switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test2495machine # [ 61.216199] systemd[1]: Stopped target Local File Systems.2496machine # [ 61.217572] systemd[1]: testservice.service: Deactivated successfully.2497machine # [ 61.219395] systemd[1]: Stopped testservice.service.2498machine # [ 61.222099] systemd[1]: Removed slice Slice /testslice.2499machine # [ 61.223329] systemd[1]: Stopped target Remote File Systems.2500machine # [ 61.437438] systemd[1]: Reload requested from client PID 4876 ('.switch-to-conf') (unit backdoor.service)...2501machine # [ 61.439278] systemd[1]: Reloading...2502machine # [ 61.684279] systemd[1]: Reloading finished in 243 ms.2503machine # [ 61.749892] systemd[619]: Reexecution requested from client PID 4945 ('switch-to-confi')...2504machine # [ 61.751993] systemd[619]: Reexecuting.2505machine # [ 61.774489] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2506machine # [ 61.848923] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2507machine # [ 61.854516] systemd[619]: Reload requested from client PID 4945 ('switch-to-confi')...2508machine # [ 61.856601] systemd[619]: Reloading...2509machine # [ 61.929995] systemd[619]: Reloading finished in 72 ms.2510machine # [ 61.935927] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2511machine # [ 61.943120] systemd[619]: Stopped Run user-specific NixOS activation.2512machine # [ 61.944045] systemd[619]: Stopping Run user-specific NixOS activation...2513machine # [ 61.946231] systemd[619]: Starting Run user-specific NixOS activation...2514machine # [ 61.967590] systemd[619]: Finished Run user-specific NixOS activation.2515machine # [ 61.970789] systemd[1]: Stopped target Reactivate sysinit units.2516machine # [ 61.972348] systemd[1]: Stopping Reactivate sysinit units...2517machine # [ 61.973409] systemd[1]: Reached target Reactivate sysinit units.2518machine # [ 61.976359] systemd[1]: Reached target Local File Systems.2519machine # [ 61.978222] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2520machine # [ 61.980720] systemd[1]: Starting Create SUID/SGID Wrappers...2521machine # [ 61.982262] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2522machine # [ 61.984336] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672523machine # [ 61.986346] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2524machine # [ 61.987824] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2525machine # [ 61.989055] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2526machine # [ 61.995874] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2527machine # [ 61.997180] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2528machine # [ 61.999857] systemd[1]: Reached target Remote File Systems.2529machine # [ 62.134591] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2530machine # [ 62.136473] systemd[1]: Finished Create SUID/SGID Wrappers.2531machine # [ 62.140771] systemd[1]: Starting linger-users.service...2532machine # [ 62.170319] systemd[1]: linger-users.service: Deactivated successfully.2533machine # [ 62.171852] systemd[1]: Finished linger-users.service.2534machine # [ 62.429364] nixos[4876]: finished switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test2535machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.41 seconds)2536machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12537machine # [ 62.675346] nixos[5016]: switching to system configuration /nix/store/5jwws0a2qvyxfm3pmn7mlxqrxcwnbimx-nixos-system-machine-test2538machine # [ 62.678074] systemd[1]: Stopped target Remote File Systems.2539machine # [ 62.679838] systemd[1]: Stopped target Local File Systems.2540machine # [ 62.911489] systemd[1]: Reload requested from client PID 5016 ('.switch-to-conf') (unit backdoor.service)...2541machine # [ 62.913171] systemd[1]: Reloading...2542machine # [ 63.169757] systemd[1]: Reloading finished in 254 ms.2543machine # [ 63.235334] systemd[619]: Reexecution requested from client PID 5085 ('switch-to-confi')...2544machine # [ 63.236655] systemd[619]: Reexecuting.2545machine # [ 63.259276] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2546machine # [ 63.335081] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2547machine # [ 63.340969] systemd[619]: Reload requested from client PID 5085 ('switch-to-confi')...2548machine # [ 63.342733] systemd[619]: Reloading...2549machine # [ 63.417717] systemd[619]: Reloading finished in 73 ms.2550machine # [ 63.421927] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2551machine # [ 63.428086] systemd[619]: Stopped Run user-specific NixOS activation.2552machine # [ 63.429089] systemd[619]: Stopping Run user-specific NixOS activation...2553machine # [ 63.431825] systemd[619]: Starting Run user-specific NixOS activation...2554machine # [ 63.452651] systemd[619]: Finished Run user-specific NixOS activation.2555machine # [ 63.455938] systemd[1]: Stopped target Reactivate sysinit units.2556machine # [ 63.457343] systemd[1]: Stopping Reactivate sysinit units...2557machine # [ 63.458585] systemd[1]: Reached target Reactivate sysinit units.2558machine # [ 63.461619] dbus-broker-launch[499]: Caught SIGHUP, trigger reload.2559machine # [ 63.462564] systemd[1]: Reloading D-Bus System Message Bus...2560machine # [ 63.466796] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'...2561machine # [ 63.468611] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync'2562machine # [ 63.469609] dbus-broker-launch[499]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2563machine # [ 63.478836] systemd[1]: Reloaded D-Bus System Message Bus.2564machine # [ 63.485187] systemd[1]: Reached target Local File Systems.2565machine # [ 63.487290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2566machine # [ 63.490212] systemd[1]: Starting Create SUID/SGID Wrappers...2567machine # [ 63.492041] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2568machine # [ 63.493239] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672569machine # [ 63.495245] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2570machine # [ 63.496718] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2571machine # [ 63.497955] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2572machine # [ 63.503451] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2573machine # [ 63.504673] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2574machine # [ 63.508984] systemd[1]: Reached target Remote File Systems.2575machine # [ 63.642272] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2576machine # [ 63.644091] systemd[1]: Finished Create SUID/SGID Wrappers.2577machine # [ 63.648419] systemd[1]: Starting linger-users.service...2578machine # [ 63.678670] systemd[1]: linger-users.service: Deactivated successfully.2579machine # [ 63.680297] systemd[1]: Finished linger-users.service.2580machine # [ 63.938525] nixos[5016]: finished switching to system configuration /nix/store/5jwws0a2qvyxfm3pmn7mlxqrxcwnbimx-nixos-system-machine-test2581machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 1.51 seconds)2582(finished: subtest: dbus reloads, in 2.92 seconds)2583subtest: generators2584machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12585machine # [ 64.167942] nixos[5156]: switching to system configuration /nix/store/lb7xmvh4qhqfg3naga4rgdyyd6q31spg-nixos-system-machine-test2586machine # [ 64.171376] systemd[1]: Stopped target Remote File Systems.2587machine # [ 64.172447] systemd[1]: Stopped target Local File Systems.2588machine # [ 64.402042] systemd[1]: Reload requested from client PID 5156 ('.switch-to-conf') (unit backdoor.service)...2589machine # [ 64.403729] systemd[1]: Reloading...2590machine # [ 64.643110] systemd[1]: Reloading finished in 238 ms.2591machine # [ 64.706388] systemd[619]: Reexecution requested from client PID 5227 ('switch-to-confi')...2592machine # [ 64.709166] systemd[619]: Reexecuting.2593machine # [ 64.731150] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2594machine # [ 64.801784] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2595machine # [ 64.807877] systemd[619]: Reload requested from client PID 5227 ('switch-to-confi')...2596machine # [ 64.809370] systemd[619]: Reloading...2597machine # [ 64.879944] systemd[619]: Reloading finished in 69 ms.2598machine # [ 64.885897] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2599machine # [ 64.892066] systemd[619]: Stopped Run user-specific NixOS activation.2600machine # [ 64.894040] systemd[619]: Stopping Run user-specific NixOS activation...2601machine # [ 64.895627] systemd[619]: Starting Run user-specific NixOS activation...2602machine # [ 64.916148] systemd[619]: Finished Run user-specific NixOS activation.2603machine # [ 64.919553] systemd[1]: Stopped target Reactivate sysinit units.2604machine # [ 64.921241] systemd[1]: Stopping Reactivate sysinit units...2605machine # [ 64.923920] systemd[1]: Reached target Reactivate sysinit units.2606machine # [ 64.925356] dbus-broker-launch[499]: Caught SIGHUP, trigger reload.2607machine # [ 64.926312] systemd[1]: Reloading D-Bus System Message Bus...2608machine # [ 64.929741] dbus-broker-launch[499]: Looking up NSS user entry for 'systemd-timesync'...2609machine # [ 64.931527] dbus-broker-launch[499]: NSS returned no entry for 'systemd-timesync'2610machine # [ 64.932562] dbus-broker-launch[499]: Invalid user-name in /nix/store/fgbp1zy096lby5zjpzvrry9461vbw8sq-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2611machine # [ 64.941552] systemd[1]: Reloaded D-Bus System Message Bus.2612machine # [ 64.946061] systemd[1]: Reached target Local File Systems.2613machine # [ 64.948064] systemd[1]: Reached target Remote File Systems.2614machine # [ 64.948855] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2615machine # [ 64.952212] systemd[1]: Starting Create SUID/SGID Wrappers...2616machine # [ 64.954049] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2617machine # [ 64.955243] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672618machine # [ 64.957281] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2619machine # [ 64.958737] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2620machine # [ 64.959862] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2621machine # [ 64.965684] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2622machine # [ 64.967058] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2623machine # [ 65.097199] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2624machine # [ 65.098866] systemd[1]: Finished Create SUID/SGID Wrappers.2625machine # [ 65.102917] systemd[1]: Starting linger-users.service...2626machine # [ 65.132375] systemd[1]: linger-users.service: Deactivated successfully.2627machine # [ 65.134081] systemd[1]: Finished linger-users.service.2628machine # [ 65.392553] nixos[5156]: finished switching to system configuration /nix/store/lb7xmvh4qhqfg3naga4rgdyyd6q31spg-nixos-system-machine-test2629machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 1.45 seconds)2630machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2631machine # [ 65.440786] systemd[1]: Started simple-generated.service.2632machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.06 seconds)2633machine: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&12634machine # [ 65.640768] nixos[5305]: switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test2635machine # [ 65.643121] systemd[1]: Stopped target Remote File Systems.2636machine # [ 65.644732] systemd[1]: Stopped target Local File Systems.2637machine # [ 65.851405] systemd[1]: Reload requested from client PID 5305 ('.switch-to-conf') (unit backdoor.service)...2638machine # [ 65.853193] systemd[1]: Reloading...2639machine # [ 66.085456] systemd[1]: Reloading finished in 230 ms.2640machine # [ 66.147715] systemd[619]: Reexecution requested from client PID 5375 ('switch-to-confi')...2641machine # [ 66.150339] systemd[619]: Reexecuting.2642machine # [ 66.169175] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2643machine # [ 66.240861] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2644machine # [ 66.246140] systemd[619]: Reload requested from client PID 5375 ('switch-to-confi')...2645machine # [ 66.247396] systemd[619]: Reloading...2646machine # [ 66.319058] systemd[619]: Reloading finished in 70 ms.2647machine # [ 66.324872] systemd[619]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2648machine # [ 66.331032] systemd[619]: Stopped Run user-specific NixOS activation.2649machine # [ 66.332057] systemd[619]: Stopping Run user-specific NixOS activation...2650machine # [ 66.334691] systemd[619]: Starting Run user-specific NixOS activation...2651machine # [ 66.355044] systemd[619]: Finished Run user-specific NixOS activation.2652machine # [ 66.358339] systemd[1]: Stopped target Reactivate sysinit units.2653machine # [ 66.359632] systemd[1]: Stopping Reactivate sysinit units...2654machine # [ 66.360693] systemd[1]: Reached target Reactivate sysinit units.2655machine # [ 66.362698] systemd[1]: Reached target Remote File Systems.2656machine # [ 66.365729] systemd[1]: Starting linger-users.service...2657machine # [ 66.369962] systemd[1]: Reached target Local File Systems.2658machine # [ 66.372911] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2659machine # [ 66.375990] systemd[1]: Starting Create SUID/SGID Wrappers...2660machine # [ 66.376872] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2661machine # [ 66.377991] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672662machine # [ 66.381089] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2663machine # [ 66.382548] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2664machine # [ 66.383728] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2665machine # [ 66.389178] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2666machine # [ 66.390408] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2667machine # [ 66.432968] systemd[1]: linger-users.service: Deactivated successfully.2668machine # [ 66.435290] systemd[1]: Finished linger-users.service.2669machine # [ 66.538707] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2670machine # [ 66.540654] systemd[1]: Finished Create SUID/SGID Wrappers.2671machine # [ 66.797782] nixos[5305]: finished switching to system configuration /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test2672machine: (finished: must succeed: /nix/store/pm3qfg0v2jgs4zs4s15z925b1iygczxc-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.35 seconds)2673machine: must succeed: systemctl is-active simple-generated.service2674machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.03 seconds)2675(finished: subtest: generators, in 2.89 seconds)2676(finished: run the VM test script, in 68.36 seconds)2677test script finished in 68.41s2678cleanup2679kill QemuMachine (pid 45)2680machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/lb41b0anx1f98y9y5s9mdv97gjgsq740-python3-3.14.7/bin/python3.14)2681machine # [2026-10-19T03:01:17Z INFO virtiofsd] Client disconnected, shutting down2682machine # [2026-10-19T03:01:17Z INFO virtiofsd] Client disconnected, shutting down2683machine # [2026-10-19T03:01:17Z INFO virtiofsd] Client disconnected, shutting down2684(finished: cleanup, in 0.19 seconds)2685additionally exposed symbols:2686 machine, other,2687 vlan1,2688 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