vm-test-run-switch-test-units
checks.x86_64-linux.switchTest-units
· build #38
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.x38YInfakG', 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: bbf3c048-18c7-4359-9a66-3ad3f9bdf26f17machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # Starting virtiofs daemons...27machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)28machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether29machine # [2026-09-16T03:02:44Z INFO virtiofsd] Waiting for vhost-user socket connection...30machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)31machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether32machine # [2026-09-16T03:02:44Z INFO virtiofsd] Waiting for vhost-user socket connection...33machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] Failed to open file handle for the root node: Operation not permitted (os error 1)34machine # [2026-09-16T03:02:44Z WARN virtiofsd::passthrough] File handles do not appear safe to use, disabling file handles altogether35machine # [2026-09-16T03:02:44Z INFO virtiofsd] Waiting for vhost-user socket connection...36machine # [2026-09-16T03:02:44Z INFO virtiofsd] Client connected, servicing requests37machine # [2026-09-16T03:02:44Z INFO virtiofsd] Client connected, servicing requests38machine # [2026-09-16T03:02:44Z INFO virtiofsd] Client connected, servicing requests39machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)40machine # 41machine # 42machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0043machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...44machine # 45machine # 46machine # 47machine # 48machine # iPXE (http://ipxe.org) 00:05.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0049machine # Press Ctrl-B to configure iPXE (PCI 00:05.0)...50machine # 51machine # 52machine # Booting from ROM...53machine # Probing EDD (edd=off to disable)... ok[ 0.000000] Linux version 6.18.51 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Sep 11 09:49:46 UTC 202654machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/init regInfo=/nix/store/b4385d350yj8lslzyzbkwdsqadn1bls3-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 472408057 cycles75machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns76machine # [ 0.000005] tsc: Detected 2400.012 MHz processor77machine # [ 0.000810] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000078machine # [ 0.000837] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs79machine # [ 0.000840] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT80machine # [ 0.002730] found SMP MP-table at [mem 0x000f5450-0x000f545f]81machine # [ 0.002740] Using GB pages for direct mapping82machine # [ 0.002823] RAMDISK: [mem 0x3e41e000-0x3ffcffff]83machine # [ 0.002831] ACPI: Early table checksum verification disabled84machine # [ 0.002833] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )85machine # [ 0.002837] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)86machine # [ 0.002841] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)87machine # [ 0.002847] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)88machine # [ 0.002849] ACPI: FACS 0x000000003FFE0000 00004089machine # [ 0.002850] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)90machine # [ 0.002852] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)91machine # [ 0.002854] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)92machine # [ 0.002855] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)93machine # [ 0.002856] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]94machine # [ 0.002857] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]95machine # [ 0.002858] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]96machine # [ 0.002858] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]97machine # [ 0.002859] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]98machine # [ 0.002859] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]99machine # [ 0.002860] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]100machine # [ 0.003092] No NUMA configuration found101machine # [ 0.003093] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]102machine # [ 0.003095] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]103machine # [ 0.005518] Zone ranges:104machine # [ 0.005518] DMA [mem 0x0000000000001000-0x0000000000ffffff]105machine # [ 0.005520] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]106machine # [ 0.005521] Normal empty107machine # [ 0.005522] Device empty108machine # [ 0.005522] Movable zone start for each node109machine # [ 0.005523] Early memory node ranges110machine # [ 0.005523] node 0: [mem 0x0000000000001000-0x000000000009efff]111machine # [ 0.005524] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]112machine # [ 0.005525] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]113machine # [ 0.005546] On node 0, zone DMA: 1 pages in unavailable ranges114machine # [ 0.005818] On node 0, zone DMA: 97 pages in unavailable ranges115machine # [ 0.024228] On node 0, zone DMA32: 40 pages in unavailable ranges116machine # [ 0.024677] ACPI: PM-Timer IO Port: 0x608117machine # [ 0.024687] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])118machine # [ 0.024711] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23119machine # [ 0.024713] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)120machine # [ 0.024715] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)121machine # [ 0.024716] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)122machine # [ 0.024717] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)123machine # [ 0.024718] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)124machine # [ 0.024720] ACPI: Using ACPI (MADT) for SMP configuration information125machine # [ 0.024721] ACPI: HPET id: 0x8086a201 base: 0xfed00000126machine # [ 0.024724] TSC deadline timer available127machine # [ 0.024728] CPU topo: Max. logical packages: 1128machine # [ 0.024729] CPU topo: Max. logical dies: 1129machine # [ 0.024729] CPU topo: Max. dies per package: 1130machine # [ 0.024732] CPU topo: Max. threads per core: 1131machine # [ 0.024733] CPU topo: Num. cores per package: 1132machine # [ 0.024733] CPU topo: Num. threads per package: 1133machine # [ 0.024734] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs134machine # [ 0.024748] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()135machine # [ 0.024777] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]136machine # [ 0.024778] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]137machine # [ 0.024779] [mem 0x40000000-0xafffffff] available for PCI devices138machine # [ 0.024781] Booting paravirtualized kernel on KVM139machine # [ 0.024784] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns140machine # [ 0.029289] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1141machine # [ 0.031810] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152142machine # [ 0.031854] kvm-guest: PV spinlocks disabled, single CPU143machine # [ 0.031856] 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/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/init regInfo=/nix/store/b4385d350yj8lslzyzbkwdsqadn1bls3-closure-info/registration console=ttyS0,115200n8 console=tty0144machine # [ 0.031950] Unknown kernel command line parameters "regInfo=/nix/store/b4385d350yj8lslzyzbkwdsqadn1bls3-closure-info/registration", will be passed to user space.145machine # [ 0.031963] random: crng init done146machine # [ 0.031963] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes147machine # [ 0.031984] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)148machine # [ 0.031998] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)149machine # [ 0.032030] Fallback order for Node 0: 0150machine # [ 0.032033] Built 1 zonelists, mobility grouping on. Total pages: 262006151machine # [ 0.032034] Policy zone: DMA32152machine # [ 0.034596] mem auto-init: stack:all(zero), heap alloc:on, heap free:off153machine # [ 0.037167] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1154machine # [ 0.039409] allocated 2097152 bytes of page_ext155machine # [ 0.049023] ftrace: allocating 48736 entries in 192 pages156machine # [ 0.049025] ftrace: allocated 192 pages with 2 groups157machine # [ 0.049867] Dynamic Preempt: lazy158machine # [ 0.050041] rcu: Preemptible hierarchical RCU implementation.159machine # [ 0.050042] rcu: RCU event tracing is enabled.160machine # [ 0.050051] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.161machine # [ 0.050052] Trampoline variant of Tasks RCU enabled.162machine # [ 0.050053] Rude variant of Tasks RCU enabled.163machine # [ 0.050053] Tracing variant of Tasks RCU enabled.164machine # [ 0.050054] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.165machine # [ 0.050055] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1166machine # [ 0.050078] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.167machine # [ 0.050080] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.168machine # [ 0.050081] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.169machine # [ 0.054459] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16170machine # [ 0.054732] rcu: srcu_init: Setting srcu_struct sizes based on contention.171machine # [ 0.054738] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns172machine # [ 0.054838] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)173machine # [ 0.058384] Console: colour VGA+ 80x25174machine # [ 0.058387] printk: legacy console [tty0] enabled175machine # [ 0.088003] printk: legacy console [ttyS0] enabled176machine # [ 0.194601] ACPI: Core revision 20250807177machine # [ 0.195513] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns178machine # [ 0.197136] APIC: Switch to symmetric I/O mode setup179machine # [ 0.198151] x2apic enabled180machine # [ 0.198898] APIC: Switched APIC routing to: physical x2apic181machine # [ 0.200980] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1182machine # [ 0.201988] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns183machine # [ 0.203767] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400012)184machine # [ 0.204850] x86/cpu: User Mode Instruction Prevention (UMIP) activated185machine # [ 0.205892] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127186machine # [ 0.206765] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0187machine # [ 0.207768] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto188machine # [ 0.209766] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl189machine # [ 0.210766] Transient Scheduler Attacks: Vulnerable: No microcode190machine # [ 0.211765] Spectre V2 : Mitigation: Enhanced / Automatic IBRS191machine # [ 0.212764] Speculative Return Stack Overflow: Mitigation: Safe RET192machine # [ 0.213764] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization193machine # [ 0.215773] Spectre V2 : Enabling IBPB for BPF194machine # [ 0.216765] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier195machine # [ 0.217765] active return thunk: srso_alias_return_thunk196machine # [ 0.218786] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'197machine # [ 0.219764] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'198machine # [ 0.221558] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'199machine # [ 0.222584] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'200machine # [ 0.223591] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'201machine # [ 0.224764] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'202machine # [ 0.225764] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'203machine # [ 0.226764] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256204machine # [ 0.227764] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64205machine # [ 0.228764] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512206machine # [ 0.229764] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024207machine # [ 0.230764] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8208machine # [ 0.231764] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.209machine # [ 0.267352] Freeing SMP alternatives memory: 44K210machine # [ 0.267767] pid_max: default: 32768 minimum: 301211machine # [ 0.268883] LSM: initializing lsm=capability,landlock,yama,bpf,ima212machine # [ 0.269867] landlock: Up and running.213machine # [ 0.270765] Yama: becoming mindful.214machine # [ 0.271938] LSM support for eBPF active215machine # [ 0.272734] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)216machine # [ 0.273784] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)217machine # [ 0.276414] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)218machine # [ 0.277320] Performance Events: Fam17h+ core perfctr, AMD PMU driver.219machine # [ 0.277769] ... version: 2220machine # [ 0.278487] ... bit width: 48221machine # [ 0.278767] ... generic counters: 6222machine # [ 0.279539] ... generic bitmap: 000000000000003f223machine # [ 0.279773] ... fixed-purpose counters: 0224machine # [ 0.280510] ... fixed-purpose bitmap: 0000000000000000225machine # [ 0.280767] ... value mask: 0000ffffffffffff226machine # [ 0.281677] ... max period: 00007fffffffffff227machine # [ 0.282480] ... global_ctrl mask: 000000000000003f228machine # [ 0.282864] signal: max sigframe size: 3376229machine # [ 0.283723] rcu: Hierarchical SRCU implementation.230machine # [ 0.284416] rcu: Max phase no-delay instances is 400.231machine # [ 0.289325] smp: Bringing up secondary CPUs ...232machine # [ 0.289781] smp: Brought up 1 node, 1 CPU233machine # [ 0.290518] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)234machine # [ 0.290952] Memory: 941732K/1048024K available (17231K kernel code, 2726K rwdata, 13600K rodata, 3644K init, 2988K bss, 98884K reserved, 0K cma-reserved)235machine # [ 0.291997] devtmpfs: initialized236machine # [ 0.292800] x86/mm: Memory block size: 128MB237machine # [ 0.294527] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)238machine # [ 0.295754] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).239machine # [ 0.296854] pinctrl core: initialized pinctrl subsystem240machine # [ 0.298079] PM: RTC time: 03:02:44, date: 2026-09-16241machine # [ 0.301476] NET: Registered PF_NETLINK/PF_ROUTE protocol family242machine # [ 0.302130] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations243machine # [ 0.302782] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations244machine # [ 0.303904] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations245machine # [ 0.304777] audit: initializing netlink subsys (disabled)246machine # [ 0.306011] thermal_sys: Registered thermal governor 'fair_share'247machine # [ 0.306013] thermal_sys: Registered thermal governor 'bang_bang'248machine # [ 0.306788] thermal_sys: Registered thermal governor 'step_wise'249machine # [ 0.307785] audit: type=2000 audit(1789527765.170:1): state=initialized audit_enabled=0 res=1250machine # [ 0.309768] thermal_sys: Registered thermal governor 'user_space'251machine # [ 0.309770] thermal_sys: Registered thermal governor 'power_allocator'252machine # [ 0.310807] cpuidle: using governor menu253machine # [ 0.313038] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5254machine # [ 0.314033] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]255machine # [ 0.314770] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry256machine # [ 0.315782] PCI: Using configuration type 1 for base access257machine # [ 0.316898] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.258machine # [ 0.323969] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages259machine # [ 0.324768] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page260machine # [ 0.329768] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages261machine # [ 0.330768] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page262machine # [ 0.341176] ACPI: Added _OSI(Module Device)263machine # [ 0.341768] ACPI: Added _OSI(Processor Device)264machine # [ 0.342490] ACPI: Added _OSI(Processor Aggregator Device)265machine # [ 0.349346] ACPI: 1 ACPI AML tables successfully acquired and loaded266machine # [ 0.353187] ACPI: Interpreter enabled267machine # [ 0.353785] ACPI: PM: (supports S0 S3 S4 S5)268machine # [ 0.354554] ACPI: Using IOAPIC for interrupt routing269machine # [ 0.356839] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug270machine # [ 0.357767] PCI: Using E820 reservations for host bridge windows271machine # [ 0.360860] ACPI: Enabled 2 GPEs in block 00 to 3F272machine # [ 0.368217] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])273machine # [ 0.368774] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]274machine # [ 0.369854] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]275machine # [ 0.370889] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]276machine # [ 0.372104] PCI host bridge to bus 0000:00277machine # [ 0.372771] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]278machine # [ 0.373767] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]279machine # [ 0.374767] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]280machine # [ 0.375767] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]281machine # [ 0.376767] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]282machine # [ 0.377767] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]283machine # [ 0.378767] pci_bus 0000:00: root bus resource [bus 00-ff]284machine # [ 0.379734] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint285machine # [ 0.381179] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint286machine # [ 0.383872] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]287machine # [ 0.384779] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]288machine # [ 0.385789] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]289machine # [ 0.387082] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]290machine # [ 0.388477] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint291machine # [ 0.391812] pci 0000:00:02.0: BAR 0 [io 0xc100-0xc11f]292machine # [ 0.392715] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]293machine # [ 0.393598] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]294machine # [ 0.394689] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]295machine # [ 0.396212] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint296machine # [ 0.399746] pci 0000:00:03.0: BAR 0 [io 0xc120-0xc13f]297machine # [ 0.400503] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]298machine # [ 0.400789] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]299machine # [ 0.402311] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint300machine # [ 0.404956] pci 0000:00:04.0: BAR 0 [io 0xc000-0xc07f]301machine # [ 0.405774] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]302machine # [ 0.406788] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]303machine # [ 0.408355] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint304machine # [ 0.409809] pci 0000:00:05.0: BAR 0 [io 0xc140-0xc15f]305machine # [ 0.410723] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]306machine # [ 0.411572] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]307machine # [ 0.412702] pci 0000:00:05.0: ROM [mem 0xfeb80000-0xfebbffff pref]308machine # [ 0.414154] pci 0000:00:06.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint309machine # [ 0.415815] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]310machine # [ 0.416789] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]311machine # [ 0.418317] pci 0000:00:07.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint312machine # [ 0.419780] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]313machine # [ 0.420790] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]314machine # [ 0.422333] pci 0000:00:08.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint315machine # [ 0.423781] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]316machine # [ 0.424790] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]317machine # [ 0.426325] pci 0000:00:09.0: [1af4:105a] type 00 class 0x018000 conventional PCI endpoint318machine # [ 0.427780] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]319machine # [ 0.428789] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]320machine # [ 0.430305] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint321machine # [ 0.431789] pci 0000:00:0a.0: BAR 0 [io 0xc080-0xc0bf]322machine # [ 0.432684] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]323machine # [ 0.433563] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]324machine # [ 0.435240] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint325machine # [ 0.436774] pci 0000:00:0b.0: BAR 0 [io 0xc160-0xc17f]326machine # [ 0.437700] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]327machine # [ 0.438549] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]328machine # [ 0.440233] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint329machine # [ 0.441502] pci 0000:00:1d.0: BAR 4 [io 0xc180-0xc19f]330machine # [ 0.441963] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint331machine # [ 0.443486] pci 0000:00:1d.1: BAR 4 [io 0xc1a0-0xc1bf]332machine # [ 0.444010] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint333machine # [ 0.445400] pci 0000:00:1d.2: BAR 4 [io 0xc1c0-0xc1df]334machine # [ 0.445978] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint335machine # [ 0.448402] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]336machine # [ 0.449002] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint337machine # [ 0.450097] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO338machine # [ 0.451022] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint339machine # [ 0.452773] pci 0000:00:1f.2: BAR 4 [io 0xc1e0-0xc1ff]340machine # [ 0.453721] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]341machine # [ 0.454933] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint342machine # [ 0.456389] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]343machine # [ 0.460548] ACPI: PCI: Interrupt link LNKA configured for IRQ 10344machine # [ 0.461726] ACPI: PCI: Interrupt link LNKB configured for IRQ 10345machine # [ 0.462680] ACPI: PCI: Interrupt link LNKC configured for IRQ 11346machine # [ 0.463687] ACPI: PCI: Interrupt link LNKD configured for IRQ 11347machine # [ 0.464681] ACPI: PCI: Interrupt link LNKE configured for IRQ 10348machine # [ 0.465636] ACPI: PCI: Interrupt link LNKF configured for IRQ 10349machine # [ 0.466864] ACPI: PCI: Interrupt link LNKG configured for IRQ 11350machine # [ 0.467869] ACPI: PCI: Interrupt link LNKH configured for IRQ 11351machine # [ 0.468807] ACPI: PCI: Interrupt link GSIA configured for IRQ 16352machine # [ 0.469782] ACPI: PCI: Interrupt link GSIB configured for IRQ 17353machine # [ 0.470782] ACPI: PCI: Interrupt link GSIC configured for IRQ 18354machine # [ 0.471780] ACPI: PCI: Interrupt link GSID configured for IRQ 19355machine # [ 0.472781] ACPI: PCI: Interrupt link GSIE configured for IRQ 20356machine # [ 0.473778] ACPI: PCI: Interrupt link GSIF configured for IRQ 21357machine # [ 0.474781] ACPI: PCI: Interrupt link GSIG configured for IRQ 22358machine # [ 0.475756] ACPI: PCI: Interrupt link GSIH configured for IRQ 23359machine # [ 0.477568] iommu: Default domain type: Translated360machine # [ 0.478390] iommu: DMA domain TLB invalidation policy: lazy mode361machine # [ 0.479035] ACPI: bus type USB registered362machine # [ 0.479837] usbcore: registered new interface driver usbfs363machine # [ 0.480790] usbcore: registered new interface driver hub364machine # [ 0.481699] usbcore: registered new device driver usb365machine # [ 0.483361] NetLabel: Initializing366machine # [ 0.483768] NetLabel: domain hash size = 128367machine # [ 0.484499] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO368machine # [ 0.484817] NetLabel: unlabeled traffic allowed by default369machine # [ 0.485725] PCI: Using ACPI for IRQ routing370machine # [ 0.529753] pci 0000:00:01.0: vgaarb: setting as boot VGA device371machine # [ 0.529763] pci 0000:00:01.0: vgaarb: bridge control possible372machine # [ 0.529763] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none373machine # [ 0.529772] vgaarb: loaded374machine # [ 0.530529] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0375machine # [ 0.531373] hpet0: 3 comparators, 64-bit 100.000000 MHz counter376machine # [ 0.535855] clocksource: Switched to clocksource kvm-clock377machine # [ 0.539374] VFS: Disk quotas dquot_6.6.0378machine # [ 0.540121] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)379machine # [ 0.541522] pnp: PnP ACPI init380machine # [ 0.542352] ACPI: IRQ 4 override to edge(!), high(!)381machine # [ 0.543367] system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved382machine # [ 0.544980] pnp: PnP ACPI: found 5 devices383machine # [ 0.552511] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns384machine # [ 0.554036] clocksource: Switched to clocksource acpi_pm385machine # [ 0.555066] NET: Registered PF_INET protocol family386machine # [ 0.556165] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)387machine # [ 0.572648] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)388machine # [ 0.574205] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)389machine # [ 0.575574] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)390machine # [ 0.576969] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)391machine # [ 0.578260] TCP: Hash tables configured (established 8192 bind 8192)392machine # [ 0.579425] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)393machine # [ 0.580766] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)394machine # [ 0.581956] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)395machine # [ 0.583234] NET: Registered PF_UNIX/PF_LOCAL protocol family396machine # [ 0.584252] NET: Registered PF_XDP protocol family397machine # [ 0.585158] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]398machine # [ 0.586223] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]399machine # [ 0.587296] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]400machine # [ 0.588457] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]401machine # [ 0.589612] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]402machine # [ 0.590785] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]403machine # [ 0.592793] ACPI: \_SB_.GSIA: Enabled at IRQ 16404machine # [ 0.595436] ACPI: \_SB_.GSIB: Enabled at IRQ 17405machine # [ 0.597436] ACPI: \_SB_.GSIC: Enabled at IRQ 18406machine # [ 0.599445] ACPI: \_SB_.GSID: Enabled at IRQ 19407machine # [ 0.601177] PCI: CLS 0 bytes, default 64408machine # [ 0.602152] Trying to unpack rootfs image as initramfs...409machine # [ 0.604151] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229842dd0bd, max_idle_ns: 440795319571 ns410machine # [ 0.646498] Initialise system trusted keyrings411machine # [ 0.647502] workingset: timestamp_bits=40 max_order=18 bucket_order=0412machine # [ 0.671150] Key type asymmetric registered413machine # [ 0.671891] Asymmetric key parser 'x509' registered414machine # [ 0.672758] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)415machine # [ 0.678183] io scheduler mq-deadline registered416machine # [ 0.679022] io scheduler kyber registered417machine # [ 0.681561] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled418machine # [ 0.682875] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A419machine # [ 0.688653] Linux agpgart interface v0.103420machine # [ 0.689455] ACPI: bus type drm_connector registered421machine # [ 0.692502] usbcore: registered new interface driver usbserial_generic422machine # [ 0.693759] usbserial: USB Serial support registered for generic423machine # [ 0.697064] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.424machine # [ 0.697064] Please enable it if your BIOS has the CPPC option.425machine # [ 0.699459] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled426machine # [ 0.703162] drop_monitor: Initializing network drop monitor service427machine # [ 0.704400] NET: Registered PF_INET6 protocol family428machine # [ 0.708516] Segment Routing with IPv6429machine # [ 0.711121] In-situ OAM (IOAM) with IPv6430machine # [ 0.712219] IPI shorthand broadcast: enabled431machine # [ 0.720768] sched_clock: Marking stable (576014270, 144026025)->(796390758, -76350463)432machine # [ 0.724210] registered taskstats version 1433machine # [ 0.725240] Loading compiled-in X.509 certificates434machine # [ 0.744088] Demotion targets for Node 0: null435machine # [ 0.746100] Key type .fscrypt registered436machine # [ 0.746794] Key type fscrypt-provisioning registered437machine # [ 0.750157] ima: No TPM chip found, activating TPM-bypass!438machine # [ 0.751136] ima: Allocated hash algorithm: sha1439machine # [ 0.751975] ima: No architecture policies found440machine # [ 0.754226] PM: Magic number: 10:497:7441machine # [ 0.755852] RAS: Correctable Errors collector initialized.442machine # [ 0.764683] clk: Disabling unused clocks443machine # [ 0.768058] PM: genpd: Disabling unused power domains444machine # [ 0.928541] Freeing initrd memory: 28360K445machine # [ 0.931740] Freeing unused decrypted memory: 2028K446machine # [ 0.934356] Freeing unused kernel image (initmem) memory: 3644K447machine # [ 0.935480] Write protecting the kernel read-only data: 32768k448machine # [ 0.937374] Freeing unused kernel image (text/rodata gap) memory: 1200K449machine # [ 0.938939] Freeing unused kernel image (rodata/data gap) memory: 736K450machine # [ 0.990054] x86/mm: Checked W+X mappings: passed, no W+X pages found.451machine # [ 0.991171] Run /init as init process452machine # [ 1.001661] systemd[1]: Inserted module 'autofs4'453machine # [ 1.021815] fuse: init (API version 7.45)454machine # [ 1.029270] ACPI: \_SB_.GSIG: Enabled at IRQ 22455machine # [ 1.031848] ACPI: \_SB_.GSIH: Enabled at IRQ 23456machine # [ 1.034988] ACPI: \_SB_.GSIE: Enabled at IRQ 20457machine # [ 1.037190] ACPI: \_SB_.GSIF: Enabled at IRQ 21458machine # [ 1.042350] virtiofs virtio5: discovered new tag: nix-store459machine # [ 1.043933] virtiofs virtio5: virtio_fs_setup_dax: No cache capability460machine # [ 1.051592] virtiofs virtio6: discovered new tag: shared461machine # [ 1.053163] virtiofs virtio6: virtio_fs_setup_dax: No cache capability462machine # [ 1.056604] virtiofs virtio7: discovered new tag: xchg463machine # [ 1.058112] virtiofs virtio7: virtio_fs_setup_dax: No cache capability464machine # [ 1.078713] systemd[1]: Successfully made /usr/ read-only.465machine # [ 1.414189] systemd[1]: systemd 262-f3880de running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)466machine # [ 1.419461] systemd[1]: Detected virtualization kvm.467machine # [ 1.420381] systemd[1]: Detected architecture x86-64.468machine # [ 1.421304] systemd[1]: Running in initrd.469machine # [ 1.422428] systemd[1]: Initializing machine ID from random generator.470machine # [ 1.423710] systemd[1]: Hostname set to <machine>.471machine # [ 1.494670] systemd[1]: bpf-restrict-fs: LSM BPF program attached472machine # [ 1.530401] systemd[1]: Queued start job for default target Initrd Default Target.473machine # [ 1.534266] systemd[1]: Created slice Slice /system/modprobe.474machine # [ 1.535563] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.475machine # [ 1.537066] systemd[1]: Expecting device /dev/disk/by-label/nixos...476machine # [ 1.538232] systemd[1]: Reached target Path Units.477machine # [ 1.539172] systemd[1]: Reached target Slice Units.478machine # [ 1.542289] systemd[1]: Reached target Swaps.479machine # [ 1.543417] systemd[1]: Reached target Timer Units.480machine # [ 1.544603] systemd[1]: Listening on D-Bus System Message Bus Socket.481machine # [ 1.545909] systemd[1]: Listening on Journal Socket (/dev/log).482machine # [ 1.547290] systemd[1]: Listening on Journal Sockets.483machine # [ 1.548329] systemd[1]: Listening on udev Kernel Socket.484machine # [ 1.549315] systemd[1]: Reached target Socket Units.485machine # [ 1.551573] systemd[1]: Starting Create List of Static Device Nodes...486machine # [ 1.556121] systemd[1]: Starting Load Kernel Module configfs...487machine # [ 1.566118] systemd[1]: Starting Journal Service...488machine # [ 1.578125] systemd[1]: Starting Load Kernel Modules...489machine # [ 1.597130] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os490machine # [ 1.604124] systemd[1]: Starting Coldplug All udev Devices...491machine # [ 1.618505] systemd[1]: Finished Create List of Static Device Nodes.492machine # [ 1.625421] systemd[1]: modprobe@configfs.service: Deactivated successfully.493machine # [ 1.630724] systemd-journald[66]: Collecting audit messages is disabled.494machine # [ 1.632380] systemd[1]: Finished Load Kernel Module configfs.495machine # [ 1.637654] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config496machine # [ 1.651298] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...497machine # [ 1.655069] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.498machine # [ 1.662076] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev499machine # [ 1.702549] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.500machine # [ 1.708821] systemd[1]: Finished Load Kernel Modules.501machine # [ 1.715291] systemd[1]: Starting Apply Kernel Variables...502machine # [ 1.726119] systemd[1]: Starting Create Static Device Nodes in /dev...503machine # [ 1.748718] systemd[1]: Started Journal Service.504machine # [ 1.605707] systemd-modules-load[67]: Inserted module 'dm_mod'505machine # [ 1.609943] systemd-modules-load[67]: Inserted module 'virtio_balloon'506machine # [ 1.610937] systemd-modules-load[67]: Inserted module 'virtio_gpu'507machine # [ 1.626706] systemd[1]: Finished Apply Kernel Variables.508machine # [ 1.629123] systemd[1]: Finished Create Static Device Nodes in /dev.509machine # [ 1.630070] systemd[1]: Reached target Preparation for Local File Systems.510machine # [ 1.630941] systemd[1]: Reached target Local File Systems.511machine # [ 1.636140] systemd[1]: Starting Create System Files and Directories...512machine # [ 1.641074] systemd[1]: Starting Rule-based Manager for Device Events and Files...513machine # [ 1.667180] systemd[1]: Finished Create System Files and Directories.514machine # [ 1.693339] systemd-udevd[81]: Using default interface naming scheme 'v261'.515machine # [ 1.716597] systemd[1]: Started Rule-based Manager for Device Events and Files.516machine # [ 1.760390] systemd[1]: Finished Coldplug All udev Devices.517machine # [ 1.763083] systemd[1]: Reached target System Initialization.518machine # [ 1.764214] systemd[1]: Reached target Basic System.519machine # [ 2.066868] virtio_blk virtio2: 1/0/0 default/read/poll queues520machine # [ 2.083570] virtio_blk virtio2: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)521machine # [ 2.129747] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12522machine # [ 2.132794] serio: i8042 KBD port at 0x60,0x64 irq 1523machine # [ 2.137513] ehci-pci 0000:00:1d.7: EHCI Host Controller524machine # [ 2.138280] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1525machine # [ 2.140006] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000526machine # [ 2.146579] serio: i8042 AUX port at 0x60,0x64 irq 12527machine # [ 2.147458] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00528machine # [ 2.148594] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18529machine # [ 2.150641] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.152594] usb usb1: Product: EHCI Host Controller531machine # [ 2.153280] usb usb1: Manufacturer: Linux 6.18.51 ehci_hcd532machine # [ 2.155730] usb usb1: SerialNumber: 0000:00:1d.7533machine # [ 2.157447] hub 1-0:1.0: USB hub found534machine # [ 2.159393] hub 1-0:1.0: 6 ports detected535machine # [ 2.166678] uhci_hcd 0000:00:1d.0: UHCI Host Controller536machine # [ 2.167413] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2537machine # [ 2.178074] uhci_hcd 0000:00:1d.0: detected 2 ports538machine # [ 2.178861] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c180539machine # [ 2.188444] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18540machine # [ 2.189594] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1541machine # [ 2.065109] (udev-worker)[95]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.542machine # [ 2.211379] usb usb2: Product: UHCI Host Controller543machine # [ 2.070482] (udev-worker)[95]: Network interface NamePolicy= disabled on kernel command line.544machine # [ 2.074316] systemd[1]: Starting Virtual Console Setup...545machine # [ 2.222643] usb usb2: Manufacturer: Linux 6.18.51 uhci_hcd546machine # [ 2.234743] usb usb2: SerialNumber: 0000:00:1d.0547machine # [ 2.236633] SCSI subsystem initialized548machine # [ 2.240291] hub 2-0:1.0: USB hub found549machine # [ 2.243643] hub 2-0:1.0: 2 ports detected550machine # [ 2.269400] uhci_hcd 0000:00:1d.1: UHCI Host Controller551machine # [ 2.126761] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.552machine # [ 2.131428] systemd[1]: Finished Virtual Console Setup.553machine # [ 2.137673] systemd[1]: Found device /dev/disk/by-label/nixos.554machine # [ 2.138803] systemd[1]: Reached target Initrd Root Device.555machine # [ 2.141449] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...556machine # [ 2.290082] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3557machine # [ 2.293373] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0558machine # [ 2.296786] uhci_hcd 0000:00:1d.1: detected 2 ports559machine # [ 2.305339] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c1a0560machine # [ 2.310691] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18561machine # [ 2.311822] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1562machine # [ 2.315077] usb usb3: Product: UHCI Host Controller563machine # [ 2.315820] usb usb3: Manufacturer: Linux 6.18.51 uhci_hcd564machine # [ 2.318072] usb usb3: SerialNumber: 0000:00:1d.1565machine # [ 2.320532] hub 3-0:1.0: USB hub found566machine # [ 2.322121] hub 3-0:1.0: 2 ports detected567machine # [ 2.328236] uhci_hcd 0000:00:1d.2: UHCI Host Controller568machine # [ 2.328970] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4569machine # [ 2.335157] uhci_hcd 0000:00:1d.2: detected 2 ports570machine # [ 2.335959] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c1c0571machine # [ 2.193629] systemd-fsck[105]: nixos: clean, 12/65536 files, 13019/262144 blocks572machine # [ 2.340228] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18573machine # [ 2.201461] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.574machine # [ 2.347104] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1575machine # [ 2.351108] usb usb4: Product: UHCI Host Controller576machine # [ 2.351988] usb usb4: Manufacturer: Linux 6.18.51 uhci_hcd577machine # [ 2.359854] usb usb4: SerialNumber: 0000:00:1d.2578machine # [ 2.363472] hub 4-0:1.0: USB hub found579machine # [ 2.365174] hub 4-0:1.0: 2 ports detected580machine # [ 2.400076] usb 1-1: new high-speed USB device number 2 using ehci-pci581machine # [ 2.403785] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode582machine # [ 2.404969] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)583machine # [ 2.406924] ahci 0000:00:1f.2: flags: 64bit ncq only584machine # [ 2.411004] scsi host0: ahci585machine # [ 2.412588] scsi host1: ahci586machine # [ 2.414346] scsi host2: ahci587machine # [ 2.415990] scsi host3: ahci588machine # [ 2.417714] scsi host4: ahci589machine # [ 2.419434] scsi host5: ahci590machine # [ 2.420785] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 47 lpm-pol 1591machine # [ 2.422217] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 47 lpm-pol 1592machine # [ 2.423450] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 47 lpm-pol 1593machine # [ 2.424641] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 47 lpm-pol 1594machine # [ 2.425805] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 47 lpm-pol 1595machine # [ 2.427002] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 47 lpm-pol 1596machine # [ 2.531594] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00597machine # [ 2.534518] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10598machine # [ 2.537828] usb 1-1: Product: QEMU USB Tablet599machine # [ 2.539637] usb 1-1: Manufacturer: QEMU600machine # [ 2.541350] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1601machine # [ 2.573619] hid: raw HID events driver (C) Jiri Kosina602machine # [ 2.469857] systemd[1]: Mounting /sysroot...603machine # [ 2.735111] ata1: SATA link down (SStatus 0 SControl 300)604machine # [ 2.742525] ata5: SATA link down (SStatus 0 SControl 300)605machine # [ 2.744701] ata6: SATA link down (SStatus 0 SControl 300)606machine # [ 2.747246] ata2: SATA link down (SStatus 0 SControl 300)607machine # [ 2.749527] ata4: SATA link down (SStatus 0 SControl 300)608machine # [ 2.752107] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)609machine # [ 2.754399] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100610machine # [ 2.756621] ata3.00: applying bridge limits611machine # [ 2.758501] ata3.00: configured for UDMA/100612machine # [ 2.760881] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5613machine # [ 2.829363] usbcore: registered new interface driver usbhid614machine # [ 2.839061] usbhid: USB HID core driver615machine # [ 2.865250] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2616machine # [ 2.867206] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0617machine # [ 2.878386] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray618machine # [ 2.887987] EXT4-fs (vda): mounted filesystem bbf3c048-18c7-4359-9a66-3ad3f9bdf26f r/w with ordered data mode. Quota mode: none.619machine # [ 2.891901] cdrom: Uniform CD-ROM driver Revision: 3.20620machine # [ 2.751718] systemd[1]: Mounted /sysroot.621machine # [ 2.753177] systemd[1]: Reached target Initrd Root File System.622machine # [ 2.756706] systemd[1]: Starting Mountpoints Configured in the Real Root...623machine # [ 2.772445] systemd-sysroot-fstab-check[138]: /sysroot should be mounted in the initrd, will request daemon-reload.624machine # [ 2.778077] systemd[1]: Reload requested from client PID 138 ('systemd-sysroot') (unit initrd-parse-etc.service)...625machine # [ 2.779516] systemd[1]: Reloading...626machine # [ 2.855907] systemd[1]: Reloading finished in 78 ms.627machine # [ 2.865750] systemd-sysroot-fstab-check[138]: Requesting initrd-fs.target/start/replace...628machine # [ 2.870176] systemd-sysroot-fstab-check[138]: Requesting swap.target/start/replace...629machine # [ 2.873029] systemd[1]: initrd-parse-etc.service: Deactivated successfully.630machine # [ 2.874371] systemd[1]: Finished Mountpoints Configured in the Real Root.631machine # [ 2.876130] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.632machine # [ 3.471307] systemd[1]: Mounting /sysroot/nix/.ro-store...633machine # [ 3.478164] systemd[1]: Mounting /sysroot/nix/.rw-store...634machine # [ 3.487196] systemd[1]: Mounting /sysroot/run...635machine # [ 3.497170] systemd[1]: Mounting /sysroot/tmp/shared...636machine # [ 3.506912] systemd[1]: Mounting /sysroot/tmp/xchg...637machine # [ 3.549398] systemd[1]: Mounted /sysroot/nix/.ro-store.638machine # [ 3.550460] systemd[1]: Mounted /sysroot/nix/.rw-store.639machine # [ 3.551499] systemd[1]: Mounted /sysroot/run.640machine # [ 3.554163] systemd[1]: Mounted /sysroot/tmp/shared.641machine # [ 3.557085] systemd[1]: Mounted /sysroot/tmp/xchg.642machine # [ 3.561125] systemd[1]: Starting rw-sysroot-nix-store.service...643machine # [ 3.571771] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.644machine # [ 3.574074] systemd[1]: Finished rw-sysroot-nix-store.service.645machine # [ 3.575548] systemd[1]: Mounting /sysroot/nix/store...646machine # [ 3.597092] systemd[1]: Mounted /sysroot/nix/store.647machine # [ 3.598181] systemd[1]: Reached target Initrd File Systems.648machine # [ 3.599780] systemd[1]: Starting Find NixOS closure...649machine # [ 3.603136] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...650machine # [ 3.622423] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.651machine # [ 3.627880] systemd[1]: Finished Find NixOS closure.652machine # [ 3.628968] systemd[1]: Reached target Initrd Default Target.653machine # [ 3.630736] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...654machine # [ 3.643418] systemd[1]: Stopped target Initrd Default Target.655machine # [ 3.644906] systemd[1]: Stopped target Basic System.656machine # [ 3.645810] systemd[1]: Stopped target Initrd Root Device.657machine # [ 3.647105] systemd[1]: Stopped target Path Units.658machine # [ 3.647843] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.659machine # [ 3.648854] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.660machine # [ 3.650233] systemd[1]: Stopped target Slice Units.661machine # [ 3.651379] systemd[1]: Stopped target Socket Units.662machine # [ 3.652381] systemd[1]: Stopped target System Initialization.663machine # [ 3.654139] systemd[1]: Stopped target Swaps.664machine # [ 3.654757] systemd[1]: Stopped target Timer Units.665machine # [ 3.655458] systemd[1]: dbus.socket: Deactivated successfully.666machine # [ 3.656385] systemd[1]: Closed D-Bus System Message Bus Socket.667machine # [ 3.657217] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.668machine # [ 3.658205] systemd[1]: Stopped Find NixOS closure.669machine # [ 3.659518] systemd[1]: Starting rw-sysroot-nix-store.service...670machine # [ 3.661132] systemd[1]: systemd-sysctl.service: Deactivated successfully.671machine # [ 3.662173] systemd[1]: Stopped Apply Kernel Variables.672machine # [ 3.663180] systemd[1]: systemd-modules-load.service: Deactivated successfully.673machine # [ 3.664339] systemd[1]: Stopped Load Kernel Modules.674machine # [ 3.665946] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.675machine # [ 3.667087] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.676machine # [ 3.668258] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.677machine # [ 3.669865] systemd[1]: Stopped Create System Files and Directories.678machine # [ 3.671080] systemd[1]: Stopped target Local File Systems.679machine # [ 3.671812] systemd[1]: Stopped target Preparation for Local File Systems.680machine # [ 3.674116] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.681machine # [ 3.675076] systemd[1]: Stopped Coldplug All udev Devices.682machine # [ 3.677428] systemd[1]: Stopping Rule-based Manager for Device Events and Files...683machine # [ 3.679200] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.684machine # [ 3.680347] systemd[1]: Stopped Virtual Console Setup.685machine # [ 3.689539] systemd[1]: systemd-udevd.service: Deactivated successfully.686machine # [ 3.693171] systemd[1]: Stopped Rule-based Manager for Device Events and Files.687machine # [ 3.695232] systemd[1]: initrd-cleanup.service: Deactivated successfully.688machine # [ 3.696164] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.689machine # [ 3.701605] systemd[1]: Starting Cleanup udev Database...690machine # [ 3.702488] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.691machine # [ 3.704169] systemd[1]: Stopped Create Static Device Nodes in /dev.692machine # [ 3.705041] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.693machine # [ 3.706122] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.694machine # [ 3.708118] systemd[1]: kmod-static-nodes.service: Deactivated successfully.695machine # [ 3.709079] systemd[1]: Stopped Create List of Static Device Nodes.696machine # [ 3.710132] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.697machine # [ 3.712110] systemd[1]: Finished rw-sysroot-nix-store.service.698machine # [ 3.727613] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.699machine # [ 3.729267] systemd[1]: Finished Cleanup udev Database.700machine # [ 3.730389] systemd[1]: Reached target Switch Root.701machine # [ 3.732042] systemd[1]: Starting NixOS Activation...702machine # [ 3.798468] initrd-nixos-activation-start[191]: booting system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test703machine # [ 3.823249] initrd-nixos-activation-start[191]: running activation script...704machine # [ 4.012531] initrd-nixos-activation-start[214]: setting up /etc...705machine # [ 4.108866] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.706machine # [ 4.111071] systemd[1]: Finished NixOS Activation.707machine # [ 4.112729] systemd[1]: Starting Switch Root...708machine # [ 4.126686] systemd[1]: Switching root.709machine # [ 4.398583] systemd-journald[66]: Received SIGTERM from PID 1 (systemd).710machine # [ 4.470960] NET: Registered PF_VSOCK protocol family711machine # [ 4.828114] systemd[1]: systemd 262-f3880de 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)712machine # [ 4.832167] systemd[1]: Detected virtualization kvm.713machine # [ 4.832860] systemd[1]: Detected architecture x86-64.714machine # [ 4.833605] systemd[1]: Detected first boot.715machine # [ 4.835469] systemd[1]: Initializing machine ID from random generator.716machine # [ 4.921125] systemd[1]: bpf-restrict-fs: LSM BPF program attached717machine # [ 5.007478] systemd[1]: Applying preset policy.718machine # [ 5.168627] systemd[1]: Populated /etc with preset unit settings.719machine # [ 5.342106] systemd[1]: initrd-switch-root.service: Deactivated successfully.720machine # [ 5.343462] systemd[1]: Stopped initrd-switch-root.service.721machine # [ 5.345480] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.722machine # [ 5.347532] systemd[1]: Created slice Slice /system/getty.723machine # [ 5.348819] systemd[1]: Created slice User and Session Slice.724machine # [ 5.349740] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.725machine # [ 5.350945] systemd[1]: Started Forward Password Requests to Wall Directory Watch.726machine # [ 5.352061] systemd[1]: Expecting device /dev/hvc0...727machine # [ 5.352768] systemd[1]: Expecting device /dev/ttyS0...728machine # [ 5.353563] systemd[1]: Reached target Local Encrypted Volumes.729machine # [ 5.354392] systemd[1]: Stopped target initrd-fs.target.730machine # [ 5.355158] systemd[1]: Stopped target initrd-root-fs.target.731machine # [ 5.355950] systemd[1]: Stopped target initrd-switch-root.target.732machine # [ 5.356817] systemd[1]: Reached target Virtual Machines and Containers.733machine # [ 5.357816] systemd[1]: Reached target Path Units.734machine # [ 5.358547] systemd[1]: Reached target Remote File Systems.735machine # [ 5.359361] systemd[1]: Reached target Slice Units.736machine # [ 5.360123] systemd[1]: Reached target Swaps.737machine # [ 5.361705] systemd[1]: Listening on Query the User Interactively for a Password.738machine # [ 5.364094] systemd[1]: Listening on Process Core Dump Socket.739machine # [ 5.365757] systemd[1]: Listening on Credential Encryption/Decryption.740machine # [ 5.367431] systemd[1]: Listening on Factory Reset Management.741machine # [ 5.368438] systemd[1]: Listening on Hostname Service Socket.742machine # [ 5.382765] systemd[1]: Starting Journal Log Access Socket...743machine # [ 5.384319] systemd[1]: Listening on Journal Audit Socket.744machine # [ 5.386875] systemd[1]: Listening on Console Output Muting Service Socket.745machine # [ 5.388042] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.746machine # [ 5.389163] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os747machine # [ 5.390437] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki748machine # [ 5.393872] systemd[1]: Listening on Disk Repartitioning Service Socket.749machine # [ 5.394907] systemd[1]: Listening on udev Varlink Socket.750machine # [ 5.397097] systemd[1]: Mounting Huge Pages File System...751machine # [ 5.399326] systemd[1]: Mounting POSIX Message Queue File System...752machine # [ 5.406102] systemd[1]: Mounting Kernel Debug File System...753machine # [ 5.413234] systemd[1]: Mounting Kernel Trace File System...754machine # [ 5.422180] systemd[1]: Starting Create List of Static Device Nodes...755machine # [ 5.438079] systemd[1]: Starting Load Kernel Module configfs...756machine # [ 5.438929] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm757machine # [ 5.456954] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore758machine # [ 5.467749] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse759machine # [ 5.478128] systemd[1]: Mounting FUSE Control File System...760machine # [ 5.481638] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67761machine # [ 5.490387] systemd[1]: Starting Journal Service...762machine # [ 5.497688] systemd[1]: Starting Load Kernel Modules...763machine # [ 5.506474] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...764machine # [ 5.517946] systemd[1]: Starting Remount Root and Kernel File Systems...765machine # [ 5.524128] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os766machine # [ 5.532198] systemd[1]: Starting Coldplug All udev Devices...767machine # [ 5.544333] systemd[1]: Listening on Journal Log Access Socket.768machine # [ 5.546946] systemd-journald[285]: Collecting audit messages is enabled.769machine # [ 5.551751] systemd[1]: Mounted Huge Pages File System.770machine # [ 5.554354] systemd[1]: Mounted POSIX Message Queue File System.771machine # [ 5.558416] systemd[1]: Mounted Kernel Debug File System.772machine # [ 5.562413] systemd[1]: Mounted Kernel Trace File System.773machine # [ 5.567251] systemd[1]: Finished Create List of Static Device Nodes.774machine # [ 5.425691] systemd[1]: Queued start job for default target Multi-User System.775machine # [ 5.430441] systemd[1]: systemd-journald.service: Deactivated successfully.776machine # [ 5.576374] systemd[1]: Started Journal Service.777machine # [ 5.442177] systemd[1]: modprobe@configfs.service: Deactivated successfully.778machine # [ 5.587316] loop: module loaded779machine # [ 5.447169] systemd[1]: Finished Load Kernel Module configfs.780machine # [ 5.448607] systemd-modules-load[286]: Inserted module 'loop'781machine # [ 5.458975] systemd[1]: Finished Load Kernel Modules.782machine # [ 5.616169] EXT4-fs (vda): re-mounted bbf3c048-18c7-4359-9a66-3ad3f9bdf26f.783machine # [ 5.475680] systemd[1]: Mounting Kernel Configuration File System...784machine # [ 5.482142] systemd[1]: Starting Firewall...785machine # [ 5.490193] systemd[1]: Starting Apply Kernel Variables...786machine # [ 5.504101] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...787machine # [ 5.505130] systemd[1]: Mounted FUSE Control File System.788machine # [ 5.519745] systemd[1]: Finished Remount Root and Kernel File Systems.789machine # [ 5.520815] systemd-oomd[288]: No swap; memory pressure usage will be degraded790machine # [ 5.533076] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.791machine # [ 5.539082] systemd[1]: Listening on Disk Image Download Service Socket.792machine # [ 5.548796] systemd[1]: Starting Flush Journal to Persistent Storage...793machine # [ 5.549725] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore794machine # [ 5.553287] systemd[1]: Starting Load/Save OS Random Seed...795machine # [ 5.555287] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os796machine # [ 5.606495] systemd[1]: Finished Apply Kernel Variables.797machine # [ 5.762410] systemd-journald[285]: Received client request to flush runtime journal.798machine # [ 5.702654] systemd[1]: Mounted Kernel Configuration File System.799machine # [ 5.704845] systemd[1]: Finished Load/Save OS Random Seed.800machine # [ 5.706313] systemd[1]: Reached target First Boot Complete.801machine # [ 5.712185] systemd[1]: Finished Flush Journal to Persistent Storage.802machine # [ 5.754123] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.803machine # [ 5.757392] systemd[1]: Starting Create Static Device Nodes in /dev...804machine # [ 5.814451] systemd[1]: Finished Coldplug All udev Devices.805machine # [ 5.820246] systemd[1]: Finished Create Static Device Nodes in /dev.806machine # [ 5.821427] systemd[1]: Reached target Preparation for Local File Systems.807machine # [ 5.824569] systemd[1]: Starting Rule-based Manager for Device Events and Files...808machine # [ 5.865711] systemd-udevd[329]: Using default interface naming scheme 'v261'.809machine # [ 5.904225] systemd[1]: Started Rule-based Manager for Device Events and Files.810machine # [ 6.019337] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse811machine # [ 6.110679] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.812machine # [ 6.123238] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.813machine # [ 6.151682] (udev-worker)[348]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.814machine # [ 6.156123] (udev-worker)[348]: Network interface NamePolicy= disabled on kernel command line.815machine # [ 6.206386] systemd[1]: Mounting /run/wrappers...816machine # [ 6.244912] systemd[1]: Mounted /run/wrappers.817machine # [ 6.245644] systemd[1]: Reached target Local File Systems.818machine # [ 6.250420] systemd[1]: Listening on Boot Loader Control Service Socket.819machine # [ 6.254073] systemd[1]: Starting Create SUID/SGID Wrappers...820machine # [ 6.254943] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.821machine # [ 6.260301] systemd[1]: Starting Save Transient machine-id to Disk...822machine # [ 6.265572] systemd[1]: Starting Create System Files and Directories...823machine # [ 6.330779] systemd[1]: Condition check resulted in Virtio network device being skipped.824machine # [ 6.331988] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore825machine # [ 6.333451] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.826machine # [ 6.335412] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67827machine # [ 6.337636] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore828machine # [ 6.339469] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os829machine # [ 6.342354] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os830machine # [ 6.410272] systemd[1]: Finished Create System Files and Directories.831machine # [ 6.411430] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.832machine # [ 6.418129] systemd[1]: Finished Save Transient machine-id to Disk.833machine # [ 6.426505] systemd[1]: Starting Rebuild Journal Catalog...834machine # [ 6.433042] systemd[1]: Starting Record System Boot/Shutdown in UTMP...835machine # [ 6.501604] systemd[1]: Finished Record System Boot/Shutdown in UTMP.836machine # [ 6.528146] systemd[1]: Finished Rebuild Journal Catalog.837machine # [ 6.533679] systemd[1]: Starting Update is Completed...838machine # [ 6.718060] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3839machine # [ 6.579541] systemd[1]: Finished Update is Completed.840machine # [ 6.728907] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console841machine # [ 6.730496] systemd[1]: Finished Firewall.842machine # [ 6.782413] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.843machine # [ 6.784603] systemd[1]: Finished Create SUID/SGID Wrappers.844machine # [ 6.789354] systemd[1]: Reached target System Initialization.845machine # [ 6.793386] systemd[1]: Started Discard unused filesystem blocks once a week.846machine # [ 6.797156] systemd[1]: Started Daily Cleanup of Temporary Directories.847machine # [ 6.798254] systemd[1]: Reached target Timer Units.848machine # [ 6.799282] systemd[1]: Listening on D-Bus System Message Bus Socket.849machine # [ 6.763196] ACPI: button: Power Button [PWRF]850machine # [ 6.787095] mousedev: PS/2 mouse device common for all mice851machine # [ 6.804189] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.852machine # [ 6.805532] systemd[1]: Reached target Socket Units.853machine # [ 6.806289] systemd[1]: Reached target Basic System.854machine # [ 6.859185] rtc_cmos PNP0B00:00: RTC can wake from S4855machine # [ 6.878149] rtc_cmos PNP0B00:00: registered as rtc0856machine # [ 6.878215] rtc_cmos PNP0B00:00: setting system clock to 2026-09-16T03:02:51 UTC (1789527771)857machine # [ 6.878285] rtc_cmos PNP0B00:00: alarms up to one day, y3k, 242 bytes nvram, hpet irqs858machine # [ 6.809686] systemd[1]: Started backdoor.service.859machine # [ 6.811151] systemd[1]: Starting Import lastlog data into lastlog2 database...860machine # [ 6.812194] systemd[1]: Starting Name Service Cache Daemon (nsncd)...861machine # [ 6.813096] systemd[1]: Starting Post-Boot Actions...862machine # [ 6.879275] systemd-journald[285]: Time jumped backwards, rotating.863machine # [ 6.887382] parport_pc 00:02: reported by Plug and Play ACPI864machine # [ 6.831231] systemd[1]: Started Reset console on configuration changes.865machine # [ 6.838950] systemd[1]: Starting resolvconf update...866machine # [ 6.862847] systemd[1]: Starting D-Bus System Message Bus...867machine # [ 6.887456] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]868machine # [ 6.914924] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:06.0/virtio4/input/input4869machine # [ 6.885123] systemd[1]: Finished Post-Boot Actions.870machine # [ 6.900137] systemd[1]: Started Name Service Cache Daemon (nsncd).871machine # [ 6.902471] nsncd[495]: Sep 16 03:02:51.667 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"872machine # [ 6.906348] systemd[1]: Reached target Host and Network Name Lookups.873machine # [ 6.907913] systemd[1]: Reached target User and Group Name Lookups.874machine # connecting to host...875machine # [ 6.946080] Console: switching to colour dummy device 80x25876machine # [ 6.962318] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized877machine # [ 7.063392] [drm] Found bochs VGA, ID 0xb0c5.878machine # [ 7.063394] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.879machine # [ 6.926140] systemd[1]: Starting User Login Management...880machine: Guest shell says: b'Spawning backdoor root shell...\n'881machine: connected to guest root shell882machine # [ 7.113936] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic883machine: (connecting took 7.66 seconds)884machine # [ 7.114605] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0885machine: (finished: waiting for the VM to finish booting, in 7.85 seconds)886machine # [ 6.998298] systemd[1]: Finished Import lastlog data into lastlog2 database.887machine # [ 7.070410] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...888machine # [ 7.221115] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt889machine # [ 7.221761] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD890machine # [ 7.093948] systemd-logind[518]: New seat seat0.891machine # [ 7.277715] Console: switching to colour frame buffer device 160x50892machine # [ 7.283728] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6893machine # [ 7.162503] systemd[1]: Started User Login Management.894machine # [ 7.172377] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'895machine # [ 7.173425] dbus-broker-launch[502]: Invalid user-name in /nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"896machine # [ 7.178180] systemd[1]: Stopped target Host and Network Name Lookups.897machine # [ 7.179087] systemd[1]: Stopping Host and Network Name Lookups...898machine # [ 7.179928] systemd[1]: Stopped target User and Group Name Lookups.899machine # [ 7.181688] systemd[1]: Stopping User and Group Name Lookups...900machine # [ 7.284174] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5901machine # [ 7.331133] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device902machine # [ 7.189160] systemd[1]: Starting linger-users.service...903machine # [ 7.190372] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...904machine # [ 7.203062] systemd[1]: nscd.service: Deactivated successfully.905machine # [ 7.204269] systemd[1]: Stopped Name Service Cache Daemon (nsncd).906machine # [ 7.225103] systemd[1]: Started D-Bus System Message Bus.907machine # [ 7.233877] systemd[1]: Starting Name Service Cache Daemon (nsncd)...908machine # [ 7.263547] systemd[1]: Starting Virtual Console Setup...909machine # [ 7.266736] dbus-broker-launch[502]: Ready910machine # [ 7.299881] systemd-logind[518]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)911machine # [ 7.317962] systemd[1]: Finished resolvconf update.912machine # [ 7.327423] nsncd[588]: Sep 16 03:02:52.091 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"913machine # [ 7.339055] systemd[1]: Started Name Service Cache Daemon (nsncd).914machine # [ 7.347839] systemd-logind[518]: Watching system buttons on /dev/input/event2 (Power Button)915machine # [ 7.366142] systemd[1]: Created slice Slice /user/1001.916machine # [ 7.366893] systemd[1]: Reached target Preparation for Network.917machine # [ 7.368130] systemd[1]: Reached target Host and Network Name Lookups.918machine # [ 7.377267] systemd[1]: Reached target User and Group Name Lookups.919machine # [ 7.381932] systemd[1]: Starting DHCP Client...920machine # [ 7.395781] systemd[1]: Starting Address configuration of eth1...921machine # [ 7.396732] systemd[1]: Starting Extra networking commands....922machine # [ 7.410633] systemd[1]: Starting User Runtime Directory /run/user/1001...923machine # [ 7.422263] systemd[1]: linger-users.service: Deactivated successfully.924machine # [ 7.423307] systemd[1]: Finished linger-users.service.925machine # [ 7.424053] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.926machine # [ 7.425024] systemd[1]: Stopped Virtual Console Setup.927machine # [ 7.425703] systemd[1]: Starting Virtual Console Setup...928machine # [ 7.591740] ppdev: user-space parallel port driver929machine # [ 7.637554] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)930machine # [ 7.505263] systemd-logind[518]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)931machine # [ 7.665081] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)932machine # [ 7.566185] systemd[1]: Finished User Runtime Directory /run/user/1001.933machine # [ 7.574103] systemd[1]: Starting User Manager for UID 1001...934machine # [ 7.574940] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.935machine # [ 7.578076] systemd[1]: Stopped Virtual Console Setup.936machine # [ 7.583130] systemd[1]: Starting Virtual Console Setup...937machine # [ 7.628319] (systemd)[623]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)938machine # [ 7.644226] systemd-logind[518]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.939machine # [ 7.681268] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy940machine # [ 7.749684] network-addresses-eth1-start[611]: adding address 192.168.1.1/24... done941machine # [ 7.796116] network-addresses-eth1-start[611]: adding address 2001:db8:1::1/64... done942machine # [ 7.836189] systemd[1]: Finished Address configuration of eth1.943machine # [ 7.854376] systemd[623]: Queued start job for default target Main User Target.944machine # [ 7.857319] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy945machine # [ 7.863907] systemd[623]: Created slice User Application Slice.946machine # [ 7.864931] systemd[623]: Started Daily Cleanup of User's Temporary Directories.947machine # [ 7.871166] systemd[623]: Reached target Paths.948machine # [ 7.871865] systemd[623]: Reached target Timers.949machine # [ 7.872489] systemd[623]: Starting D-Bus User Message Bus Socket...950machine # [ 7.874132] systemd[623]: Starting Create User Files and Directories...951machine # [ 7.909586] systemd[623]: Finished Create User Files and Directories.952machine # [ 7.931154] systemd[623]: Listening on D-Bus User Message Bus Socket.953machine # [ 7.932101] systemd[623]: Reached target Sockets.954machine # [ 7.932724] systemd[623]: Reached target Basic System.955machine # [ 7.934194] systemd[1]: Started User Manager for UID 1001.956machine # [ 7.934951] systemd[623]: Starting Run user-specific NixOS activation...957machine # [ 8.086833] kvm_amd: TSC scaling supported958machine # [ 8.093169] kvm_amd: Nested Virtualization enabled959machine # [ 8.093625] kvm_amd: Nested Paging enabled960machine # [ 8.098294] kvm_amd: LBR virtualization supported961machine # [ 8.098798] kvm_amd: Virtual VMLOAD VMSAVE supported962machine # [ 8.101619] kvm_amd: Virtual GIF supported963machine # [ 8.105056] kvm_amd: Virtual NMI enabled964machine # [ 7.967481] systemd[623]: Finished Run user-specific NixOS activation.965machine # [ 7.968540] systemd[623]: Reached target Main User Target.966machine # [ 7.970134] systemd[623]: Startup finished in 293ms.967machine # [ 8.000403] systemd[1]: Finished Extra networking commands..968machine # [ 8.001474] systemd[1]: Reached target Network.969machine # [ 8.003331] systemd[1]: Starting Permit User Sessions...970machine # [ 8.006584] dhcpcd[632]: dhcpcd-10.3.2 starting971machine # [ 8.015185] dhcpcd[702]: dev: loaded udev972machine # [ 8.171557] 8021q: 802.1Q VLAN Support v1.8973machine # [ 8.172233] 8021q: adding VLAN 0 to HW filter on device eth1974machine # [ 8.179171] EDAC MC: Ver: 3.0.0975machine # [ 8.046241] systemd[1]: Finished Permit User Sessions.976machine # [ 8.048959] systemd[1]: Started Getty on tty1.977machine # [ 8.049702] systemd[1]: Reached target Login Prompts.978machine # [ 8.085976] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.979machine # [ 8.265466] cfg80211: Loading compiled-in X.509 certificates for regulatory database980machine # [ 8.277074] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'981machine # [ 8.277719] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'982machine # [ 8.279719] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2983machine # [ 8.280534] cfg80211: failed to load regulatory.db984machine # [ 8.307698] 8021q: adding VLAN 0 to HW filter on device eth0985machine # [ 8.164932] dhcpcd[702]: eth0: waiting for carrier986machine # [ 8.165829] dhcpcd[702]: eth0: carrier acquired987machine # [ 8.171046] dhcpcd[702]: DUID 00:01:00:01:32:3c:c3:5c:52:54:00:12:34:56988machine # [ 8.171973] dhcpcd[702]: eth0: IAID 00:12:34:56989machine # [ 8.172861] dhcpcd[702]: eth0: adding address fe80::5054:ff:fe12:3456990machine # [ 8.180176] systemd-vconsole-setup[625]: Configuration of first virtual console was skipped, ignoring remaining ones.991machine # [ 8.183662] systemd[1]: Finished Virtual Console Setup.992machine # [ 9.903626] dhcpcd[702]: eth0: soliciting a DHCP lease993machine # [ 10.054873] NET: Registered PF_PACKET protocol family994machine # [ 9.914831] dhcpcd[702]: eth0: offered 10.0.2.15 from 10.0.2.2995machine # [ 9.921226] dhcpcd[702]: eth0: probing address 10.0.2.15/24996machine # [ 10.420221] dhcpcd[702]: eth0: soliciting an IPv6 router997machine # [ 10.424149] dhcpcd[702]: eth0: Router Advertisement from fe80::2998machine # [ 10.426572] dhcpcd[702]: eth0: adding address fec0::5054:ff:fe12:3456/64999machine # [ 10.429274] dhcpcd[702]: eth0: adding route to fec0::/641000machine # [ 10.431422] dhcpcd[702]: eth0: adding default route via fe80::21001machine # [ 15.082364] dhcpcd[702]: eth0: leased 10.0.2.15 for 86400 seconds1002machine # [ 15.083737] dhcpcd[702]: eth0: adding route to 10.0.2.0/241003machine # [ 15.084675] dhcpcd[702]: eth0: adding default route via 10.0.2.21004machine # [ 15.140955] systemd[1]: Started DHCP Client.1005machine # [ 15.142963] systemd[1]: Reached target Multi-User System.1006machine # [ 15.143815] systemd[1]: Startup finished in 850ms (kernel) + 3.459s (initrd) + 10.832s (userspace) = 15.142s.1007machine: (finished: waiting for unit multi-user.target, in 16.62 seconds)1008machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test1009machine # Checking switch inhibitors... done1010machine # [ 15.950182] nixos[853]: switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test1011machine # activating the configuration...1012machine # [ 15.953808] systemd[1]: Stopped target Local File Systems.1013machine # [ 15.954858] systemd[1]: Stopped target Remote File Systems.1014machine # setting up /etc...1015machine # [ 16.171410] systemd[1]: Reload requested from client PID 853 ('.switch-to-conf') (unit backdoor.service)...1016machine # [ 16.172978] systemd[1]: Reloading...1017machine # [ 16.395752] systemd[1]: Reloading finished in 221 ms.1018machine # reloading user units for usertest...1019machine # [ 16.424829] systemd[623]: Created slice User Core Session Slice.1020machine # [ 16.426955] systemd[623]: Starting D-Bus User Message Bus...1021machine # [ 16.448287] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/services/org.freedesktop.import1.service'1022machine # [ 16.450957] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1023machine # [ 16.453453] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1024machine # [ 16.456085] dbus-broker-launch[923]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1025machine # [ 16.458644] dbus-broker-launch[923]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1026machine # [ 16.460529] dbus-broker-launch[923]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1027machine # [ 16.462224] systemd[623]: Started D-Bus User Message Bus.1028machine # [ 16.465529] dbus-broker-launch[923]: Ready1029machine # [ 16.504314] systemd[623]: Reexecution requested from client PID 922 ('switch-to-confi')...1030machine # [ 16.505544] systemd[623]: Reexecuting.1031machine # [ 16.523947] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1032machine # [ 16.594626] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1033machine # [ 16.599596] systemd[623]: Reload requested from client PID 922 ('switch-to-confi')...1034machine # [ 16.600869] systemd[623]: Reloading...1035machine # [ 16.671527] systemd[623]: Reloading finished in 69 ms.1036machine # [ 16.676887] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1037machine # restarting the following user units: nixos-activation.service1038machine # [ 16.683976] systemd[623]: Stopped Run user-specific NixOS activation.1039machine # [ 16.685367] systemd[623]: Stopping Run user-specific NixOS activation...1040machine # [ 16.687171] systemd[623]: Starting Run user-specific NixOS activation...1041machine # [ 16.705762] systemd[623]: Finished Run user-specific NixOS activation.1042machine # restarting sysinit-reactivation.target1043machine # [ 16.713625] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1044machine # [ 16.778419] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1045machine # [ 16.780291] systemd[1]: Reached target Reactivate sysinit units.1046machine # [ 16.783811] systemd[1]: Reached target Remote File Systems.1047machine # [ 16.785129] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1048machine # [ 16.786961] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1049machine # [ 16.790894] systemd[1]: Starting linger-users.service...1050machine # [ 16.796404] systemd[1]: Reached target Local File Systems.1051machine # [ 16.797290] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1052machine # [ 16.800090] systemd[1]: Starting Create SUID/SGID Wrappers...1053machine # [ 16.800950] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1054machine # [ 16.803219] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671055machine # [ 16.805277] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1056machine # [ 16.808587] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1057machine # [ 16.811085] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1058machine # [ 16.851081] systemd[1]: linger-users.service: Deactivated successfully.1059machine # [ 16.853069] systemd[1]: Finished linger-users.service.1060machine # [ 16.956434] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1061machine # [ 16.958271] systemd[1]: Finished Create SUID/SGID Wrappers.1062machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1063machine # [ 17.218852] nixos[853]: finished switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test1064machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test, in 1.49 seconds)1065machine: must succeed: rm /etc/NIXOS1066machine: (finished: must succeed: rm /etc/NIXOS, in 0.03 seconds)1067machine: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/z1x1n6dvkmh8cdds3qsapq9kmsa5aprg-nixos-system-other-test/bin/switch-to-configuration test1068machine # this should succeed (config: /nix/store/z1x1n6dvkmh8cdds3qsapq9kmsa5aprg-nixos-system-other-test, action: test)1069machine # Checking switch inhibitors... done1070machine # [ 17.472803] nixos[1000]: switching to system configuration /nix/store/z1x1n6dvkmh8cdds3qsapq9kmsa5aprg-nixos-system-other-test1071machine # [ 17.474984] systemd[1]: Stopped target Local File Systems.1072machine # [ 17.476770] systemd[1]: Stopped target Remote File Systems.1073machine # activating the configuration...1074machine # removing user ‘usertest’1075machine # setting up /etc...1076machine # [ 17.684880] systemd[1]: Reload requested from client PID 1000 ('.switch-to-conf') (unit backdoor.service)...1077machine # [ 17.686620] systemd[1]: Reloading...1078machine # [ 17.934879] systemd[1]: Reloading finished in 246 ms.1079machine # reloading user units for usertest...1080machine # [ 18.019506] systemd[623]: Reexecution requested from client PID 1071 ('switch-to-confi')...1081machine # [ 18.021155] systemd[623]: Reexecuting.1082machine # [ 18.040341] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1083machine # [ 18.114847] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1084machine # [ 18.120298] systemd[623]: Reload requested from client PID 1071 ('switch-to-confi')...1085machine # [ 18.121663] systemd[623]: Reloading...1086machine # [ 18.190388] systemd[623]: Reloading finished in 67 ms.1087machine # [ 18.194239] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1088machine # restarting the following user units: nixos-activation.service1089machine # [ 18.202849] systemd[623]: Stopped Run user-specific NixOS activation.1090machine # [ 18.203691] systemd[623]: Stopping Run user-specific NixOS activation...1091machine # [ 18.205227] systemd[623]: Starting Run user-specific NixOS activation...1092machine # [ 18.224419] systemd[623]: Finished Run user-specific NixOS activation.1093machine # restarting sysinit-reactivation.target1094machine # [ 18.227857] systemd[1]: Stopped target Reactivate sysinit units.1095machine # [ 18.229149] systemd[1]: Stopping Reactivate sysinit units...1096machine # [ 18.229920] systemd[1]: Reached target Reactivate sysinit units.1097machine # reloading the following units: dbus-broker.service1098machine # [ 18.233974] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.1099machine # [ 18.235069] systemd[1]: Reloading D-Bus System Message Bus...1100machine # [ 18.239547] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...1101machine # [ 18.241304] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'1102machine # [ 18.242333] dbus-broker-launch[502]: Invalid user-name in /nix/store/989rj88llw3abqz9ksq3han1a6m9nldp-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1103machine # [ 18.250331] systemd[1]: Reloaded D-Bus System Message Bus.1104machine # restarting the following units: network-addresses-eth1.service1105machine # [ 18.255101] systemd[1]: Stopping Address configuration of eth1...1106machine # [ 18.273289] network-addresses-eth1-pre-stop[1084]: deleting address 192.168.1.1/24... done1107machine # [ 18.278124] network-addresses-eth1-pre-stop[1084]: deleting address 2001:db8:1::1/64... done1108machine # [ 18.282874] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1109machine # [ 18.284438] systemd[1]: Stopped Address configuration of eth1.1110machine # [ 18.287736] systemd[1]: Starting Address configuration of eth1...1111machine # [ 18.314883] network-addresses-eth1-start[1089]: adding address 192.168.1.2/24... done1112machine # [ 18.320128] network-addresses-eth1-start[1089]: adding address 2001:db8:1::2/64... done1113machine # [ 18.326927] systemd[1]: Finished Address configuration of eth1.1114machine # [ 18.331065] systemd[1]: Reached target Local File Systems.1115machine # [ 18.331799] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1116machine # [ 18.335207] systemd[1]: Starting Create SUID/SGID Wrappers...1117machine # [ 18.336032] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1118machine # [ 18.338113] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671119machine # [ 18.340942] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1120machine # [ 18.343149] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1121machine # [ 18.345267] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1122machine # [ 18.347952] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1123machine # [ 18.349158] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1124machine # [ 18.356264] systemd[1]: Reached target Remote File Systems.1125machine # [ 18.495200] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1126machine # [ 18.496783] systemd[1]: Finished Create SUID/SGID Wrappers.1127machine # [ 18.501068] systemd[1]: Starting linger-users.service...1128machine # [ 18.521290] linger-users-start[1161]: id: ‘usertest’: no such user1129machine # [ 18.522863] linger-users-start[1159]: Removing linger for missing user usertest1130machine # [ 18.527482] systemd[1]: linger-users.service: Deactivated successfully.1131machine # [ 18.528931] systemd[1]: Finished linger-users.service.1132machine # [ 18.785978] nixos[1000]: finished switching to system configuration /nix/store/z1x1n6dvkmh8cdds3qsapq9kmsa5aprg-nixos-system-other-test1133machine: (finished: must succeed: /nix/store/yidcpw0fsqryxdbi00sgaskaw7wb9pps-stderr-runner /nix/store/z1x1n6dvkmh8cdds3qsapq9kmsa5aprg-nixos-system-other-test/bin/switch-to-configuration test, in 1.54 seconds)1134machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&11135machine # [ 18.999725] nixos[1167]: switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test1136machine # [ 19.001900] systemd[1]: Stopped target Remote File Systems.1137machine # [ 19.003526] systemd[1]: Stopped target Local File Systems.1138machine # [ 19.212933] systemd[1]: Reload requested from client PID 1167 ('.switch-to-conf') (unit backdoor.service)...1139machine # [ 19.214569] systemd[1]: Reloading...1140machine # [ 19.450675] systemd[1]: Reloading finished in 234 ms.1141machine # [ 19.511197] systemd[623]: Reexecution requested from client PID 1236 ('switch-to-confi')...1142machine # [ 19.512970] systemd[623]: Reexecuting.1143machine # [ 19.534227] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1144machine # [ 19.608367] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1145machine # [ 19.613192] systemd[623]: Reload requested from client PID 1236 ('switch-to-confi')...1146machine # [ 19.614969] systemd[623]: Reloading...1147machine # [ 19.688712] systemd[623]: Reloading finished in 73 ms.1148machine # [ 19.694838] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1149machine # [ 19.701315] systemd[623]: Stopped Run user-specific NixOS activation.1150machine # [ 19.703092] systemd[623]: Stopping Run user-specific NixOS activation...1151machine # [ 19.704710] systemd[623]: Starting Run user-specific NixOS activation...1152machine # [ 19.721938] systemd[623]: Finished Run user-specific NixOS activation.1153machine # [ 19.724969] systemd[1]: Stopped target Reactivate sysinit units.1154machine # [ 19.726625] systemd[1]: Stopping Reactivate sysinit units...1155machine # [ 19.729755] systemd[1]: Reached target Reactivate sysinit units.1156machine # [ 19.731148] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.1157machine # [ 19.732183] systemd[1]: Reloading D-Bus System Message Bus...1158machine # [ 19.735101] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...1159machine # [ 19.736824] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'1160machine # [ 19.737709] dbus-broker-launch[502]: Invalid user-name in /nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1161machine # [ 19.747992] systemd[1]: Reloaded D-Bus System Message Bus.1162machine # [ 19.752572] systemd[1]: Stopping Address configuration of eth1...1163machine # [ 19.772762] network-addresses-eth1-pre-stop[1248]: deleting address 192.168.1.2/24... done1164machine # [ 19.778573] network-addresses-eth1-pre-stop[1248]: deleting address 2001:db8:1::2/64... done1165machine # [ 19.784506] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1166machine # [ 19.786294] systemd[1]: Stopped Address configuration of eth1.1167machine # [ 19.789882] systemd[1]: Starting Address configuration of eth1...1168machine # [ 19.819831] network-addresses-eth1-start[1253]: adding address 192.168.1.1/24... done1169machine # [ 19.825169] network-addresses-eth1-start[1253]: adding address 2001:db8:1::1/64... done1170machine # [ 19.831936] systemd[1]: Finished Address configuration of eth1.1171machine # [ 19.834970] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1172machine # [ 19.836556] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1173machine # [ 19.838914] systemd[1]: Reached target Local File Systems.1174machine # [ 19.841872] systemd[1]: Reached target Remote File Systems.1175machine # [ 19.844633] systemd[1]: Starting linger-users.service...1176machine # [ 19.846750] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1177machine # [ 19.851082] systemd[1]: Starting Create SUID/SGID Wrappers...1178machine # [ 19.851821] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1179machine # [ 19.855433] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671180machine # [ 19.857371] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1181machine # [ 19.858789] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1182machine # [ 19.859865] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1183machine # [ 19.894961] systemd[1]: linger-users.service: Deactivated successfully.1184machine # [ 19.896174] systemd[1]: Finished linger-users.service.1185machine # [ 20.006224] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1186machine # [ 20.007781] systemd[1]: Finished Create SUID/SGID Wrappers.1187machine # [ 20.263217] nixos[1167]: finished switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test1188machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.47 seconds)1189subtest: socket-activated services1190machine: must fail: [ -S /run/test.sock ]1191machine: (finished: must fail: [ -S /run/test.sock ], in 0.01 seconds)1192machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11193machine # [ 20.467897] nixos[1325]: switching to system configuration /nix/store/i7gygqi90vjrlbjy6j3p681xsg28kn7z-nixos-system-machine-test1194machine # [ 20.469975] systemd[1]: Stopped target Remote File Systems.1195machine # [ 20.471486] systemd[1]: Stopped target Local File Systems.1196machine # [ 20.701390] systemd[1]: Reload requested from client PID 1325 ('.switch-to-conf') (unit backdoor.service)...1197machine # [ 20.703131] systemd[1]: Reloading...1198machine # [ 20.945335] systemd[1]: Reloading finished in 240 ms.1199machine # [ 21.016503] systemd[623]: Reexecution requested from client PID 1394 ('switch-to-confi')...1200machine # [ 21.017953] systemd[623]: Reexecuting.1201machine # [ 21.036762] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1202machine # [ 21.104864] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1203machine # [ 21.110305] systemd[623]: Reload requested from client PID 1394 ('switch-to-confi')...1204machine # [ 21.111619] systemd[623]: Reloading...1205machine # [ 21.182045] systemd[623]: Reloading finished in 69 ms.1206machine # [ 21.187829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1207machine # [ 21.194826] systemd[623]: Stopped Run user-specific NixOS activation.1208machine # [ 21.195674] systemd[623]: Stopping Run user-specific NixOS activation...1209machine # [ 21.197222] systemd[623]: Starting Run user-specific NixOS activation...1210machine # [ 21.216377] systemd[623]: Finished Run user-specific NixOS activation.1211machine # [ 21.219383] systemd[1]: Stopped target Reactivate sysinit units.1212machine # [ 21.220523] systemd[1]: Stopping Reactivate sysinit units...1213machine # [ 21.221512] systemd[1]: Reached target Reactivate sysinit units.1214machine # [ 21.225720] systemd[1]: Reached target Remote File Systems.1215machine # [ 21.228256] systemd[1]: Starting linger-users.service...1216machine # [ 21.234941] systemd[1]: Reached target Local File Systems.1217machine # [ 21.235712] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1218machine # [ 21.238649] systemd[1]: Starting Create SUID/SGID Wrappers...1219machine # [ 21.239468] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1220machine # [ 21.240571] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671221machine # [ 21.244062] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1222machine # [ 21.245424] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1223machine # [ 21.246521] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1224machine # [ 21.251677] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1225machine # [ 21.252843] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1226machine # [ 21.290074] systemd[1]: linger-users.service: Deactivated successfully.1227machine # [ 21.291887] systemd[1]: Finished linger-users.service.1228machine # [ 21.379973] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1229machine # [ 21.381594] systemd[1]: Finished Create SUID/SGID Wrappers.1230machine # [ 21.383789] systemd[1]: Listening on socket-activated.socket.1231machine # [ 21.640986] nixos[1325]: finished switching to system configuration /nix/store/i7gygqi90vjrlbjy6j3p681xsg28kn7z-nixos-system-machine-test1232machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.37 seconds)1233machine: must succeed: [ -S /run/test.sock ]1234machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1235machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11236machine # [ 21.885706] nixos[1469]: switching to system configuration /nix/store/p3iz5x8dv0dh9znqr62z2mjlk64v579g-nixos-system-machine-test1237machine # [ 21.887828] systemd[1]: Stopped target Local File Systems.1238machine # [ 21.889439] systemd[1]: Stopped target Remote File Systems.1239machine # [ 22.113366] systemd[1]: Reload requested from client PID 1469 ('.switch-to-conf') (unit backdoor.service)...1240machine # [ 22.114957] systemd[1]: Reloading...1241machine # [ 22.360955] systemd[1]: Reloading finished in 244 ms.1242machine # [ 22.422088] systemd[623]: Reexecution requested from client PID 1538 ('switch-to-confi')...1243machine # [ 22.423379] systemd[623]: Reexecuting.1244machine # [ 22.443052] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1245machine # [ 22.513430] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1246machine # [ 22.518347] systemd[623]: Reload requested from client PID 1538 ('switch-to-confi')...1247machine # [ 22.520054] systemd[623]: Reloading...1248machine # [ 22.590558] systemd[623]: Reloading finished in 69 ms.1249machine # [ 22.594829] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1250machine # [ 22.601060] systemd[623]: Stopped Run user-specific NixOS activation.1251machine # [ 22.601927] systemd[623]: Stopping Run user-specific NixOS activation...1252machine # [ 22.604175] systemd[623]: Starting Run user-specific NixOS activation...1253machine # [ 22.622952] systemd[623]: Finished Run user-specific NixOS activation.1254machine # [ 22.626061] systemd[1]: Stopped target Reactivate sysinit units.1255machine # [ 22.627455] systemd[1]: Stopping Reactivate sysinit units...1256machine # [ 22.628405] systemd[1]: Reached target Reactivate sysinit units.1257machine # [ 22.633103] systemd[1]: Reached target Remote File Systems.1258machine # [ 22.635831] systemd[1]: Starting linger-users.service...1259machine # [ 22.638042] systemd[1]: Reached target Local File Systems.1260machine # [ 22.640093] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1261machine # [ 22.644359] systemd[1]: Starting Create SUID/SGID Wrappers...1262machine # [ 22.645477] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1263machine # [ 22.646628] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671264machine # [ 22.648551] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1265machine # [ 22.649953] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 22.651143] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1267machine # [ 22.661160] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1268machine # [ 22.662656] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1269machine # [ 22.697710] systemd[1]: linger-users.service: Deactivated successfully.1270machine # [ 22.699221] systemd[1]: Finished linger-users.service.1271machine # [ 22.799837] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1272machine # [ 22.801697] systemd[1]: Finished Create SUID/SGID Wrappers.1273machine # [ 23.059328] nixos[1469]: finished switching to system configuration /nix/store/p3iz5x8dv0dh9znqr62z2mjlk64v579g-nixos-system-machine-test1274machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.40 seconds)1275machine: must succeed: [ -S /run/test.sock ]1276machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1277machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1278machine # [ 23.118599] systemd[1]: Started A socket-activated service.1279machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.62 seconds)1280machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11281machine # [ 23.894175] nixos[1616]: switching to system configuration /nix/store/i7gygqi90vjrlbjy6j3p681xsg28kn7z-nixos-system-machine-test1282machine # [ 23.897322] systemd[1]: Stopped target Local File Systems.1283machine # [ 23.898234] systemd[1]: Stopped target Remote File Systems.1284machine # [ 24.134984] systemd[1]: Reload requested from client PID 1616 ('.switch-to-conf') (unit backdoor.service)...1285machine # [ 24.136632] systemd[1]: Reloading...1286machine # [ 24.367791] systemd[1]: Reloading finished in 230 ms.1287machine # [ 24.430155] systemd[623]: Reexecution requested from client PID 1687 ('switch-to-confi')...1288machine # [ 24.432580] systemd[623]: Reexecuting.1289machine # [ 24.452068] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1290machine # [ 24.523242] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1291machine # [ 24.528330] systemd[623]: Reload requested from client PID 1687 ('switch-to-confi')...1292machine # [ 24.530117] systemd[623]: Reloading...1293machine # [ 24.600511] systemd[623]: Reloading finished in 70 ms.1294machine # [ 24.604853] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1295machine # [ 24.610953] systemd[623]: Stopped Run user-specific NixOS activation.1296machine # [ 24.612088] systemd[623]: Stopping Run user-specific NixOS activation...1297machine # [ 24.614580] systemd[623]: Starting Run user-specific NixOS activation...1298machine # [ 24.633065] systemd[623]: Finished Run user-specific NixOS activation.1299machine # [ 24.635940] systemd[1]: Stopped target Reactivate sysinit units.1300machine # [ 24.637211] systemd[1]: Stopping Reactivate sysinit units...1301machine # [ 24.637967] systemd[1]: Reached target Reactivate sysinit units.1302machine # [ 24.641645] systemd[1]: Stopping A socket-activated service...1303machine # [ 24.643706] systemd[1]: socket-activated.service: Deactivated successfully.1304machine # [ 24.644710] systemd[1]: Stopped A socket-activated service.1305machine # [ 24.647636] systemd[1]: Started A socket-activated service.1306machine # [ 24.652575] systemd[1]: Reached target Remote File Systems.1307machine # [ 24.654126] systemd[1]: Reached target Local File Systems.1308machine # [ 24.657092] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1309machine # [ 24.661166] systemd[1]: Starting Create SUID/SGID Wrappers...1310machine # [ 24.661945] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1311machine # [ 24.663130] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671312machine # [ 24.665071] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1313machine # [ 24.666486] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 24.667589] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1315machine # [ 24.679525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1316machine # [ 24.681248] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1317machine # [ 24.833280] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1318machine # [ 24.835880] systemd[1]: Finished Create SUID/SGID Wrappers.1319machine # [ 24.838939] systemd[1]: Starting linger-users.service...1320machine # [ 24.865954] systemd[1]: linger-users.service: Deactivated successfully.1321machine # [ 24.867567] systemd[1]: Finished linger-users.service.1322machine # [ 25.124882] nixos[1616]: finished switching to system configuration /nix/store/i7gygqi90vjrlbjy6j3p681xsg28kn7z-nixos-system-machine-test1323machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 1.42 seconds)1324machine: must succeed: [ -S /run/test.sock ]1325machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1326machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1327machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1328machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11329machine # [ 25.916441] nixos[1766]: switching to system configuration /nix/store/cdls3vbjv5l695zygqzc323yf0wdwjij-nixos-system-machine-test1330machine # [ 25.920746] systemd[1]: Stopping A socket-activated service...1331machine # [ 25.922932] systemd[1]: socket-activated.service: Deactivated successfully.1332machine # [ 25.924359] systemd[1]: Stopped A socket-activated service.1333machine # [ 25.926805] systemd[1]: Stopped target Local File Systems.1334machine # [ 25.928205] systemd[1]: Stopped target Remote File Systems.1335machine # [ 25.929722] systemd[1]: socket-activated.socket: Deactivated successfully.1336machine # [ 25.930864] systemd[1]: Closed socket-activated.socket.1337machine # [ 26.153405] systemd[1]: Reload requested from client PID 1766 ('.switch-to-conf') (unit backdoor.service)...1338machine # [ 26.155573] systemd[1]: Reloading...1339machine # [ 26.397910] systemd[1]: Reloading finished in 240 ms.1340machine # [ 26.450777] systemd[623]: Reexecution requested from client PID 1836 ('switch-to-confi')...1341machine # [ 26.453098] systemd[623]: Reexecuting.1342machine # [ 26.471262] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1343machine # [ 26.540803] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1344machine # [ 26.545739] systemd[623]: Reload requested from client PID 1836 ('switch-to-confi')...1345machine # [ 26.548043] systemd[623]: Reloading...1346machine # [ 26.614242] systemd[623]: Reloading finished in 66 ms.1347machine # [ 26.619849] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1348machine # [ 26.626209] systemd[623]: Stopped Run user-specific NixOS activation.1349machine # [ 26.628065] systemd[623]: Stopping Run user-specific NixOS activation...1350machine # [ 26.629783] systemd[623]: Starting Run user-specific NixOS activation...1351machine # [ 26.649571] systemd[623]: Finished Run user-specific NixOS activation.1352machine # [ 26.652334] systemd[1]: Stopped target Reactivate sysinit units.1353machine # [ 26.653517] systemd[1]: Stopping Reactivate sysinit units...1354machine # [ 26.654658] systemd[1]: Reached target Reactivate sysinit units.1355machine # [ 26.657809] systemd[1]: Listening on socket-activated.socket.1356machine # [ 26.660986] systemd[1]: Reached target Remote File Systems.1357machine # [ 26.665801] systemd[1]: Reached target Local File Systems.1358machine # [ 26.667975] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1359machine # [ 26.670989] systemd[1]: Starting Create SUID/SGID Wrappers...1360machine # [ 26.671770] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1361machine # [ 26.673723] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671362machine # [ 26.676615] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1363machine # [ 26.679114] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 26.680803] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1365machine # [ 26.683871] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1366machine # [ 26.685445] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1367machine # [ 26.811930] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1368machine # [ 26.813522] systemd[1]: Finished Create SUID/SGID Wrappers.1369machine # [ 26.817112] systemd[1]: Starting linger-users.service...1370machine # [ 26.844417] systemd[1]: linger-users.service: Deactivated successfully.1371machine # [ 26.846080] systemd[1]: Finished linger-users.service.1372machine # [ 27.103529] nixos[1766]: finished switching to system configuration /nix/store/cdls3vbjv5l695zygqzc323yf0wdwjij-nixos-system-machine-test1373machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1374machine: must succeed: [ -S /run/test.sock ]1375machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1376machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1377machine # [ 27.161769] systemd[1]: Started A socket-activated service.1378machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.57 seconds)1379machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11380machine # [ 27.910562] nixos[1915]: switching to system configuration /nix/store/ib4kpsvy88m66jvhs6cymfdpsdjjc03l-nixos-system-machine-test1381machine # [ 27.912816] systemd[1]: Stopped target Remote File Systems.1382machine # [ 27.914437] systemd[1]: Stopped target Local File Systems.1383machine # [ 28.139343] systemd[1]: Reload requested from client PID 1915 ('.switch-to-conf') (unit backdoor.service)...1384machine # [ 28.140972] systemd[1]: Reloading...1385machine # [ 28.418938] systemd[1]: Reloading finished in 276 ms.1386machine # [ 28.481079] systemd[623]: Reexecution requested from client PID 1985 ('switch-to-confi')...1387machine # [ 28.482324] systemd[623]: Reexecuting.1388machine # [ 28.502065] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1389machine # [ 28.573417] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1390machine # [ 28.578396] systemd[623]: Reload requested from client PID 1985 ('switch-to-confi')...1391machine # [ 28.580702] systemd[623]: Reloading...1392machine # [ 28.651128] systemd[623]: Reloading finished in 70 ms.1393machine # [ 28.656825] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1394machine # [ 28.663090] systemd[623]: Stopped Run user-specific NixOS activation.1395machine # [ 28.664065] systemd[623]: Stopping Run user-specific NixOS activation...1396machine # [ 28.666616] systemd[623]: Starting Run user-specific NixOS activation...1397machine # [ 28.684851] systemd[623]: Finished Run user-specific NixOS activation.1398machine # [ 28.687711] systemd[1]: Stopped target Reactivate sysinit units.1399machine # [ 28.689259] systemd[1]: Stopping Reactivate sysinit units...1400machine # [ 28.690365] systemd[1]: Reached target Reactivate sysinit units.1401machine # [ 28.692643] systemd[1]: Reloading A socket-activated service...1402machine # [ 28.706109] systemd[1]: Reloaded A socket-activated service.1403machine # [ 28.708281] systemd[1]: Reached target Remote File Systems.1404machine # [ 28.709912] systemd[1]: Reached target Local File Systems.1405machine # [ 28.714217] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1406machine # [ 28.717446] systemd[1]: Starting Create SUID/SGID Wrappers...1407machine # [ 28.719448] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1408machine # [ 28.721543] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671409machine # [ 28.724088] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1410machine # [ 28.726786] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1411machine # [ 28.729304] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1412machine # [ 28.730475] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1413machine # [ 28.733576] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1414machine # [ 28.858425] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1415machine # [ 28.862099] systemd[1]: Finished Create SUID/SGID Wrappers.1416machine # [ 28.863878] systemd[1]: Starting linger-users.service...1417machine # [ 28.890084] systemd[1]: linger-users.service: Deactivated successfully.1418machine # [ 28.892286] systemd[1]: Finished linger-users.service.1419machine # [ 29.148542] nixos[1915]: finished switching to system configuration /nix/store/ib4kpsvy88m66jvhs6cymfdpsdjjc03l-nixos-system-machine-test1420machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 1.45 seconds)1421machine: must succeed: [ -S /run/test.sock ]1422machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1423machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1424machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1425machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11426machine # [ 29.908369] nixos[2064]: switching to system configuration /nix/store/k64d6cd4yjnw6x4yvs18mhk7qahq5pny-nixos-system-machine-test1427machine # [ 29.910768] systemd[1]: Stopped target Remote File Systems.1428machine # [ 29.912951] systemd[1]: socket-activated.socket: Deactivated successfully.1429machine # [ 29.914509] systemd[1]: Closed socket-activated.socket.1430machine # [ 29.915499] systemd[1]: Stopped target Local File Systems.1431machine # [ 30.139197] systemd[1]: Reload requested from client PID 2064 ('.switch-to-conf') (unit backdoor.service)...1432machine # [ 30.140813] systemd[1]: Reloading...1433machine # [ 30.396409] systemd[1]: Reloading finished in 254 ms.1434machine # [ 30.458284] systemd[623]: Reexecution requested from client PID 2134 ('switch-to-confi')...1435machine # [ 30.460129] systemd[623]: Reexecuting.1436machine # [ 30.480969] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 30.581247] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1438machine # [ 30.585638] systemd[623]: Reload requested from client PID 2134 ('switch-to-confi')...1439machine # [ 30.587390] systemd[623]: Reloading...1440machine # [ 30.661887] systemd[623]: Reloading finished in 73 ms.1441machine # [ 30.666827] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1442machine # [ 30.673520] systemd[623]: Stopped Run user-specific NixOS activation.1443machine # [ 30.674492] systemd[623]: Stopping Run user-specific NixOS activation...1444machine # [ 30.677029] systemd[623]: Starting Run user-specific NixOS activation...1445machine # [ 30.695886] systemd[623]: Finished Run user-specific NixOS activation.1446machine # [ 30.698701] systemd[1]: Stopped target Reactivate sysinit units.1447machine # [ 30.699991] systemd[1]: Stopping Reactivate sysinit units...1448machine # [ 30.701194] systemd[1]: Reached target Reactivate sysinit units.1449machine # [ 30.704498] systemd[1]: Stopping A socket-activated service...1450machine # [ 30.706616] systemd[1]: socket-activated.service: Deactivated successfully.1451machine # [ 30.707610] systemd[1]: Stopped A socket-activated service.1452machine # [ 30.710826] systemd[1]: Started A socket-activated service.1453machine # [ 30.716069] systemd[1]: Reached target Remote File Systems.1454machine # [ 30.717947] systemd[1]: Starting linger-users.service...1455machine # [ 30.721897] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1456machine # [ 30.724472] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1457machine # [ 30.733064] systemd[1]: Reached target Local File Systems.1458machine # [ 30.738082] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1459machine # [ 30.744879] systemd[1]: Starting Create SUID/SGID Wrappers...1460machine # [ 30.745712] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1461machine # [ 30.746860] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671462machine # [ 30.748733] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1463machine # [ 30.750150] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1464machine # [ 30.751272] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1465machine # [ 30.797992] systemd[1]: linger-users.service: Deactivated successfully.1466machine # [ 30.800950] systemd[1]: Finished linger-users.service.1467machine # [ 30.917699] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1468machine # [ 30.920464] systemd[1]: Finished Create SUID/SGID Wrappers.1469machine # [ 31.177215] nixos[2064]: finished switching to system configuration /nix/store/k64d6cd4yjnw6x4yvs18mhk7qahq5pny-nixos-system-machine-test1470machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 1.48 seconds)1471machine: must succeed: systemctl is-active socket-activated.service1472machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.03 seconds)1473machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11474machine # [ 31.408142] nixos[2211]: switching to system configuration /nix/store/cdls3vbjv5l695zygqzc323yf0wdwjij-nixos-system-machine-test1475machine # [ 31.410237] systemd[1]: Stopped target Remote File Systems.1476machine # [ 31.411798] systemd[1]: Stopped target Local File Systems.1477machine # [ 31.415504] systemd[1]: Stopping A socket-activated service...1478machine # [ 31.417610] systemd[1]: socket-activated.service: Deactivated successfully.1479machine # [ 31.418585] systemd[1]: Stopped A socket-activated service.1480machine # [ 31.621798] systemd[1]: Reload requested from client PID 2211 ('.switch-to-conf') (unit backdoor.service)...1481machine # [ 31.623442] systemd[1]: Reloading...1482machine # [ 31.851440] systemd[1]: Reloading finished in 226 ms.1483machine # [ 31.908731] systemd[623]: Reexecution requested from client PID 2281 ('switch-to-confi')...1484machine # [ 31.911116] systemd[623]: Reexecuting.1485machine # [ 31.931039] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1486machine # [ 32.001926] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1487machine # [ 32.007845] systemd[623]: Reload requested from client PID 2281 ('switch-to-confi')...1488machine # [ 32.009320] systemd[623]: Reloading...1489machine # [ 32.081932] systemd[623]: Reloading finished in 70 ms.1490machine # [ 32.087842] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1491machine # [ 32.094762] systemd[623]: Stopped Run user-specific NixOS activation.1492machine # [ 32.095616] systemd[623]: Stopping Run user-specific NixOS activation...1493machine # [ 32.097259] systemd[623]: Starting Run user-specific NixOS activation...1494machine # [ 32.116559] systemd[623]: Finished Run user-specific NixOS activation.1495machine # [ 32.119630] systemd[1]: Stopped target Reactivate sysinit units.1496machine # [ 32.121289] systemd[1]: Stopping Reactivate sysinit units...1497machine # [ 32.122468] systemd[1]: Reached target Reactivate sysinit units.1498machine # [ 32.123487] systemd[1]: Reached target Remote File Systems.1499machine # [ 32.124648] systemd[1]: Reached target Local File Systems.1500machine # [ 32.129072] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1501machine # [ 32.134079] systemd[1]: Starting Create SUID/SGID Wrappers...1502machine # [ 32.134831] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1503machine # [ 32.135907] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671504machine # [ 32.137781] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1505machine # [ 32.139144] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 32.140241] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 32.147766] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 32.149137] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1509machine # [ 32.270531] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1510machine # [ 32.272128] systemd[1]: Finished Create SUID/SGID Wrappers.1511machine # [ 32.274522] systemd[1]: Listening on socket-activated.socket.1512machine # [ 32.277520] systemd[1]: Starting linger-users.service...1513machine # [ 32.304219] systemd[1]: linger-users.service: Deactivated successfully.1514machine # [ 32.305662] systemd[1]: Finished linger-users.service.1515machine # [ 32.563416] nixos[2211]: finished switching to system configuration /nix/store/cdls3vbjv5l695zygqzc323yf0wdwjij-nixos-system-machine-test1516machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 1.36 seconds)1517machine: must succeed: [ -S /run/test.sock ]1518machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1519machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1520machine # [ 32.620132] systemd[1]: Started A socket-activated service.1521machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.57 seconds)1522(finished: subtest: socket-activated services, in 12.90 seconds)1523subtest: socket-activated services with Accept=yes1524machine: must fail: [ -S /run/accept-test.sock ]1525machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.01 seconds)1526machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11527machine # [ 33.378380] nixos[2362]: switching to system configuration /nix/store/982xb2vdixgmg50wiskz9wvy7mxzfapv-nixos-system-machine-test1528machine # [ 33.381402] systemd[1]: socket-activated.socket: Deactivated successfully.1529machine # [ 33.382504] systemd[1]: Closed socket-activated.socket.1530machine # [ 33.383663] systemd[1]: Stopped target Local File Systems.1531machine # [ 33.387290] systemd[1]: Stopping A socket-activated service...1532machine # [ 33.389298] systemd[1]: socket-activated.service: Deactivated successfully.1533machine # [ 33.390734] systemd[1]: Stopped A socket-activated service.1534machine # [ 33.392514] systemd[1]: Stopped target Remote File Systems.1535machine # [ 33.621556] systemd[1]: Reload requested from client PID 2362 ('.switch-to-conf') (unit backdoor.service)...1536machine # [ 33.623247] systemd[1]: Reloading...1537machine # [ 33.877967] systemd[1]: Reloading finished in 252 ms.1538machine # [ 33.964109] systemd[623]: Reexecution requested from client PID 2432 ('switch-to-confi')...1539machine # [ 33.965352] systemd[623]: Reexecuting.1540machine # [ 33.986217] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1541machine # [ 34.057037] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1542machine # [ 34.063160] systemd[623]: Reload requested from client PID 2432 ('switch-to-confi')...1543machine # [ 34.064533] systemd[623]: Reloading...1544machine # [ 34.139611] systemd[623]: Reloading finished in 73 ms.1545machine # [ 34.146845] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1546machine # [ 34.153962] systemd[623]: Stopped Run user-specific NixOS activation.1547machine # [ 34.155956] systemd[623]: Stopping Run user-specific NixOS activation...1548machine # [ 34.157578] systemd[623]: Starting Run user-specific NixOS activation...1549machine # [ 34.177901] systemd[623]: Finished Run user-specific NixOS activation.1550machine # [ 34.180796] systemd[1]: Stopped target Reactivate sysinit units.1551machine # [ 34.181970] systemd[1]: Stopping Reactivate sysinit units...1552machine # [ 34.182977] systemd[1]: Reached target Reactivate sysinit units.1553machine # [ 34.184468] systemd[1]: Reached target Remote File Systems.1554machine # [ 34.188923] systemd[1]: Listening on accept-socket.socket.1555machine # [ 34.190495] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 34.192106] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1557machine # [ 34.197497] systemd[1]: Starting linger-users.service...1558machine # [ 34.203324] systemd[1]: Reached target Local File Systems.1559machine # [ 34.206177] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1560machine # [ 34.211554] systemd[1]: Starting Create SUID/SGID Wrappers...1561machine # [ 34.212498] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1562machine # [ 34.213807] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671563machine # [ 34.216644] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1564machine # [ 34.218152] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1565machine # [ 34.221888] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1566machine # [ 34.253097] systemd[1]: linger-users.service: Deactivated successfully.1567machine # [ 34.255458] systemd[1]: Finished linger-users.service.1568machine # [ 34.363975] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1569machine # [ 34.365159] systemd[1]: Finished Create SUID/SGID Wrappers.1570machine # [ 34.620179] nixos[2362]: finished switching to system configuration /nix/store/982xb2vdixgmg50wiskz9wvy7mxzfapv-nixos-system-machine-test1571machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 1.45 seconds)1572machine: must succeed: [ -S /run/accept-test.sock ]1573machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.01 seconds)1574machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1575machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.01 seconds)1576machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1577machine # [ 34.655312] systemd[1]: Created slice Slice /system/accept-socket.1578machine # [ 34.659078] systemd[1]: Started A per-connection socket-activated service (PID 2507/UID 0).1579machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.04 seconds)1580machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11581machine # [ 34.885924] nixos[2519]: switching to system configuration /nix/store/xr6kv9fyxzdvbv99l6j4cxv49bdgmxn2-nixos-system-machine-test1582machine # [ 34.888031] systemd[1]: Stopped target Remote File Systems.1583machine # [ 34.889523] systemd[1]: Stopped target Local File Systems.1584machine # [ 34.892082] systemd[1]: Stopping A per-connection socket-activated service (PID 2507/UID 0)...1585machine # [ 34.894576] systemd[1]: accept-socket@0-1-2507_2508-0.service: Deactivated successfully.1586machine # [ 34.896210] systemd[1]: Stopped A per-connection socket-activated service (PID 2507/UID 0).1587machine # [ 34.900172] systemd[1]: accept-socket.socket: Deactivated successfully.1588machine # [ 34.901581] systemd[1]: Closed accept-socket.socket.1589machine # [ 35.122290] systemd[1]: Reload requested from client PID 2519 ('.switch-to-conf') (unit backdoor.service)...1590machine # [ 35.123787] systemd[1]: Reloading...1591machine # [ 35.360768] systemd[1]: Reloading finished in 235 ms.1592machine # [ 35.420848] systemd[623]: Reexecution requested from client PID 2590 ('switch-to-confi')...1593machine # [ 35.423192] systemd[623]: Reexecuting.1594machine # [ 35.444258] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1595machine # [ 35.515082] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1596machine # [ 35.520941] systemd[623]: Reload requested from client PID 2590 ('switch-to-confi')...1597machine # [ 35.522106] systemd[623]: Reloading...1598machine # [ 35.592523] systemd[623]: Reloading finished in 69 ms.1599machine # [ 35.597831] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1600machine # [ 35.603689] systemd[623]: Stopped Run user-specific NixOS activation.1601machine # [ 35.605137] systemd[623]: Stopping Run user-specific NixOS activation...1602machine # [ 35.607071] systemd[623]: Starting Run user-specific NixOS activation...1603machine # [ 35.627181] systemd[623]: Finished Run user-specific NixOS activation.1604machine # [ 35.630099] systemd[1]: Stopped target Reactivate sysinit units.1605machine # [ 35.631587] systemd[1]: Stopping Reactivate sysinit units...1606machine # [ 35.632556] systemd[1]: Reached target Reactivate sysinit units.1607machine # [ 35.636471] systemd[1]: Reached target Local File Systems.1608machine # [ 35.638327] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1609machine # [ 35.639487] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1610machine # [ 35.640626] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1611machine # [ 35.643965] systemd[1]: Starting Create SUID/SGID Wrappers...1612machine # [ 35.644880] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1613machine # [ 35.646717] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671614machine # [ 35.649257] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1615machine # [ 35.650644] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1616machine # [ 35.651783] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1617machine # [ 35.658601] systemd[1]: Reached target Remote File Systems.1618machine # [ 35.782739] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1619machine # [ 35.784321] systemd[1]: Finished Create SUID/SGID Wrappers.1620machine # [ 35.787207] systemd[1]: Listening on accept-socket.socket.1621machine # [ 35.789850] systemd[1]: Starting linger-users.service...1622machine # [ 35.817302] systemd[1]: linger-users.service: Deactivated successfully.1623machine # [ 35.818798] systemd[1]: Finished linger-users.service.1624machine # [ 36.075723] nixos[2519]: finished switching to system configuration /nix/store/xr6kv9fyxzdvbv99l6j4cxv49bdgmxn2-nixos-system-machine-test1625machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 1.41 seconds)1626machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1627machine # [ 36.122815] systemd[1]: Started A per-connection socket-activated service (PID 2661/UID 0).1628machine # [ 36.154975] systemd[1]: accept-socket@1-2-2661_2662-0.service: Deactivated successfully.1629machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.08 seconds)1630(finished: subtest: socket-activated services with Accept=yes, in 2.99 seconds)1631subtest: mounts1632machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11633machine # [ 36.374352] nixos[2668]: switching to system configuration /nix/store/2qspwcfwndn0ki510xzzsfd4qd3s0mg4-nixos-system-machine-test1634machine # [ 36.376931] systemd[1]: accept-socket.socket: Deactivated successfully.1635machine # [ 36.377794] systemd[1]: Closed accept-socket.socket.1636machine # [ 36.379424] systemd[1]: Stopped target Local File Systems.1637machine # [ 36.380975] systemd[1]: Stopped target Remote File Systems.1638machine # [ 36.624558] systemd[1]: Reload requested from client PID 2668 ('.switch-to-conf') (unit backdoor.service)...1639machine # [ 36.626346] systemd[1]: Reloading...1640machine # [ 36.911396] systemd[1]: Reloading finished in 282 ms.1641machine # [ 37.006200] systemd[623]: Reexecution requested from client PID 2737 ('switch-to-confi')...1642machine # [ 37.007538] systemd[623]: Reexecuting.1643machine # [ 37.027249] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1644machine # [ 37.093766] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1645machine # [ 37.099532] systemd[623]: Reload requested from client PID 2737 ('switch-to-confi')...1646machine # [ 37.101104] systemd[623]: Reloading...1647machine # [ 37.172283] systemd[623]: Reloading finished in 70 ms.1648machine # [ 37.177817] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1649machine # [ 37.183717] systemd[623]: Stopped Run user-specific NixOS activation.1650machine # [ 37.185121] systemd[623]: Stopping Run user-specific NixOS activation...1651machine # [ 37.187079] systemd[623]: Starting Run user-specific NixOS activation...1652machine # [ 37.206333] systemd[623]: Finished Run user-specific NixOS activation.1653machine # [ 37.209358] systemd[1]: Stopped target Reactivate sysinit units.1654machine # [ 37.210645] systemd[1]: Stopping Reactivate sysinit units...1655machine # [ 37.211679] systemd[1]: Reached target Reactivate sysinit units.1656machine # [ 37.213617] systemd[1]: Reached target Remote File Systems.1657machine # [ 37.218380] systemd[1]: Starting linger-users.service...1658machine # [ 37.225815] systemd[1]: Mounting Testmount...1659machine # [ 37.226500] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1660machine # [ 37.229342] systemd[1]: Starting Create SUID/SGID Wrappers...1661machine # [ 37.231427] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671662machine # [ 37.234104] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1663machine # [ 37.237620] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1664machine # [ 37.238976] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1665machine # [ 37.241116] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1666machine # [ 37.242276] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1667machine # [ 37.278887] systemd[1]: Mounted Testmount.1668machine # [ 37.280137] systemd[1]: Reached target Local File Systems.1669machine # [ 37.280856] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1670machine # [ 37.297638] systemd[1]: linger-users.service: Deactivated successfully.1671machine # [ 37.299522] systemd[1]: Finished linger-users.service.1672machine # [ 37.416435] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1673machine # [ 37.419446] systemd[1]: Finished Create SUID/SGID Wrappers.1674machine # [ 37.676341] nixos[2668]: finished switching to system configuration /nix/store/2qspwcfwndn0ki510xzzsfd4qd3s0mg4-nixos-system-machine-test1675machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.52 seconds)1676machine: must succeed: mount | grep 'on /testmount'1677machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1678machine: must succeed: systemctl stop testmount.mount1679machine # [ 37.743067] systemd[1]: Unmounting Testmount...1680machine # [ 37.759869] systemd[1]: testmount.mount: Deactivated successfully.1681machine # [ 37.761437] systemd[1]: Unmounted Testmount.1682machine: (finished: must succeed: systemctl stop testmount.mount, in 0.05 seconds)1683machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11684machine # [ 37.945693] nixos[2822]: switching to system configuration /nix/store/2qspwcfwndn0ki510xzzsfd4qd3s0mg4-nixos-system-machine-test1685machine # [ 37.949162] systemd[1]: Stopped target Remote File Systems.1686machine # [ 37.950133] systemd[1]: Stopped target Local File Systems.1687machine # [ 38.146320] systemd[1]: Reload requested from client PID 2822 ('.switch-to-conf') (unit backdoor.service)...1688machine # [ 38.147956] systemd[1]: Reloading...1689machine # [ 38.370688] systemd[1]: Reloading finished in 221 ms.1690machine # [ 38.433412] systemd[623]: Reexecution requested from client PID 2891 ('switch-to-confi')...1691machine # [ 38.434646] systemd[623]: Reexecuting.1692machine # [ 38.452954] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1693machine # [ 38.523940] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1694machine # [ 38.528956] systemd[623]: Reload requested from client PID 2891 ('switch-to-confi')...1695machine # [ 38.530165] systemd[623]: Reloading...1696machine # [ 38.601930] systemd[623]: Reloading finished in 70 ms.1697machine # [ 38.606843] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1698machine # [ 38.613193] systemd[623]: Stopped Run user-specific NixOS activation.1699machine # [ 38.615074] systemd[623]: Stopping Run user-specific NixOS activation...1700machine # [ 38.616529] systemd[623]: Starting Run user-specific NixOS activation...1701machine # [ 38.636258] systemd[623]: Finished Run user-specific NixOS activation.1702machine # [ 38.639243] systemd[1]: Stopped target Reactivate sysinit units.1703machine # [ 38.640599] systemd[1]: Stopping Reactivate sysinit units...1704machine # [ 38.641597] systemd[1]: Reached target Reactivate sysinit units.1705machine # [ 38.645787] systemd[1]: Mounting Testmount...1706machine # [ 38.650068] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1707machine # [ 38.651223] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1708machine # [ 38.654855] systemd[1]: Reached target Remote File Systems.1709machine # [ 38.660062] systemd[1]: Starting linger-users.service...1710machine # [ 38.667124] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1711machine # [ 38.672947] systemd[1]: Starting Create SUID/SGID Wrappers...1712machine # [ 38.673748] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671713machine # [ 38.675609] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1714machine # [ 38.677109] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1715machine # [ 38.678258] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1716machine # [ 38.694456] systemd[1]: Mounted Testmount.1717machine # [ 38.696533] systemd[1]: Reached target Local File Systems.1718machine # [ 38.698085] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1719machine # [ 38.726483] systemd[1]: linger-users.service: Deactivated successfully.1720machine # [ 38.728182] systemd[1]: Finished linger-users.service.1721machine # [ 38.844550] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1722machine # [ 38.846746] systemd[1]: Finished Create SUID/SGID Wrappers.1723machine # [ 39.101313] nixos[2822]: finished switching to system configuration /nix/store/2qspwcfwndn0ki510xzzsfd4qd3s0mg4-nixos-system-machine-test1724machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 1.34 seconds)1725machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11726machine # [ 39.294261] nixos[2964]: switching to system configuration /nix/store/siybzis58ij6clzd44xg1zmgkv5hvkiz-nixos-system-machine-test1727machine # [ 39.296468] systemd[1]: Stopped target Local File Systems.1728machine # [ 39.298195] systemd[1]: Stopped target Remote File Systems.1729machine # [ 39.525483] systemd[1]: Reload requested from client PID 2964 ('.switch-to-conf') (unit backdoor.service)...1730machine # [ 39.527513] systemd[1]: Reloading...1731machine # [ 39.793974] systemd[1]: Reloading finished in 264 ms.1732machine # [ 39.859041] systemd[623]: Reexecution requested from client PID 3033 ('switch-to-confi')...1733machine # [ 39.860269] systemd[623]: Reexecuting.1734machine # [ 39.879837] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1735machine # [ 39.952276] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1736machine # [ 39.957110] systemd[623]: Reload requested from client PID 3033 ('switch-to-confi')...1737machine # [ 39.958655] systemd[623]: Reloading...1738machine # [ 40.031719] systemd[623]: Reloading finished in 70 ms.1739machine # [ 40.035876] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1740machine # [ 40.041808] systemd[623]: Stopped Run user-specific NixOS activation.1741machine # [ 40.043245] systemd[623]: Stopping Run user-specific NixOS activation...1742machine # [ 40.045351] systemd[623]: Starting Run user-specific NixOS activation...1743machine # [ 40.070603] systemd[623]: Finished Run user-specific NixOS activation.1744machine # [ 40.074781] systemd[1]: Stopped target Reactivate sysinit units.1745machine # [ 40.076549] systemd[1]: Stopping Reactivate sysinit units...1746machine # [ 40.078199] systemd[1]: Reached target Reactivate sysinit units.1747machine # [ 40.081356] systemd[1]: Reloading Testmount...1748machine # [ 40.115509] systemd[1]: Reloaded Testmount.1749machine # [ 40.137983] systemd[1]: Reached target Local File Systems.1750machine # [ 40.140407] systemd[1]: Reached target Remote File Systems.1751machine # [ 40.142298] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1752machine # [ 40.146590] systemd[1]: Starting Create SUID/SGID Wrappers...1753machine # [ 40.150096] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1754machine # [ 40.151815] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671755machine # [ 40.155150] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1756machine # [ 40.158737] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1757machine # [ 40.161461] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1758machine # [ 40.167134] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1759machine # [ 40.168357] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1760machine # [ 40.321328] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1761machine # [ 40.323082] systemd[1]: Finished Create SUID/SGID Wrappers.1762machine # [ 40.328510] systemd[1]: Starting linger-users.service...1763machine # [ 40.361811] systemd[1]: linger-users.service: Deactivated successfully.1764machine # [ 40.363657] systemd[1]: Finished linger-users.service.1765machine # [ 40.620768] nixos[2964]: finished switching to system configuration /nix/store/siybzis58ij6clzd44xg1zmgkv5hvkiz-nixos-system-machine-test1766machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 1.52 seconds)1767machine: must succeed: mount | grep 'on /testmount'1768machine: (finished: must succeed: mount | grep 'on /testmount', in 0.04 seconds)1769machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11770machine # [ 40.856756] nixos[3112]: switching to system configuration /nix/store/n0njia8dixjh30282z6455nk1mbiqm91-nixos-system-machine-test1771machine # [ 40.858855] systemd[1]: Stopped target Local File Systems.1772machine # [ 40.860400] systemd[1]: Stopped target Remote File Systems.1773machine # [ 41.084754] systemd[1]: Reload requested from client PID 3112 ('.switch-to-conf') (unit backdoor.service)...1774machine # [ 41.086416] systemd[1]: Reloading...1775machine # [ 41.325504] systemd[1]: Reloading finished in 237 ms.1776machine # [ 41.386607] systemd[623]: Reexecution requested from client PID 3181 ('switch-to-confi')...1777machine # [ 41.388587] systemd[623]: Reexecuting.1778machine # [ 41.410363] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1779machine # [ 41.485652] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1780machine # [ 41.490994] systemd[623]: Reload requested from client PID 3181 ('switch-to-confi')...1781machine # [ 41.492524] systemd[623]: Reloading...1782machine # [ 41.560359] systemd[623]: Reloading finished in 66 ms.1783machine # [ 41.565861] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1784machine # [ 41.571973] systemd[623]: Stopped Run user-specific NixOS activation.1785machine # [ 41.573058] systemd[623]: Stopping Run user-specific NixOS activation...1786machine # [ 41.575684] systemd[623]: Starting Run user-specific NixOS activation...1787machine # [ 41.594210] systemd[623]: Finished Run user-specific NixOS activation.1788machine # [ 41.597271] systemd[1]: Stopped target Reactivate sysinit units.1789machine # [ 41.598588] systemd[1]: Stopping Reactivate sysinit units...1790machine # [ 41.599803] systemd[1]: Reached target Reactivate sysinit units.1791machine # [ 41.601909] systemd[1]: Unmounting Testmount...1792machine # [ 41.618071] systemd[1]: testmount.mount: Deactivated successfully.1793machine # [ 41.618980] systemd[1]: Unmounted Testmount.1794machine # [ 41.957763] systemd[1]: Mounting Testmount...1795machine # [ 41.991203] systemd[1]: Mounted Testmount.1796machine # [ 42.001934] systemd[1]: Reached target Remote File Systems.1797machine # [ 42.015360] systemd[1]: Starting linger-users.service...1798machine # [ 42.020143] systemd[1]: Reached target Local File Systems.1799machine # [ 42.032068] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1800machine # [ 42.035295] systemd[1]: Starting Create SUID/SGID Wrappers...1801machine # [ 42.036545] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1802machine # [ 42.037693] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671803machine # [ 42.041540] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1804machine # [ 42.044471] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1805machine # [ 42.047565] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1806machine # [ 42.050167] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1807machine # [ 42.051314] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1808machine # [ 42.090101] systemd[1]: linger-users.service: Deactivated successfully.1809machine # [ 42.093714] systemd[1]: Finished linger-users.service.1810machine # [ 42.196647] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1811machine # [ 42.198306] systemd[1]: Finished Create SUID/SGID Wrappers.1812machine # [ 42.458281] nixos[3112]: finished switching to system configuration /nix/store/n0njia8dixjh30282z6455nk1mbiqm91-nixos-system-machine-test1813machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 1.80 seconds)1814machine: must succeed: mount | grep 'on /testmount'1815machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1816(finished: subtest: mounts, in 6.34 seconds)1817subtest: timers1818machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11819machine # [ 42.696161] nixos[3261]: switching to system configuration /nix/store/yx43vddbvpkl40aakh7zmm7km3cv76ql-nixos-system-machine-test1820machine # [ 42.699766] systemd[1]: Unmounting Testmount...1821machine # [ 42.701564] systemd[1]: Stopped target Local File Systems.1822machine # [ 42.703291] systemd[1]: Stopped target Remote File Systems.1823machine # [ 42.720204] systemd[1]: testmount.mount: Deactivated successfully.1824machine # [ 42.721595] systemd[1]: Unmounted Testmount.1825machine # [ 42.956613] systemd[1]: Reload requested from client PID 3261 ('.switch-to-conf') (unit backdoor.service)...1826machine # [ 42.958341] systemd[1]: Reloading...1827machine # [ 43.218240] systemd[1]: Reloading finished in 258 ms.1828machine # [ 43.277451] systemd[623]: Reexecution requested from client PID 3332 ('switch-to-confi')...1829machine # [ 43.278759] systemd[623]: Reexecuting.1830machine # [ 43.297942] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1831machine # [ 43.371279] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1832machine # [ 43.375347] systemd[623]: Reload requested from client PID 3332 ('switch-to-confi')...1833machine # [ 43.376938] systemd[623]: Reloading...1834machine # [ 43.440467] systemd[623]: Reloading finished in 62 ms.1835machine # [ 43.445848] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1836machine # [ 43.453128] systemd[623]: Stopped Run user-specific NixOS activation.1837machine # [ 43.454089] systemd[623]: Stopping Run user-specific NixOS activation...1838machine # [ 43.456149] systemd[623]: Starting Run user-specific NixOS activation...1839machine # [ 43.474661] systemd[623]: Finished Run user-specific NixOS activation.1840machine # [ 43.477563] systemd[1]: Stopped target Reactivate sysinit units.1841machine # [ 43.478811] systemd[1]: Stopping Reactivate sysinit units...1842machine # [ 43.479779] systemd[1]: Reached target Reactivate sysinit units.1843machine # [ 43.482414] systemd[1]: Started test-timer.timer.1844machine # [ 43.483545] systemd[1]: Reached target Local File Systems.1845machine # [ 43.486470] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1846machine # [ 43.488113] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1847machine # [ 43.491053] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1848machine # [ 43.495077] systemd[1]: Starting Create SUID/SGID Wrappers...1849machine # [ 43.495836] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1850machine # [ 43.498170] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671851machine # [ 43.500154] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1852machine # [ 43.501570] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1853machine # [ 43.502731] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1854machine # [ 43.506926] systemd[1]: Reached target Remote File Systems.1855machine # [ 43.634515] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1856machine # [ 43.636136] systemd[1]: Finished Create SUID/SGID Wrappers.1857machine # [ 43.640119] systemd[1]: Starting linger-users.service...1858machine # [ 43.667798] systemd[1]: linger-users.service: Deactivated successfully.1859machine # [ 43.668821] systemd[1]: Finished linger-users.service.1860machine # [ 43.923494] nixos[3261]: finished switching to system configuration /nix/store/yx43vddbvpkl40aakh7zmm7km3cv76ql-nixos-system-machine-test1861machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 1.43 seconds)1862machine: must succeed: systemctl show test-timer.timer1863machine: (finished: must succeed: systemctl show test-timer.timer, in 0.02 seconds)1864machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11865machine # [ 44.110633] nixos[3406]: switching to system configuration /nix/store/janv5xh0ba49pl3ynln4li4i92pzg7wb-nixos-system-machine-test1866machine # [ 44.113056] systemd[1]: Stopped target Local File Systems.1867machine # [ 44.114679] systemd[1]: Stopped target Remote File Systems.1868machine # [ 44.334888] systemd[1]: Reload requested from client PID 3406 ('.switch-to-conf') (unit backdoor.service)...1869machine # [ 44.336554] systemd[1]: Reloading...1870machine # [ 44.571995] systemd[1]: Reloading finished in 233 ms.1871machine # [ 44.632615] systemd[623]: Reexecution requested from client PID 3475 ('switch-to-confi')...1872machine # [ 44.633924] systemd[623]: Reexecuting.1873machine # [ 44.653034] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1874machine # [ 44.720866] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1875machine # [ 44.726620] systemd[623]: Reload requested from client PID 3475 ('switch-to-confi')...1876machine # [ 44.727881] systemd[623]: Reloading...1877machine # [ 44.797039] systemd[623]: Reloading finished in 67 ms.1878machine # [ 44.802862] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1879machine # [ 44.808858] systemd[623]: Stopped Run user-specific NixOS activation.1880machine # [ 44.810262] systemd[623]: Stopping Run user-specific NixOS activation...1881machine # [ 44.812184] systemd[623]: Starting Run user-specific NixOS activation...1882machine # [ 44.831292] systemd[623]: Finished Run user-specific NixOS activation.1883machine # [ 44.834563] systemd[1]: Stopped target Reactivate sysinit units.1884machine # [ 44.835743] systemd[1]: Stopping Reactivate sysinit units...1885machine # [ 44.836831] systemd[1]: Reached target Reactivate sysinit units.1886machine # [ 44.838186] systemd[1]: test-timer.timer: Deactivated successfully.1887machine # [ 44.839717] systemd[1]: Stopped test-timer.timer.1888machine # [ 44.840519] systemd[1]: Stopping test-timer.timer...1889machine # [ 44.841489] systemd[1]: Started test-timer.timer.1890machine # [ 44.843433] systemd[1]: Reached target Local File Systems.1891machine # [ 44.846743] systemd[1]: Reached target Remote File Systems.1892machine # [ 44.848686] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1893machine # [ 44.850565] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1894machine # [ 44.856474] systemd[1]: Starting linger-users.service...1895machine # [ 44.862367] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1896machine # [ 44.865316] systemd[1]: Starting Create SUID/SGID Wrappers...1897machine # [ 44.866330] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1898machine # [ 44.867487] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671899machine # [ 44.869476] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1900machine # [ 44.870921] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1901machine # [ 44.872102] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1902machine # [ 44.913590] systemd[1]: linger-users.service: Deactivated successfully.1903machine # [ 44.916303] systemd[1]: Finished linger-users.service.1904machine # [ 45.017715] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1905machine # [ 45.020335] systemd[1]: Finished Create SUID/SGID Wrappers.1906machine # [ 45.276927] nixos[3406]: finished switching to system configuration /nix/store/janv5xh0ba49pl3ynln4li4i92pzg7wb-nixos-system-machine-test1907machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.34 seconds)1908machine: must succeed: systemctl show test-timer.timer1909machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1910(finished: subtest: timers, in 2.83 seconds)1911subtest: targets1912machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11913machine # [ 45.522615] nixos[3549]: switching to system configuration /nix/store/6r0hr6p4fsy2az2h74wvi980m0r73j78-nixos-system-machine-test1914machine # [ 45.525570] systemd[1]: Stopped target Remote File Systems.1915machine # [ 45.527569] systemd[1]: test-timer.timer: Deactivated successfully.1916machine # [ 45.528805] systemd[1]: Stopped test-timer.timer.1917machine # [ 45.529641] systemd[1]: Stopped target Local File Systems.1918machine # [ 45.753324] systemd[1]: Reload requested from client PID 3549 ('.switch-to-conf') (unit backdoor.service)...1919machine # [ 45.755107] systemd[1]: Reloading...1920machine # [ 45.967866] systemd[1]: Reloading finished in 211 ms.1921machine # [ 46.027288] systemd[623]: Reexecution requested from client PID 3618 ('switch-to-confi')...1922machine # [ 46.029160] systemd[623]: Reexecuting.1923machine # [ 46.049054] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1924machine # [ 46.120039] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1925machine # [ 46.125231] systemd[623]: Reload requested from client PID 3618 ('switch-to-confi')...1926machine # [ 46.126586] systemd[623]: Reloading...1927machine # [ 46.197738] systemd[623]: Reloading finished in 69 ms.1928machine # [ 46.203849] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1929machine # [ 46.211062] systemd[623]: Stopped Run user-specific NixOS activation.1930machine # [ 46.211904] systemd[623]: Stopping Run user-specific NixOS activation...1931machine # [ 46.214802] systemd[623]: Starting Run user-specific NixOS activation...1932machine # [ 46.233122] systemd[623]: Finished Run user-specific NixOS activation.1933machine # [ 46.235883] systemd[1]: Stopped target Reactivate sysinit units.1934machine # [ 46.237194] systemd[1]: Stopping Reactivate sysinit units...1935machine # [ 46.237972] systemd[1]: Reached target Reactivate sysinit units.1936machine # [ 46.243295] systemd[1]: Reached target Local File Systems.1937machine # [ 46.245149] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1938machine # [ 46.248417] systemd[1]: Starting Create SUID/SGID Wrappers...1939machine # [ 46.249260] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1940machine # [ 46.251155] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671941machine # [ 46.253072] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1942machine # [ 46.254447] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1943machine # [ 46.255589] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1944machine # [ 46.258731] systemd[1]: Reached target Remote File Systems.1945machine # [ 46.260353] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1946machine # [ 46.262101] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1947machine # [ 46.387439] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1948machine # [ 46.389066] systemd[1]: Finished Create SUID/SGID Wrappers.1949machine # [ 46.392794] systemd[1]: Starting linger-users.service...1950machine # [ 46.417648] systemd[1]: linger-users.service: Deactivated successfully.1951machine # [ 46.419152] systemd[1]: Finished linger-users.service.1952machine # [ 46.675433] nixos[3549]: finished switching to system configuration /nix/store/6r0hr6p4fsy2az2h74wvi980m0r73j78-nixos-system-machine-test1953machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 1.36 seconds)1954machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11955machine # [ 46.878372] nixos[3689]: switching to system configuration /nix/store/agkmm92carxw4gfql3jkm89qxwnm0317-nixos-system-machine-test1956machine # [ 46.880739] systemd[1]: Stopped target Local File Systems.1957machine # [ 46.882828] systemd[1]: Stopped target Remote File Systems.1958machine # [ 47.110403] systemd[1]: Reload requested from client PID 3689 ('.switch-to-conf') (unit backdoor.service)...1959machine # [ 47.111982] systemd[1]: Reloading...1960machine # [ 47.358928] systemd[1]: Reloading finished in 245 ms.1961machine # [ 47.431940] systemd[623]: Reexecution requested from client PID 3758 ('switch-to-confi')...1962machine # [ 47.434534] systemd[623]: Reexecuting.1963machine # [ 47.453769] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1964machine # [ 47.518490] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1965machine # [ 47.524059] systemd[623]: Reload requested from client PID 3758 ('switch-to-confi')...1966machine # [ 47.525457] systemd[623]: Reloading...1967machine # [ 47.596356] systemd[623]: Reloading finished in 69 ms.1968machine # [ 47.601844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1969machine # [ 47.608159] systemd[623]: Stopped Run user-specific NixOS activation.1970machine # [ 47.609184] systemd[623]: Stopping Run user-specific NixOS activation...1971machine # [ 47.611816] systemd[623]: Starting Run user-specific NixOS activation...1972machine # [ 47.632830] systemd[623]: Finished Run user-specific NixOS activation.1973machine # [ 47.635689] systemd[1]: Stopped target Reactivate sysinit units.1974machine # [ 47.637156] systemd[1]: Stopping Reactivate sysinit units...1975machine # [ 47.637918] systemd[1]: Reached target Reactivate sysinit units.1976machine # [ 47.639907] systemd[1]: Reached target Local File Systems.1977machine # [ 47.642273] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1978machine # [ 47.644565] systemd[1]: Starting Create SUID/SGID Wrappers...1979machine # [ 47.646964] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1980machine # [ 47.648185] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671981machine # [ 47.650203] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1982machine # [ 47.651614] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1983machine # [ 47.652754] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1984machine # [ 47.657433] systemd[1]: Reached target Remote File Systems.1985machine # [ 47.659346] systemd[1]: Reached target test-target.target.1986machine # [ 47.669864] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1987machine # [ 47.671176] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1988machine # [ 47.793223] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1989machine # [ 47.794765] systemd[1]: Finished Create SUID/SGID Wrappers.1990machine # [ 47.798574] systemd[1]: Starting linger-users.service...1991machine # [ 47.824408] systemd[1]: linger-users.service: Deactivated successfully.1992machine # [ 47.826600] systemd[1]: Finished linger-users.service.1993machine # [ 48.082944] nixos[3689]: finished switching to system configuration /nix/store/agkmm92carxw4gfql3jkm89qxwnm0317-nixos-system-machine-test1994machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 1.41 seconds)1995machine # [ 48.138711] systemd[1]: Started test-service.service.1996machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11997machine # [ 48.362373] nixos[3835]: switching to system configuration /nix/store/9vharf18rwgrd4nadlhzcx7k88kcfs14-nixos-system-machine-test1998machine # [ 48.364519] systemd[1]: Stopped target Remote File Systems.1999machine # [ 48.366070] systemd[1]: Stopped target Local File Systems.2000machine # [ 48.588242] systemd[1]: Reload requested from client PID 3835 ('.switch-to-conf') (unit backdoor.service)...2001machine # [ 48.589812] systemd[1]: Reloading...2002machine # [ 48.831922] systemd[1]: Reloading finished in 240 ms.2003machine # [ 48.897429] systemd[623]: Reexecution requested from client PID 3905 ('switch-to-confi')...2004machine # [ 48.898700] systemd[623]: Reexecuting.2005machine # [ 48.920278] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2006machine # [ 48.993724] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2007machine # [ 48.999349] systemd[623]: Reload requested from client PID 3905 ('switch-to-confi')...2008machine # [ 49.001321] systemd[623]: Reloading...2009machine # [ 49.072552] systemd[623]: Reloading finished in 71 ms.2010machine # [ 49.076847] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2011machine # [ 49.083146] systemd[623]: Stopped Run user-specific NixOS activation.2012machine # [ 49.085028] systemd[623]: Stopping Run user-specific NixOS activation...2013machine # [ 49.086528] systemd[623]: Starting Run user-specific NixOS activation...2014machine # [ 49.105400] systemd[623]: Finished Run user-specific NixOS activation.2015machine # [ 49.108388] systemd[1]: Stopped target Reactivate sysinit units.2016machine # [ 49.109815] systemd[1]: Stopping Reactivate sysinit units...2017machine # [ 49.110853] systemd[1]: Reached target Reactivate sysinit units.2018machine # [ 49.112618] systemd[1]: Reached target Remote File Systems.2019machine # [ 49.118241] systemd[1]: Reached target Local File Systems.2020machine # [ 49.121660] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2021machine # [ 49.123104] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2022machine # [ 49.127307] systemd[1]: Starting linger-users.service...2023machine # [ 49.135066] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2024machine # [ 49.138858] systemd[1]: Starting Create SUID/SGID Wrappers...2025machine # [ 49.139639] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2026machine # [ 49.141631] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672027machine # [ 49.144148] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2028machine # [ 49.147216] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2029machine # [ 49.148509] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2030machine # [ 49.181312] systemd[1]: linger-users.service: Deactivated successfully.2031machine # [ 49.183361] systemd[1]: Finished linger-users.service.2032machine # [ 49.294146] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2033machine # [ 49.296389] systemd[1]: Finished Create SUID/SGID Wrappers.2034machine # [ 49.550419] nixos[3835]: finished switching to system configuration /nix/store/9vharf18rwgrd4nadlhzcx7k88kcfs14-nixos-system-machine-test2035machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 1.41 seconds)2036machine: must succeed: systemctl is-active test-service.service2037machine: (finished: must succeed: systemctl is-active test-service.service, in 0.01 seconds)2038machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&12039machine # [ 49.748842] nixos[3979]: switching to system configuration /nix/store/nm69ing6sl923x4bpr4qwq2ys4b55z78-nixos-system-machine-test2040machine # [ 49.752473] systemd[1]: Stopped target test-target.target.2041machine # [ 49.753786] systemd[1]: Stopping test-service.service...2042machine # [ 49.755351] systemd[1]: test-service.service: Deactivated successfully.2043machine # [ 49.756782] systemd[1]: Stopped test-service.service.2044machine # [ 49.759739] systemd[1]: Stopped target Local File Systems.2045machine # [ 49.760706] systemd[1]: Stopped target Remote File Systems.2046machine # [ 49.985221] systemd[1]: Reload requested from client PID 3979 ('.switch-to-conf') (unit backdoor.service)...2047machine # [ 49.986844] systemd[1]: Reloading...2048machine # [ 50.227412] systemd[1]: Reloading finished in 239 ms.2049machine # [ 50.291532] systemd[623]: Reexecution requested from client PID 4049 ('switch-to-confi')...2050machine # [ 50.293059] systemd[623]: Reexecuting.2051machine # [ 50.313033] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2052machine # [ 50.387601] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2053machine # [ 50.392636] systemd[623]: Reload requested from client PID 4049 ('switch-to-confi')...2054machine # [ 50.395278] systemd[623]: Reloading...2055machine # [ 50.466358] systemd[623]: Reloading finished in 71 ms.2056machine # [ 50.471823] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2057machine # [ 50.477834] systemd[623]: Stopped Run user-specific NixOS activation.2058machine # [ 50.479219] systemd[623]: Stopping Run user-specific NixOS activation...2059machine # [ 50.481329] systemd[623]: Starting Run user-specific NixOS activation...2060machine # [ 50.500822] systemd[623]: Finished Run user-specific NixOS activation.2061machine # [ 50.503764] systemd[1]: Stopped target Reactivate sysinit units.2062machine # [ 50.504879] systemd[1]: Stopping Reactivate sysinit units...2063machine # [ 50.505854] systemd[1]: Reached target Reactivate sysinit units.2064machine # [ 50.507917] systemd[1]: Reached target Remote File Systems.2065machine # [ 50.509260] systemd[1]: Reached target test-target.target.2066machine # [ 50.511647] systemd[1]: Starting linger-users.service...2067machine # [ 50.513383] systemd[1]: Reached target Local File Systems.2068machine # [ 50.517700] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2069machine # [ 50.518872] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2070machine # [ 50.530753] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2071machine # [ 50.535220] systemd[1]: Starting Create SUID/SGID Wrappers...2072machine # [ 50.537125] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2073machine # [ 50.538254] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672074machine # [ 50.540247] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2075machine # [ 50.541714] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2076machine # [ 50.542864] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2077machine # [ 50.573506] systemd[1]: linger-users.service: Deactivated successfully.2078machine # [ 50.575541] systemd[1]: Finished linger-users.service.2079machine # [ 50.684731] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2080machine # [ 50.686409] systemd[1]: Finished Create SUID/SGID Wrappers.2081machine # [ 50.943603] nixos[3979]: finished switching to system configuration /nix/store/nm69ing6sl923x4bpr4qwq2ys4b55z78-nixos-system-machine-test2082machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 1.38 seconds)2083machine: must fail: systemctl is-active test-service.servce2084machine: (finished: must fail: systemctl is-active test-service.servce, in 0.03 seconds)2085machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&12086machine # [ 51.169583] nixos[4123]: switching to system configuration /nix/store/janv5xh0ba49pl3ynln4li4i92pzg7wb-nixos-system-machine-test2087machine # [ 51.171803] systemd[1]: Stopped target Remote File Systems.2088machine # [ 51.173337] systemd[1]: Stopped target test-target.target.2089machine # [ 51.174818] systemd[1]: Stopped target Local File Systems.2090machine # [ 51.380875] systemd[1]: Reload requested from client PID 4123 ('.switch-to-conf') (unit backdoor.service)...2091machine # [ 51.382609] systemd[1]: Reloading...2092machine # [ 51.619432] systemd[1]: Reloading finished in 235 ms.2093machine # [ 51.682705] systemd[623]: Reexecution requested from client PID 4192 ('switch-to-confi')...2094machine # [ 51.684296] systemd[623]: Reexecuting.2095machine # [ 51.704979] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2096machine # [ 51.776499] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2097machine # [ 51.781807] systemd[623]: Reload requested from client PID 4192 ('switch-to-confi')...2098machine # [ 51.783971] systemd[623]: Reloading...2099machine # [ 51.855139] systemd[623]: Reloading finished in 71 ms.2100machine # [ 51.860840] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2101machine # [ 51.868063] systemd[623]: Stopped Run user-specific NixOS activation.2102machine # [ 51.868938] systemd[623]: Stopping Run user-specific NixOS activation...2103machine # [ 51.870589] systemd[623]: Starting Run user-specific NixOS activation...2104machine # [ 51.888273] systemd[623]: Finished Run user-specific NixOS activation.2105machine # [ 51.892865] systemd[1]: Stopped target Reactivate sysinit units.2106machine # [ 51.894506] systemd[1]: Stopping Reactivate sysinit units...2107machine # [ 51.895713] systemd[1]: Reached target Reactivate sysinit units.2108machine # [ 51.900071] systemd[1]: Reached target Local File Systems.2109machine # [ 51.900979] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2110machine # [ 51.904199] systemd[1]: Starting Create SUID/SGID Wrappers...2111machine # [ 51.906202] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2112machine # [ 51.908432] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672113machine # [ 51.910789] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2114machine # [ 51.912556] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2115machine # [ 51.916173] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2116machine # [ 51.921416] systemd[1]: Reached target Remote File Systems.2117machine # [ 51.926198] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2118machine # [ 51.928441] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2119machine # [ 52.042692] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2120machine # [ 52.044297] systemd[1]: Finished Create SUID/SGID Wrappers.2121machine # [ 52.047494] systemd[1]: Started test-timer.timer.2122machine # [ 52.049392] systemd[1]: Starting linger-users.service...2123machine # [ 52.074756] systemd[1]: linger-users.service: Deactivated successfully.2124machine # [ 52.076235] systemd[1]: Finished linger-users.service.2125machine # [ 52.333419] nixos[4123]: finished switching to system configuration /nix/store/janv5xh0ba49pl3ynln4li4i92pzg7wb-nixos-system-machine-test2126machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 1.36 seconds)2127(finished: subtest: targets, in 7.02 seconds)2128subtest: paths2129machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&12130machine # [ 52.561805] nixos[4263]: switching to system configuration /nix/store/0dfz8iya0gh2x25k3h71jxc6wwjxqayx-nixos-system-machine-test2131machine # [ 52.564465] systemd[1]: Stopped target Remote File Systems.2132machine # [ 52.565836] systemd[1]: test-timer.timer: Deactivated successfully.2133machine # [ 52.567149] systemd[1]: Stopped test-timer.timer.2134machine # [ 52.568279] systemd[1]: Stopped target Local File Systems.2135machine # [ 52.789516] systemd[1]: Reload requested from client PID 4263 ('.switch-to-conf') (unit backdoor.service)...2136machine # [ 52.791158] systemd[1]: Reloading...2137machine # [ 53.041964] systemd[1]: Reloading finished in 248 ms.2138machine # [ 53.098302] systemd[623]: Reexecution requested from client PID 4332 ('switch-to-confi')...2139machine # [ 53.100661] systemd[623]: Reexecuting.2140machine # [ 53.123247] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2141machine # [ 53.188643] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2142machine # [ 53.195805] systemd[623]: Reload requested from client PID 4332 ('switch-to-confi')...2143machine # [ 53.197725] systemd[623]: Reloading...2144machine # [ 53.276603] systemd[623]: Reloading finished in 77 ms.2145machine # [ 53.281864] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2146machine # [ 53.288352] systemd[623]: Stopped Run user-specific NixOS activation.2147machine # [ 53.290065] systemd[623]: Stopping Run user-specific NixOS activation...2148machine # [ 53.291816] systemd[623]: Starting Run user-specific NixOS activation...2149machine # [ 53.311105] systemd[623]: Finished Run user-specific NixOS activation.2150machine # [ 53.314218] systemd[1]: Stopped target Reactivate sysinit units.2151machine # [ 53.315489] systemd[1]: Stopping Reactivate sysinit units...2152machine # [ 53.316641] systemd[1]: Reached target Reactivate sysinit units.2153machine # [ 53.318876] systemd[1]: Reached target Local File Systems.2154machine # [ 53.320989] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2155machine # [ 53.323352] systemd[1]: Starting Create SUID/SGID Wrappers...2156machine # [ 53.325338] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2157machine # [ 53.327190] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672158machine # [ 53.329171] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2159machine # [ 53.330516] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2160machine # [ 53.331806] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2161machine # [ 53.338363] systemd[1]: Reached target Remote File Systems.2162machine # [ 53.343486] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2163machine # [ 53.345132] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2164machine # [ 53.471616] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2165machine # [ 53.473229] systemd[1]: Finished Create SUID/SGID Wrappers.2166machine # [ 53.476077] systemd[1]: Started test-watch.path.2167machine # [ 53.478215] systemd[1]: Starting linger-users.service...2168machine # [ 53.506962] systemd[1]: linger-users.service: Deactivated successfully.2169machine # [ 53.508441] systemd[1]: Finished linger-users.service.2170machine # [ 53.765528] nixos[4263]: finished switching to system configuration /nix/store/0dfz8iya0gh2x25k3h71jxc6wwjxqayx-nixos-system-machine-test2171machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 1.43 seconds)2172machine: must fail: test -f /testpath-modified2173machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2174machine: must succeed: touch /testpath2175machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2176machine: waiting for success: test -f /testpath-modified2177machine # [ 53.823314] systemd[1]: Starting test-watch.service...2178machine # [ 53.847966] systemd[1]: Finished test-watch.service.2179machine: (finished: waiting for success: test -f /testpath-modified, in 1.04 seconds)2180machine: must succeed: rm /testpath /testpath-modified2181machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.01 seconds)2182machine # [ 54.890784] systemd[1]: test-watch.service: Deactivated successfully.2183machine # [ 54.892397] systemd[1]: Stopped test-watch.service.2184machine # Stopping 'test-watch.service', but its triggering units are still active:2185machine # test-watch.path2186machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12187machine # [ 55.081729] nixos[4428]: switching to system configuration /nix/store/zl297p0ag36gkbvdibjd5li8kf0i2fsw-nixos-system-machine-test2188machine # [ 55.084248] systemd[1]: Stopped target Remote File Systems.2189machine # [ 55.085844] systemd[1]: Stopped target Local File Systems.2190machine # [ 55.312641] systemd[1]: Reload requested from client PID 4428 ('.switch-to-conf') (unit backdoor.service)...2191machine # [ 55.314340] systemd[1]: Reloading...2192machine # [ 55.565976] systemd[1]: Reloading finished in 250 ms.2193machine # [ 55.627711] systemd[623]: Reexecution requested from client PID 4498 ('switch-to-confi')...2194machine # [ 55.629122] systemd[623]: Reexecuting.2195machine # [ 55.650036] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 55.720937] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2197machine # [ 55.726153] systemd[623]: Reload requested from client PID 4498 ('switch-to-confi')...2198machine # [ 55.727890] systemd[623]: Reloading...2199machine # [ 55.797772] systemd[623]: Reloading finished in 69 ms.2200machine # [ 55.803827] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2201machine # [ 55.810244] systemd[623]: Stopped Run user-specific NixOS activation.2202machine # [ 55.811126] systemd[623]: Stopping Run user-specific NixOS activation...2203machine # [ 55.813186] systemd[623]: Starting Run user-specific NixOS activation...2204machine # [ 55.831965] systemd[623]: Finished Run user-specific NixOS activation.2205machine # [ 55.834830] systemd[1]: Stopped target Reactivate sysinit units.2206machine # [ 55.836430] systemd[1]: Stopping Reactivate sysinit units...2207machine # [ 55.837619] systemd[1]: Reached target Reactivate sysinit units.2208machine # [ 55.840578] systemd[1]: Reached target Local File Systems.2209machine # [ 55.841335] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2210machine # [ 55.843538] systemd[1]: Starting Create SUID/SGID Wrappers...2211machine # [ 55.845803] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2212machine # [ 55.847550] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672213machine # [ 55.849471] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2214machine # [ 55.850867] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2215machine # [ 55.851977] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 55.857925] systemd[1]: Reached target Remote File Systems.2217machine # [ 55.864568] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2218machine # [ 55.866239] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2219machine # [ 55.990286] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2220machine # [ 55.991890] systemd[1]: Finished Create SUID/SGID Wrappers.2221machine # [ 55.995857] systemd[1]: Starting linger-users.service...2222machine # [ 56.022855] systemd[1]: linger-users.service: Deactivated successfully.2223machine # [ 56.024275] systemd[1]: Finished linger-users.service.2224machine # [ 56.280633] nixos[4428]: finished switching to system configuration /nix/store/zl297p0ag36gkbvdibjd5li8kf0i2fsw-nixos-system-machine-test2225machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 1.39 seconds)2226machine: must succeed: touch /testpath2227machine: (finished: must succeed: touch /testpath, in 0.03 seconds)2228machine: must fail: test -f /testpath-modified2229machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2230machine: must succeed: touch /testpath22231machine: (finished: must succeed: touch /testpath2, in 0.01 seconds)2232machine: waiting for success: test -f /testpath-modified2233machine # [ 56.357250] systemd[1]: Starting test-watch.service...2234machine # [ 56.380505] systemd[1]: Finished test-watch.service.2235machine: (finished: waiting for success: test -f /testpath-modified, in 1.05 seconds)2236(finished: subtest: paths, in 5.06 seconds)2237subtest: slices2238machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2239machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2240machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12241machine # [ 57.629577] nixos[4593]: switching to system configuration /nix/store/8n252z8sr2kgx8xy46mnxjinz27rx276-nixos-system-machine-test2242machine # [ 57.632836] systemd[1]: test-watch.service: Deactivated successfully.2243machine # [ 57.634183] systemd[1]: Stopped test-watch.service.2244machine # [ 57.636187] systemd[1]: Stopped target Remote File Systems.2245machine # [ 57.638180] systemd[1]: Starting test-watch.service...2246machine # [ 57.639626] systemd[1]: Stopped target Local File Systems.2247machine # [ 57.641614] systemd[1]: test-watch.path: Deactivated successfully.2248machine # [ 57.643148] systemd[1]: Stopped test-watch.path.2249machine # [ 57.666221] systemd[1]: Finished test-watch.service.2250machine # [ 57.871213] systemd[1]: Reload requested from client PID 4593 ('.switch-to-conf') (unit backdoor.service)...2251machine # [ 57.873126] systemd[1]: Reloading...2252machine # [ 58.101313] systemd[1]: Reloading finished in 226 ms.2253machine # [ 58.157659] systemd[623]: Reexecution requested from client PID 4664 ('switch-to-confi')...2254machine # [ 58.160368] systemd[623]: Reexecuting.2255machine # [ 58.179993] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2256machine # [ 58.248869] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2257machine # [ 58.254321] systemd[623]: Reload requested from client PID 4664 ('switch-to-confi')...2258machine # [ 58.255946] systemd[623]: Reloading...2259machine # [ 58.325900] systemd[623]: Reloading finished in 68 ms.2260machine # [ 58.329827] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2261machine # [ 58.335976] systemd[623]: Stopped Run user-specific NixOS activation.2262machine # [ 58.337157] systemd[623]: Stopping Run user-specific NixOS activation...2263machine # [ 58.339627] systemd[623]: Starting Run user-specific NixOS activation...2264machine # [ 58.359345] systemd[623]: Finished Run user-specific NixOS activation.2265machine # [ 58.362441] systemd[1]: Stopped target Reactivate sysinit units.2266machine # [ 58.363702] systemd[1]: Stopping Reactivate sysinit units...2267machine # [ 58.364662] systemd[1]: Reached target Reactivate sysinit units.2268machine # [ 58.369428] systemd[1]: Reached target Local File Systems.2269machine # [ 58.371320] systemd[1]: Reached target Remote File Systems.2270machine # [ 58.372142] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2271machine # [ 58.375111] systemd[1]: Starting Create SUID/SGID Wrappers...2272machine # [ 58.375889] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2273machine # [ 58.376991] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672274machine # [ 58.378872] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2275machine # [ 58.383089] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2276machine # [ 58.384287] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2277machine # [ 58.386523] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2278machine # [ 58.388053] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2279machine # [ 58.514398] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2280machine # [ 58.516245] systemd[1]: Finished Create SUID/SGID Wrappers.2281machine # [ 58.519873] systemd[1]: Starting linger-users.service...2282machine # [ 58.546138] systemd[1]: linger-users.service: Deactivated successfully.2283machine # [ 58.547719] systemd[1]: Finished linger-users.service.2284machine # [ 58.804845] nixos[4593]: finished switching to system configuration /nix/store/8n252z8sr2kgx8xy46mnxjinz27rx276-nixos-system-machine-test2285machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 1.40 seconds)2286machine: must fail: systemctl start testservice.service2287machine # [ 58.856636] systemd[1]: Created slice Slice /testslice.2288machine # [ 59.003499] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02289machine # [ 59.006452] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.51 #1-NixOS PREEMPT(lazy)2290machine # [ 59.006460] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/20142291machine # [ 59.006463] Call Trace:2292machine # [ 59.006482] <TASK>2293machine # [ 59.006489] dump_stack_lvl+0x5d/0x802294machine # [ 59.006530] dump_header+0x43/0x1aa2295machine # [ 59.006563] out_of_memory.cold+0x8/0x782296machine # [ 59.006570] ? srso_alias_return_thunk+0x5/0xfbef52297machine # [ 59.006581] mem_cgroup_out_of_memory+0xc2/0xd02298machine # [ 59.006625] try_charge_memcg+0x473/0x6902299machine # [ 59.006643] obj_cgroup_charge_account+0xf8/0x4202300machine # [ 59.006656] __memcg_slab_post_alloc_hook+0x105/0x3702301machine # [ 59.006664] ? mem_cgroup_css_alloc+0x28f/0x8e02302machine # [ 59.006697] __kmalloc_cache_noprof+0x4ba/0x5f02303machine # [ 59.006715] ? srso_alias_return_thunk+0x5/0xfbef52304machine # [ 59.006719] ? __xa_alloc+0xa3/0x1402305machine # [ 59.006740] ? mem_cgroup_css_alloc+0x28f/0x8e02306machine # [ 59.006743] ? srso_alias_return_thunk+0x5/0xfbef52307machine # [ 59.006747] mem_cgroup_css_alloc+0x28f/0x8e02308machine # [ 59.006765] cgroup_apply_control_enable+0x132/0x3302309machine # [ 59.006802] cgroup_mkdir+0x1ef/0x5602310machine # [ 59.006816] kernfs_iop_mkdir+0x76/0xe02311machine # [ 59.006848] vfs_mkdir+0x19a/0x2702312machine # [ 59.006867] do_mkdirat+0x14d/0x1902313machine # [ 59.006880] __x64_sys_mkdir+0x49/0x702314machine # [ 59.006886] do_syscall_64+0xd6/0x7c02315machine # [ 59.006903] ? srso_alias_return_thunk+0x5/0xfbef52316machine # [ 59.006907] ? do_syscall_64+0x110/0x7c02317machine # [ 59.006911] ? srso_alias_return_thunk+0x5/0xfbef52318machine # [ 59.006920] ? __x64_sys_openat+0x61/0xa02319machine # [ 59.006929] ? srso_alias_return_thunk+0x5/0xfbef52320machine # [ 59.006933] ? do_syscall_64+0x110/0x7c02321machine # [ 59.006938] ? srso_alias_return_thunk+0x5/0xfbef52322machine # [ 59.006942] ? srso_alias_return_thunk+0x5/0xfbef52323machine # [ 59.006946] ? do_syscall_64+0x110/0x7c02324machine # [ 59.006950] ? srso_alias_return_thunk+0x5/0xfbef52325machine # [ 59.006954] ? srso_alias_return_thunk+0x5/0xfbef52326machine # [ 59.006959] entry_SYSCALL_64_after_hwframe+0x77/0x7f2327machine # [ 59.006964] RIP: 0033:0x7f693f6ef02b2328machine # [ 59.006985] Code: 0f 1e fa 48 89 f2 b9 00 01 00 00 48 89 fe bf 9c ff ff ff e9 27 c7 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 482329machine # [ 59.006988] RSP: 002b:00007ffe8a667048 EFLAGS: 00000246 ORIG_RAX: 00000000000000532330machine # [ 59.006998] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f693f6ef02b2331machine # [ 59.007001] RDX: 0000000000000000 RSI: 00000000000001ed RDI: 000055c9284288802332machine # [ 59.007003] RBP: 00007ffe8a667070 R08: 0000000000000000 R09: 00000000000000002333machine # [ 59.007006] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000000002334machine # [ 59.007008] R13: 00000000000001a8 R14: 0000000000000000 R15: 0000000000003e1a2335machine # [ 59.007020] </TASK>2336machine # [ 59.049374] memory: usage 0kB, limit 0kB, failcnt 182337machine # [ 59.050315] swap: usage 0kB, limit 9007199254740988kB, failcnt 02338machine # [ 59.051446] Memory cgroup stats for /testslice.slice:2339machine # [ 59.051471] anon 02340machine # [ 59.052966] file 02341machine # [ 59.053373] kernel 02342machine # [ 59.054090] kernel_stack 02343machine # [ 59.054613] pagetables 02344machine # [ 59.055368] sec_pagetables 02345machine # [ 59.056116] percpu 02346machine # [ 59.056580] sock 02347machine # [ 59.057267] vmalloc 02348machine # [ 59.058008] shmem 02349machine # [ 59.058417] zswap 02350machine # [ 59.059139] zswapped 02351machine # [ 59.059644] file_mapped 02352machine # [ 59.060391] file_dirty 02353machine # [ 59.061114] file_writeback 02354machine # [ 59.061688] swapcached 02355machine # [ 59.062438] anon_thp 02356machine # [ 59.063107] file_thp 02357machine # [ 59.063582] shmem_thp 02358machine # [ 59.064298] inactive_anon 02359machine # [ 59.065048] active_anon 02360machine # [ 59.065550] inactive_file 02361machine # [ 59.066287] active_file 02362machine # [ 59.067034] unevictable 02363machine # [ 59.067494] slab_reclaimable 02364machine # [ 59.068274] slab_unreclaimable 02365machine # [ 59.069078] slab 02366machine # [ 59.069475] hugetlb 02367machine # [ 59.070170] workingset_refault_anon 02368machine # [ 59.071028] workingset_refault_file 02369machine # [ 59.071630] workingset_activate_anon 02370machine # [ 59.072495] workingset_activate_file 02371machine # [ 59.073294] workingset_restore_anon 02372machine # [ 59.074147] workingset_restore_file 02373machine # [ 59.074746] workingset_nodereclaim 02374machine # [ 59.075603] pgdemote_kswapd 02375machine # [ 59.076324] pgdemote_direct 02376machine # [ 59.077090] pgdemote_khugepaged 02377machine # [ 59.077645] pgdemote_proactive 02378machine # [ 59.078329] pgpromote_success 02379machine # [ 59.079009] pgscan 02380machine # [ 59.079340] pgsteal 02381machine # [ 59.079725] pswpin 02382machine # [ 59.080328] pswpout 02383machine # [ 59.080715] pgscan_kswapd 02384machine # [ 59.081350] pgscan_direct 02385machine # [ 59.081978] pgscan_khugepaged 02386machine # [ 59.082382] pgscan_proactive 02387machine # [ 59.083054] pgsteal_kswapd 02388machine # [ 59.083446] pgsteal_direct 02389machine # [ 59.084062] pgsteal_khugepaged 02390machine # [ 59.084479] pgsteal_proactive 02391machine # [ 59.085116] pgfault 02392machine # [ 59.085445] pgmajfault 02393machine # [ 59.086041] pgrefill 02394machine # [ 59.086371] pgactivate 02395machine # [ 59.087004] pgdeactivate 02396machine # [ 59.087366] pglazyfree 02397machine # [ 59.088007] pglazyfreed 02398machine # [ 59.088357] swpin_zero 02399machine # [ 59.089006] swpout_zero 02400machine # [ 59.089358] zswpin 02401machine # [ 59.089719] zswpout 02402machine # [ 59.090370] zswpwb 02403machine # [ 59.090732] thp_fault_alloc 02404machine # [ 59.091359] thp_collapse_alloc 02405machine # [ 59.092015] thp_swpout 02406machine # [ 59.092359] thp_swpout_fallback 02407machine # [ 59.093066] numa_pages_migrated 02408machine # [ 59.093488] numa_pte_updates 02409machine # [ 59.094131] numa_hint_faults 02410machine # [ 59.094578] Tasks state (memory values in pages):2411machine # [ 59.095377] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2412machine # [ 59.096679] Out of memory and no killable processes...2413machine # [ 58.953834] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2414machine # [ 58.955447] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2415machine # [ 58.956850] systemd[1]: testservice.service: Failed with result 'resources'.2416machine # [ 58.958649] systemd[1]: Failed to start testservice.service.2417machine # Job for testservice.service failed because of unavailable resources or another system error.2418machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2419machine: (finished: must fail: systemctl start testservice.service, in 0.15 seconds)2420machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12421machine # [ 59.124956] nixos[4739]: switching to system configuration /nix/store/y27512k552id57nmm9pq9nnhshi0kr98-nixos-system-machine-test2422machine # [ 59.128198] systemd[1]: Stopped target Remote File Systems.2423machine # [ 59.128975] systemd[1]: Stopped target Local File Systems.2424machine # [ 59.337218] systemd[1]: Reload requested from client PID 4739 ('.switch-to-conf') (unit backdoor.service)...2425machine # [ 59.338893] systemd[1]: Reloading...2426machine # [ 59.559053] systemd[1]: Reloading finished in 219 ms.2427machine # [ 59.615397] systemd[623]: Reexecution requested from client PID 4808 ('switch-to-confi')...2428machine # [ 59.618091] systemd[623]: Reexecuting.2429machine # [ 59.637949] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2430machine # [ 59.704085] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2431machine # [ 59.709071] systemd[623]: Reload requested from client PID 4808 ('switch-to-confi')...2432machine # [ 59.711156] systemd[623]: Reloading...2433machine # [ 59.783799] systemd[623]: Reloading finished in 73 ms.2434machine # [ 59.788863] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2435machine # [ 59.795362] systemd[623]: Stopped Run user-specific NixOS activation.2436machine # [ 59.796336] systemd[623]: Stopping Run user-specific NixOS activation...2437machine # [ 59.798921] systemd[623]: Starting Run user-specific NixOS activation...2438machine # [ 59.818988] systemd[623]: Finished Run user-specific NixOS activation.2439machine # [ 59.822458] systemd[1]: Stopped target Reactivate sysinit units.2440machine # [ 59.823455] systemd[1]: Stopping Reactivate sysinit units...2441machine # [ 59.824895] systemd[1]: Reached target Reactivate sysinit units.2442machine # [ 59.826105] systemd[1]: Reached target Remote File Systems.2443machine # [ 59.830786] systemd[1]: Starting linger-users.service...2444machine # [ 59.835488] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2445machine # [ 59.836706] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2446machine # [ 59.839054] systemd[1]: Reached target Local File Systems.2447machine # [ 59.843205] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2448machine # [ 59.846833] systemd[1]: Starting Create SUID/SGID Wrappers...2449machine # [ 59.848395] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2450machine # [ 59.850111] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672451machine # [ 59.852567] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2452machine # [ 59.856129] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2453machine # [ 59.859384] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2454machine # [ 59.895905] systemd[1]: linger-users.service: Deactivated successfully.2455machine # [ 59.897564] systemd[1]: Finished linger-users.service.2456machine # [ 60.005148] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2457machine # [ 60.006815] systemd[1]: Finished Create SUID/SGID Wrappers.2458machine # [ 60.264813] nixos[4739]: finished switching to system configuration /nix/store/y27512k552id57nmm9pq9nnhshi0kr98-nixos-system-machine-test2459machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 1.30 seconds)2460machine: must succeed: systemctl start testservice.service2461machine # [ 60.312256] systemd[1]: Starting testservice.service...2462machine # [ 60.332831] systemd[1]: Finished testservice.service.2463machine: (finished: must succeed: systemctl start testservice.service, in 0.06 seconds)2464machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2465machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.01 seconds)2466(finished: subtest: slices, in 2.95 seconds)2467subtest: dbus reloads2468machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&12469machine # [ 60.495697] nixos[4888]: switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test2470machine # [ 60.498119] systemd[1]: testservice.service: Deactivated successfully.2471machine # [ 60.498945] systemd[1]: Stopped testservice.service.2472machine # [ 60.502614] systemd[1]: Removed slice Slice /testslice.2473machine # [ 60.504372] systemd[1]: Stopped target Local File Systems.2474machine # [ 60.505865] systemd[1]: Stopped target Remote File Systems.2475machine # [ 60.705234] systemd[1]: Reload requested from client PID 4888 ('.switch-to-conf') (unit backdoor.service)...2476machine # [ 60.706828] systemd[1]: Reloading...2477machine # [ 60.933187] systemd[1]: Reloading finished in 224 ms.2478machine # [ 60.993882] systemd[623]: Reexecution requested from client PID 4957 ('switch-to-confi')...2479machine # [ 60.996434] systemd[623]: Reexecuting.2480machine # [ 61.016865] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2481machine # [ 61.086259] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2482machine # [ 61.090852] systemd[623]: Reload requested from client PID 4957 ('switch-to-confi')...2483machine # [ 61.093053] systemd[623]: Reloading...2484machine # [ 61.156821] systemd[623]: Reloading finished in 64 ms.2485machine # [ 61.162093] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2486machine # [ 61.169312] systemd[623]: Stopped Run user-specific NixOS activation.2487machine # [ 61.171130] systemd[623]: Stopping Run user-specific NixOS activation...2488machine # [ 61.172545] systemd[623]: Starting Run user-specific NixOS activation...2489machine # [ 61.189974] systemd[623]: Finished Run user-specific NixOS activation.2490machine # [ 61.192992] systemd[1]: Stopped target Reactivate sysinit units.2491machine # [ 61.194522] systemd[1]: Stopping Reactivate sysinit units...2492machine # [ 61.195792] systemd[1]: Reached target Reactivate sysinit units.2493machine # [ 61.197385] systemd[1]: Reached target Local File Systems.2494machine # [ 61.199203] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2495machine # [ 61.201789] systemd[1]: Starting Create SUID/SGID Wrappers...2496machine # [ 61.203280] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2497machine # [ 61.205153] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672498machine # [ 61.208085] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2499machine # [ 61.210209] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2500machine # [ 61.212544] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2501machine # [ 61.213630] systemd[1]: Reached target Remote File Systems.2502machine # [ 61.218057] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2503machine # [ 61.219231] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2504machine # [ 61.334950] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2505machine # [ 61.336534] systemd[1]: Finished Create SUID/SGID Wrappers.2506machine # [ 61.340278] systemd[1]: Starting linger-users.service...2507machine # [ 61.364836] systemd[1]: linger-users.service: Deactivated successfully.2508machine # [ 61.366235] systemd[1]: Finished linger-users.service.2509machine # [ 61.620543] nixos[4888]: finished switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test2510machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.28 seconds)2511machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12512machine # [ 61.793503] nixos[5028]: switching to system configuration /nix/store/dnwjb774lq4z7kpa581r1f352n6w8nyx-nixos-system-machine-test2513machine # [ 61.795472] systemd[1]: Stopped target Local File Systems.2514machine # [ 61.797218] systemd[1]: Stopped target Remote File Systems.2515machine # [ 62.022875] systemd[1]: Reload requested from client PID 5028 ('.switch-to-conf') (unit backdoor.service)...2516machine # [ 62.024643] systemd[1]: Reloading...2517machine # [ 62.342494] systemd[1]: Reloading finished in 316 ms.2518machine # [ 62.402986] systemd[623]: Reexecution requested from client PID 5097 ('switch-to-confi')...2519machine # [ 62.405380] systemd[623]: Reexecuting.2520machine # [ 62.426081] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2521machine # [ 62.497928] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2522machine # [ 62.503536] systemd[623]: Reload requested from client PID 5097 ('switch-to-confi')...2523machine # [ 62.504833] systemd[623]: Reloading...2524machine # [ 62.574092] systemd[623]: Reloading finished in 68 ms.2525machine # [ 62.579811] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2526machine # [ 62.586216] systemd[623]: Stopped Run user-specific NixOS activation.2527machine # [ 62.587151] systemd[623]: Stopping Run user-specific NixOS activation...2528machine # [ 62.589222] systemd[623]: Starting Run user-specific NixOS activation...2529machine # [ 62.608547] systemd[623]: Finished Run user-specific NixOS activation.2530machine # [ 62.611779] systemd[1]: Stopped target Reactivate sysinit units.2531machine # [ 62.612911] systemd[1]: Stopping Reactivate sysinit units...2532machine # [ 62.614218] systemd[1]: Reached target Reactivate sysinit units.2533machine # [ 62.616923] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.2534machine # [ 62.618511] systemd[1]: Reloading D-Bus System Message Bus...2535machine # [ 62.622402] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...2536machine # [ 62.624300] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'2537machine # [ 62.625471] dbus-broker-launch[502]: Invalid user-name in /nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2538machine # [ 62.634374] systemd[1]: Reloaded D-Bus System Message Bus.2539machine # [ 62.637695] systemd[1]: Reached target Remote File Systems.2540machine # [ 62.640583] systemd[1]: Starting linger-users.service...2541machine # [ 62.642722] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2542machine # [ 62.644881] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2543machine # [ 62.648129] systemd[1]: Reached target Local File Systems.2544machine # [ 62.653100] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2545machine # [ 62.657227] systemd[1]: Starting Create SUID/SGID Wrappers...2546machine # [ 62.658909] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2547machine # [ 62.660243] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672548machine # [ 62.662475] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2549machine # [ 62.664162] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2550machine # [ 62.665504] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2551machine # [ 62.706330] systemd[1]: linger-users.service: Deactivated successfully.2552machine # [ 62.707876] systemd[1]: Finished linger-users.service.2553machine # [ 62.811685] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2554machine # [ 62.814588] systemd[1]: Finished Create SUID/SGID Wrappers.2555machine # [ 63.071364] nixos[5028]: finished switching to system configuration /nix/store/dnwjb774lq4z7kpa581r1f352n6w8nyx-nixos-system-machine-test2556machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 1.45 seconds)2557(finished: subtest: dbus reloads, in 2.74 seconds)2558subtest: generators2559machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12560machine # [ 63.284138] nixos[5168]: switching to system configuration /nix/store/wpffwl49kjgi4q780qxinkahycwx9g25-nixos-system-machine-test2561machine # [ 63.286709] systemd[1]: Stopped target Remote File Systems.2562machine # [ 63.288478] systemd[1]: Stopped target Local File Systems.2563machine # [ 63.511194] systemd[1]: Reload requested from client PID 5168 ('.switch-to-conf') (unit backdoor.service)...2564machine # [ 63.513156] systemd[1]: Reloading...2565machine # [ 63.748068] systemd[1]: Reloading finished in 233 ms.2566machine # [ 63.809321] systemd[623]: Reexecution requested from client PID 5239 ('switch-to-confi')...2567machine # [ 63.811792] systemd[623]: Reexecuting.2568machine # [ 63.831641] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2569machine # [ 63.902200] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2570machine # [ 63.907437] systemd[623]: Reload requested from client PID 5239 ('switch-to-confi')...2571machine # [ 63.908740] systemd[623]: Reloading...2572machine # [ 63.976480] systemd[623]: Reloading finished in 66 ms.2573machine # [ 63.981844] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2574machine # [ 63.987797] systemd[623]: Stopped Run user-specific NixOS activation.2575machine # [ 63.988783] systemd[623]: Stopping Run user-specific NixOS activation...2576machine # [ 63.991098] systemd[623]: Starting Run user-specific NixOS activation...2577machine # [ 64.009696] systemd[623]: Finished Run user-specific NixOS activation.2578machine # [ 64.012604] systemd[1]: Stopped target Reactivate sysinit units.2579machine # [ 64.013851] systemd[1]: Stopping Reactivate sysinit units...2580machine # [ 64.014816] systemd[1]: Reached target Reactivate sysinit units.2581machine # [ 64.017456] dbus-broker-launch[502]: Caught SIGHUP, trigger reload.2582machine # [ 64.019210] systemd[1]: Reloading D-Bus System Message Bus...2583machine # [ 64.024581] dbus-broker-launch[502]: Looking up NSS user entry for 'systemd-timesync'...2584machine # [ 64.026528] dbus-broker-launch[502]: NSS returned no entry for 'systemd-timesync'2585machine # [ 64.027724] dbus-broker-launch[502]: Invalid user-name in /nix/store/mycj03f19377d5fqilgfq9lvmsy4pfl0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2586machine # [ 64.037403] systemd[1]: Reloaded D-Bus System Message Bus.2587machine # [ 64.039734] systemd[1]: Reached target Local File Systems.2588machine # [ 64.043287] systemd[1]: Reached target Remote File Systems.2589machine # [ 64.045855] systemd[1]: Starting linger-users.service...2590machine # [ 64.050185] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2591machine # [ 64.051563] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2592machine # [ 64.062268] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2593machine # [ 64.066064] systemd[1]: Starting Create SUID/SGID Wrappers...2594machine # [ 64.068496] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2595machine # [ 64.070446] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672596machine # [ 64.073118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2597machine # [ 64.076109] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2598machine # [ 64.077676] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2599machine # [ 64.110140] systemd[1]: linger-users.service: Deactivated successfully.2600machine # [ 64.111561] systemd[1]: Finished linger-users.service.2601machine # [ 64.216110] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2602machine # [ 64.217705] systemd[1]: Finished Create SUID/SGID Wrappers.2603machine # [ 64.472663] nixos[5168]: finished switching to system configuration /nix/store/wpffwl49kjgi4q780qxinkahycwx9g25-nixos-system-machine-test2604machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 1.40 seconds)2605machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2606machine # [ 64.495508] systemd[1]: Started simple-generated.service.2607machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.03 seconds)2608machine: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&12609machine # [ 64.687230] nixos[5317]: switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test2610machine # [ 64.689390] systemd[1]: Stopped target Remote File Systems.2611machine # [ 64.691083] systemd[1]: Stopped target Local File Systems.2612machine # [ 64.892390] systemd[1]: Reload requested from client PID 5317 ('.switch-to-conf') (unit backdoor.service)...2613machine # [ 64.893806] systemd[1]: Reloading...2614machine # [ 65.119751] systemd[1]: Reloading finished in 225 ms.2615machine # [ 65.182474] systemd[623]: Reexecution requested from client PID 5387 ('switch-to-confi')...2616machine # [ 65.184251] systemd[623]: Reexecuting.2617machine # [ 65.203944] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2618machine # [ 65.274501] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2619machine # [ 65.279909] systemd[623]: Reload requested from client PID 5387 ('switch-to-confi')...2620machine # [ 65.281867] systemd[623]: Reloading...2621machine # [ 65.351938] systemd[623]: Reloading finished in 69 ms.2622machine # [ 65.357828] systemd[623]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2623machine # [ 65.363847] systemd[623]: Stopped Run user-specific NixOS activation.2624machine # [ 65.364974] systemd[623]: Stopping Run user-specific NixOS activation...2625machine # [ 65.367507] systemd[623]: Starting Run user-specific NixOS activation...2626machine # [ 65.386546] systemd[623]: Finished Run user-specific NixOS activation.2627machine # [ 65.389587] systemd[1]: Stopped target Reactivate sysinit units.2628machine # [ 65.390992] systemd[1]: Stopping Reactivate sysinit units...2629machine # [ 65.392354] systemd[1]: Reached target Reactivate sysinit units.2630machine # [ 65.394338] systemd[1]: Reached target Local File Systems.2631machine # [ 65.397937] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2632machine # [ 65.399906] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2633machine # [ 65.402264] systemd[1]: Reached target Remote File Systems.2634machine # [ 65.408384] systemd[1]: Starting linger-users.service...2635machine # [ 65.411994] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2636machine # [ 65.415896] systemd[1]: Starting Create SUID/SGID Wrappers...2637machine # [ 65.416805] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2638machine # [ 65.418157] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672639machine # [ 65.420397] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2640machine # [ 65.422092] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2641machine # [ 65.423423] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2642machine # [ 65.464437] systemd[1]: linger-users.service: Deactivated successfully.2643machine # [ 65.466042] systemd[1]: Finished linger-users.service.2644machine # [ 65.566225] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2645machine # [ 65.568540] systemd[1]: Finished Create SUID/SGID Wrappers.2646machine # [ 65.824549] nixos[5317]: finished switching to system configuration /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test2647machine: (finished: must succeed: /nix/store/krkhzgjhwsbpl79xqqi5x0nmjgsciwmi-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 1.33 seconds)2648machine: must succeed: systemctl is-active simple-generated.service2649machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.03 seconds)2650(finished: subtest: generators, in 2.78 seconds)2651(finished: run the VM test script, in 66.75 seconds)2652test script finished in 66.80s2653cleanup2654kill QemuMachine (pid 45)2655machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/d64q19q1xjdwfhqx6czvrjgrhq0n3lcc-python3-3.14.7/bin/python3.14)2656machine # [2026-09-16T03:03:51Z INFO virtiofsd] Client disconnected, shutting down2657machine # [2026-09-16T03:03:51Z INFO virtiofsd] Client disconnected, shutting down2658machine # [2026-09-16T03:03:51Z INFO virtiofsd] Client disconnected, shutting down2659(finished: cleanup, in 0.19 seconds)2660additionally exposed symbols:2661 machine, other,2662 vlan1,2663 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