vm-test-run-switch-test-units
checks.x86_64-linux.switchTest-units
· build #19
· raw
1tribuchet: building on jamie2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)5Test will time out and terminate in 3600.0 seconds6run the VM test script7machine: waiting for unit multi-user.target8machine: waiting for the VM to finish booting9machine: starting vm10machine: QEMU running (pid 45)11machine # Disk image does not exist, creating the virtualisation disk image...12machine # Formatting '/build/vm-state-machine/tmp.gIDqls0LQZ', 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: 224a9abd-006e-4a38-aecd-0d821e9d21c417machine # Superblock backups stored on blocks:18machine # 32768, 98304, 163840, 22937619machine # 20machine # Allocating group tables: 0/8 done21machine # Writing inode tables: 0/8 done22machine # Creating journal (8192 blocks): done23machine # Writing superblocks and filesystem accounting information: 0/8 done24machine # 25machine # Virtualisation disk image created.26machine # SeaBIOS (version rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org)27machine # 28machine # 29machine # iPXE (http://ipxe.org) 00:02.0 CA00 PCI2.10 PnP PMM+3EFCC730+3EF2C730 CA0030machine # Press Ctrl-B to configure iPXE (PCI 00:02.0)...31machine # 32machine # 33machine # 34machine # 35machine # iPXE (http://ipxe.org) 00:08.0 CB00 PCI2.10 PnP PMM 3EFCC730 3EF2C730 CB0036machine # Press Ctrl-B to configure iPXE (PCI 00:08.0)...37machine # 38machine # 39machine # Booting from ROM...40machine # Probing EDD (edd=off to disable)... o[ 0.000000] Linux version 6.18.48 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Fri Aug 28 06:22:54 UTC 202641machine # [ 0.000000] Command line: console=ttyS0 console=tty0 panic=1 boot.panic_on_fail clocksource=acpi_pm root=fstab loglevel=7 net.ifnames=0 lsm=landlock,yama,bpf init=/nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/init regInfo=/nix/store/zph20x4l9vwqqnm8dz038234jcl9ppwi-closure-info/registration console=ttyS0,115200n8 console=tty042machine # [ 0.000000] BIOS-provided physical RAM map:43machine # [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable44machine # [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved45machine # [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved46machine # [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffd7fff] usable47machine # [ 0.000000] BIOS-e820: [mem 0x000000003ffd8000-0x000000003fffffff] reserved48machine # [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved49machine # [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved50machine # [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved51machine # [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved52machine # [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved53machine # [ 0.000000] NX (Execute Disable) protection: active54machine # [ 0.000000] APIC: Static calls initialized55machine # [ 0.000000] SMBIOS 2.8 present.56machine # [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/201457machine # [ 0.000000] DMI: Memory slots populated: 1/158machine # [ 0.000000] Hypervisor detected: KVM59machine # [ 0.000000] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000060machine # [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d0061machine # [ 0.000000] kvm-clock: using sched offset of 424188251 cycles62machine # [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns63machine # [ 0.000005] tsc: Detected 2400.010 MHz processor64machine # [ 0.000812] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000838] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000841] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002739] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002750] Using GB pages for direct mapping69machine # [ 0.002790] RAMDISK: [mem 0x3e429000-0x3ffcffff]70machine # [ 0.002798] ACPI: Early table checksum verification disabled71machine # [ 0.002801] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002805] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002810] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002817] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002819] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002820] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002822] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002824] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002825] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002826] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002827] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002828] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002829] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002829] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002829] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002830] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003055] No NUMA configuration found88machine # [ 0.003055] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003058] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.005127] Zone ranges:91machine # [ 0.005128] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.005129] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.005130] Normal empty94machine # [ 0.005131] Device empty95machine # [ 0.005131] Movable zone start for each node96machine # [ 0.005132] Early memory node ranges97machine # [ 0.005133] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.005134] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.005135] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.005152] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.005396] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.021680] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.022128] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.022138] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.022163] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.022166] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.022167] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.022168] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.022169] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.022170] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.022172] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.022173] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.022176] TSC deadline timer available114machine # [ 0.022180] CPU topo: Max. logical packages: 1115machine # [ 0.022180] CPU topo: Max. logical dies: 1116machine # [ 0.022181] CPU topo: Max. dies per package: 1117machine # [ 0.022184] CPU topo: Max. threads per core: 1118machine # [ 0.022185] CPU topo: Num. cores per package: 1119machine # [ 0.022185] CPU topo: Num. threads per package: 1120machine # [ 0.022186] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.022202] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.022230] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.022231] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.022232] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.022233] Booting paravirtualized kernel on KVM126machine # [ 0.022236] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.026672] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.028672] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.028714] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.028716] 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/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/init regInfo=/nix/store/zph20x4l9vwqqnm8dz038234jcl9ppwi-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.028805] Unknown kernel command line parameters "regInfo=/nix/store/zph20x4l9vwqqnm8dz038234jcl9ppwi-closure-info/registration", will be passed to user space.132machine # [ 0.028818] random: crng init done133machine # [ 0.028819] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.028839] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.028851] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.028881] Fallback order for Node 0: 0137machine # [ 0.028884] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.028885] Policy zone: DMA32139machine # [ 0.031135] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.033433] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.035616] allocated 2097152 bytes of page_ext142machine # [ 0.045013] ftrace: allocating 48733 entries in 192 pages143machine # [ 0.045015] ftrace: allocated 192 pages with 2 groups144machine # [ 0.045801] Dynamic Preempt: lazy145machine # [ 0.045934] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.045935] rcu: RCU event tracing is enabled.147machine # [ 0.045935] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.045936] Trampoline variant of Tasks RCU enabled.149machine # [ 0.045937] Rude variant of Tasks RCU enabled.150machine # [ 0.045937] Tracing variant of Tasks RCU enabled.151machine # [ 0.045938] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.045939] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.045953] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.045954] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.045955] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.050185] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.050495] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.050500] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.050600] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.054094] Console: colour VGA+ 80x25161machine # [ 0.054097] printk: legacy console [tty0] enabled162machine # [ 0.083002] printk: legacy console [ttyS0] enabled163machine # [ 0.189263] ACPI: Core revision 20250807164machine # [ 0.190181] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.191814] APIC: Switch to symmetric I/O mode setup166machine # [ 0.192867] x2apic enabled167machine # [ 0.193600] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.195435] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.196460] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns170machine # [ 0.198216] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)171machine # [ 0.199295] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.201296] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.202211] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.203214] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.204211] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.206211] Transient Scheduler Attacks: Vulnerable: No microcode177machine # [ 0.207210] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.208211] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.209210] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.211217] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.212212] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.213212] active return thunk: srso_alias_return_thunk183machine # [ 0.214232] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.215211] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.217029] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.218211] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.219210] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.220211] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.221211] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.222211] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256191machine # [ 0.223210] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64192machine # [ 0.224210] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512193machine # [ 0.225210] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024194machine # [ 0.226211] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8195machine # [ 0.227210] x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format.196machine # [ 0.261750] Freeing SMP alternatives memory: 44K197machine # [ 0.262213] pid_max: default: 32768 minimum: 301198machine # [ 0.263294] LSM: initializing lsm=capability,landlock,yama,bpf,ima199machine # [ 0.264309] landlock: Up and running.200machine # [ 0.265211] Yama: becoming mindful.201machine # [ 0.266045] LSM support for eBPF active202machine # [ 0.266778] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)203machine # [ 0.267229] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)204machine # [ 0.270500] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)205machine # [ 0.271727] Performance Events: Fam17h+ core perfctr, AMD PMU driver.206machine # [ 0.272219] ... version: 2207machine # [ 0.272974] ... bit width: 48208machine # [ 0.273213] ... generic counters: 6209machine # [ 0.273973] ... generic bitmap: 000000000000003f210machine # [ 0.274213] ... fixed-purpose counters: 0211machine # [ 0.275028] ... fixed-purpose bitmap: 0000000000000000212machine # [ 0.275954] ... value mask: 0000ffffffffffff213machine # [ 0.276213] ... max period: 00007fffffffffff214machine # [ 0.277143] ... global_ctrl mask: 000000000000003f215machine # [ 0.278046] signal: max sigframe size: 3376216machine # [ 0.278864] rcu: Hierarchical SRCU implementation.217machine # [ 0.279216] rcu: Max phase no-delay instances is 400.218machine # [ 0.284531] smp: Bringing up secondary CPUs ...219machine # [ 0.285227] smp: Brought up 1 node, 1 CPU220machine # [ 0.285910] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)221machine # [ 0.286365] Memory: 941800K/1048024K available (17215K kernel code, 2726K rwdata, 13580K rodata, 3644K init, 2988K bss, 98840K reserved, 0K cma-reserved)222machine # [ 0.287419] devtmpfs: initialized223machine # [ 0.288238] x86/mm: Memory block size: 128MB224machine # [ 0.289977] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)225machine # [ 0.291180] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).226machine # [ 0.292282] pinctrl core: initialized pinctrl subsystem227machine # [ 0.293503] PM: RTC time: 02:41:37, date: 2026-09-03228machine # [ 0.296733] NET: Registered PF_NETLINK/PF_ROUTE protocol family229machine # [ 0.297566] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations230machine # [ 0.298228] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations231machine # [ 0.299506] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations232machine # [ 0.300223] audit: initializing netlink subsys (disabled)233machine # [ 0.301400] thermal_sys: Registered thermal governor 'fair_share'234machine # [ 0.301402] thermal_sys: Registered thermal governor 'bang_bang'235machine # [ 0.302214] thermal_sys: Registered thermal governor 'step_wise'236machine # [ 0.303216] audit: type=2000 audit(1788403298.571:1): state=initialized audit_enabled=0 res=1237machine # [ 0.305216] thermal_sys: Registered thermal governor 'user_space'238machine # [ 0.305218] thermal_sys: Registered thermal governor 'power_allocator'239machine # [ 0.306237] cpuidle: using governor menu240machine # [ 0.308460] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5241machine # [ 0.309445] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]242machine # [ 0.310216] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry243machine # [ 0.311224] PCI: Using configuration type 1 for base access244machine # [ 0.312363] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.245machine # [ 0.317506] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages246machine # [ 0.318215] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page247machine # [ 0.323215] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages248machine # [ 0.324213] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page249machine # [ 0.334544] ACPI: Added _OSI(Module Device)250machine # [ 0.335215] ACPI: Added _OSI(Processor Device)251machine # [ 0.335973] ACPI: Added _OSI(Processor Aggregator Device)252machine # [ 0.344521] ACPI: 1 ACPI AML tables successfully acquired and loaded253machine # [ 0.348625] ACPI: Interpreter enabled254machine # [ 0.349229] ACPI: PM: (supports S0 S3 S4 S5)255machine # [ 0.349970] ACPI: Using IOAPIC for interrupt routing256machine # [ 0.350266] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug257machine # [ 0.351214] PCI: Using E820 reservations for host bridge windows258machine # [ 0.354357] ACPI: Enabled 2 GPEs in block 00 to 3F259machine # [ 0.362970] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])260machine # [ 0.363221] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]261machine # [ 0.365147] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]262machine # [ 0.366280] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]263machine # [ 0.367556] PCI host bridge to bus 0000:00264machine # [ 0.368219] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]265machine # [ 0.369213] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]266machine # [ 0.370213] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]267machine # [ 0.371213] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]268machine # [ 0.372213] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]269machine # [ 0.373213] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]270machine # [ 0.374215] pci_bus 0000:00: root bus resource [bus 00-ff]271machine # [ 0.375199] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint272machine # [ 0.376655] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint273machine # [ 0.379254] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]274machine # [ 0.380226] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]275machine # [ 0.381235] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]276machine # [ 0.382496] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]277machine # [ 0.383963] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint278machine # [ 0.386240] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]279machine # [ 0.387118] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]280machine # [ 0.387996] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]281machine # [ 0.389118] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]282machine # [ 0.390570] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint283machine # [ 0.393242] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]284machine # [ 0.394136] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]285machine # [ 0.395020] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]286machine # [ 0.396656] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint287machine # [ 0.398221] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]288machine # [ 0.399097] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]289machine # [ 0.399995] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]290machine # [ 0.401788] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.403221] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]292machine # [ 0.404102] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]293machine # [ 0.405018] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]294machine # [ 0.406752] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.408242] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]296machine # [ 0.409220] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]297machine # [ 0.410229] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]298machine # [ 0.411758] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint299machine # [ 0.413221] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]300machine # [ 0.414131] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]301machine # [ 0.415009] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]302machine # [ 0.416691] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint303machine # [ 0.419192] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]304machine # [ 0.419913] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]305machine # [ 0.420235] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]306machine # [ 0.421219] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]307machine # [ 0.422771] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint308machine # [ 0.424245] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]309machine # [ 0.425235] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]310machine # [ 0.426764] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint311machine # [ 0.428221] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]312machine # [ 0.429099] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]313machine # [ 0.429977] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]314machine # [ 0.431666] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint315machine # [ 0.433221] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]316machine # [ 0.434220] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]317machine # [ 0.435235] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]318machine # [ 0.436788] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint319machine # [ 0.437756] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]320machine # [ 0.438409] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint321machine # [ 0.439740] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]322machine # [ 0.440438] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.443767] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]324machine # [ 0.444452] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint325machine # [ 0.446099] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]326machine # [ 0.447225] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint327machine # [ 0.448501] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO328machine # [ 0.449485] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint329machine # [ 0.451768] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]330machine # [ 0.452220] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]331machine # [ 0.453514] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint332machine # [ 0.454961] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]333machine # [ 0.458127] ACPI: PCI: Interrupt link LNKA configured for IRQ 10334machine # [ 0.459121] ACPI: PCI: Interrupt link LNKB configured for IRQ 10335machine # [ 0.460101] ACPI: PCI: Interrupt link LNKC configured for IRQ 11336machine # [ 0.461095] ACPI: PCI: Interrupt link LNKD configured for IRQ 11337machine # [ 0.462086] ACPI: PCI: Interrupt link LNKE configured for IRQ 10338machine # [ 0.463166] ACPI: PCI: Interrupt link LNKF configured for IRQ 10339machine # [ 0.464112] ACPI: PCI: Interrupt link LNKG configured for IRQ 11340machine # [ 0.465109] ACPI: PCI: Interrupt link LNKH configured for IRQ 11341machine # [ 0.466056] ACPI: PCI: Interrupt link GSIA configured for IRQ 16342machine # [ 0.467016] ACPI: PCI: Interrupt link GSIB configured for IRQ 17343machine # [ 0.468020] ACPI: PCI: Interrupt link GSIC configured for IRQ 18344machine # [ 0.469111] ACPI: PCI: Interrupt link GSID configured for IRQ 19345machine # [ 0.470038] ACPI: PCI: Interrupt link GSIE configured for IRQ 20346machine # [ 0.471060] ACPI: PCI: Interrupt link GSIF configured for IRQ 21347machine # [ 0.472012] ACPI: PCI: Interrupt link GSIG configured for IRQ 22348machine # [ 0.473005] ACPI: PCI: Interrupt link GSIH configured for IRQ 23349machine # [ 0.474981] iommu: Default domain type: Translated350machine # [ 0.475221] iommu: DMA domain TLB invalidation policy: lazy mode351machine # [ 0.476450] ACPI: bus type USB registered352machine # [ 0.477207] usbcore: registered new interface driver usbfs353machine # [ 0.478140] usbcore: registered new interface driver hub354machine # [ 0.478925] usbcore: registered new device driver usb355machine # [ 0.480060] NetLabel: Initializing356machine # [ 0.480648] NetLabel: domain hash size = 128357machine # [ 0.481221] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO358machine # [ 0.482207] NetLabel: unlabeled traffic allowed by default359machine # [ 0.483185] PCI: Using ACPI for IRQ routing360machine # [ 0.526857] pci 0000:00:01.0: vgaarb: setting as boot VGA device361machine # [ 0.527209] pci 0000:00:01.0: vgaarb: bridge control possible362machine # [ 0.527209] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none363machine # [ 0.527218] vgaarb: loaded364machine # [ 0.527942] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0365machine # [ 0.528215] hpet0: 3 comparators, 64-bit 100.000000 MHz counter366machine # [ 0.533301] clocksource: Switched to clocksource kvm-clock367machine # [ 0.537550] VFS: Disk quotas dquot_6.6.0368machine # [ 0.538293] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)369machine # [ 0.539653] pnp: PnP ACPI init370machine # [ 0.540474] ACPI: IRQ 4 override to edge(!), high(!)371machine # [ 0.541451] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved372machine # [ 0.543031] pnp: PnP ACPI: found 6 devices373machine # [ 0.550571] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns374machine # [ 0.552094] clocksource: Switched to clocksource acpi_pm375machine # [ 0.553111] NET: Registered PF_INET protocol family376machine # [ 0.554147] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)377machine # [ 0.570438] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)378machine # [ 0.572011] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)379machine # [ 0.573375] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)380machine # [ 0.574750] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)381machine # [ 0.576023] TCP: Hash tables configured (established 8192 bind 8192)382machine # [ 0.577150] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)383machine # [ 0.578471] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)384machine # [ 0.579627] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)385machine # [ 0.580880] NET: Registered PF_UNIX/PF_LOCAL protocol family386machine # [ 0.581894] NET: Registered PF_XDP protocol family387machine # [ 0.582781] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]388machine # [ 0.583835] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]389machine # [ 0.584876] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]390machine # [ 0.586016] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]391machine # [ 0.587174] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]392machine # [ 0.588303] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]393machine # [ 0.590168] ACPI: \_SB_.GSIA: Enabled at IRQ 16394machine # [ 0.592295] ACPI: \_SB_.GSIB: Enabled at IRQ 17395machine # [ 0.594255] ACPI: \_SB_.GSIC: Enabled at IRQ 18396machine # [ 0.596143] ACPI: \_SB_.GSID: Enabled at IRQ 19397machine # [ 0.597788] PCI: CLS 0 bytes, default 64398machine # [ 0.598682] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns399machine # [ 0.600562] Trying to unpack rootfs image as initramfs...400machine # [ 0.643442] Initialise system trusted keyrings401machine # [ 0.646777] workingset: timestamp_bits=40 max_order=18 bucket_order=0402machine # [ 0.669163] Key type asymmetric registered403machine # [ 0.669937] Asymmetric key parser 'x509' registered404machine # [ 0.672800] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)405machine # [ 0.674238] io scheduler mq-deadline registered406machine # [ 0.677735] io scheduler kyber registered407machine # [ 0.679006] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled408machine # [ 0.680315] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A409machine # [ 0.685492] Linux agpgart interface v0.103410machine # [ 0.686276] ACPI: bus type drm_connector registered411machine # [ 0.689178] usbcore: registered new interface driver usbserial_generic412machine # [ 0.690276] usbserial: USB Serial support registered for generic413machine # [ 0.693749] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.414machine # [ 0.693749] Please enable it if your BIOS has the CPPC option.415machine # [ 0.696038] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled416machine # [ 0.699862] drop_monitor: Initializing network drop monitor service417machine # [ 0.701084] NET: Registered PF_INET6 protocol family418machine # [ 0.704174] Segment Routing with IPv6419machine # [ 0.706744] In-situ OAM (IOAM) with IPv6420machine # [ 0.707829] IPI shorthand broadcast: enabled421machine # [ 0.716163] sched_clock: Marking stable (573014850, 142709714)->(783122437, -67397873)422machine # [ 0.720817] registered taskstats version 1423machine # [ 0.721822] Loading compiled-in X.509 certificates424machine # [ 0.739744] Demotion targets for Node 0: null425machine # [ 0.741783] Key type .fscrypt registered426machine # [ 0.742863] Key type fscrypt-provisioning registered427machine # [ 0.745838] ima: No TPM chip found, activating TPM-bypass!428machine # [ 0.746809] ima: Allocated hash algorithm: sha1429machine # [ 0.747633] ima: No architecture policies found430machine # [ 0.751739] PM: Magic number: 10:584:661431machine # [ 0.753427] RAS: Correctable Errors collector initialized.432machine # [ 0.762521] clk: Disabling unused clocks433machine # [ 0.766811] PM: genpd: Disabling unused power domains434machine # [ 0.922298] Freeing initrd memory: 28316K435machine # [ 0.925309] Freeing unused decrypted memory: 2028K436machine # [ 0.927751] Freeing unused kernel image (initmem) memory: 3644K437machine # [ 0.928807] Write protecting the kernel read-only data: 32768k438machine # [ 0.930597] Freeing unused kernel image (text/rodata gap) memory: 1216K439machine # [ 0.932066] Freeing unused kernel image (rodata/data gap) memory: 756K440machine # [ 0.983084] x86/mm: Checked W+X mappings: passed, no W+X pages found.441machine # [ 0.984133] Run /init as init process442machine # [ 0.993792] systemd[1]: Inserted module 'autofs4'443machine # [ 1.009829] fuse: init (API version 7.45)444machine # [ 1.015605] ACPI: \_SB_.GSIG: Enabled at IRQ 22445machine # [ 1.018241] ACPI: \_SB_.GSIH: Enabled at IRQ 23446machine # [ 1.021122] ACPI: \_SB_.GSIE: Enabled at IRQ 20447machine # [ 1.023376] ACPI: \_SB_.GSIF: Enabled at IRQ 21448machine # [ 1.046392] systemd[1]: Successfully made /usr/ read-only.449machine # [ 1.381865] systemd[1]: systemd 262-2451b1a running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)450machine # [ 1.386581] systemd[1]: Detected virtualization kvm.451machine # [ 1.387393] systemd[1]: Detected architecture x86-64.452machine # [ 1.388248] systemd[1]: Running in initrd.453machine # [ 1.389273] systemd[1]: Initializing machine ID from random generator.454machine # [ 1.390402] systemd[1]: Hostname set to <machine>.455machine # [ 1.460275] systemd[1]: bpf-restrict-fs: LSM BPF program attached456machine # [ 1.496025] systemd[1]: Queued start job for default target Initrd Default Target.457machine # [ 1.499922] systemd[1]: Created slice Slice /system/modprobe.458machine # [ 1.501066] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.459machine # [ 1.502399] systemd[1]: Expecting device /dev/disk/by-label/nixos...460machine # [ 1.503465] systemd[1]: Reached target Path Units.461machine # [ 1.504292] systemd[1]: Reached target Slice Units.462machine # [ 1.505124] systemd[1]: Reached target Swaps.463machine # [ 1.505892] systemd[1]: Reached target Timer Units.464machine # [ 1.506826] systemd[1]: Listening on D-Bus System Message Bus Socket.465machine # [ 1.507989] systemd[1]: Listening on Journal Socket (/dev/log).466machine # [ 1.509125] systemd[1]: Listening on Journal Sockets.467machine # [ 1.510067] systemd[1]: Listening on udev Kernel Socket.468machine # [ 1.510993] systemd[1]: Reached target Socket Units.469machine # [ 1.512660] systemd[1]: Starting Create List of Static Device Nodes...470machine # [ 1.516518] systemd[1]: Starting Load Kernel Module 9pnet_virtio...471machine # [ 1.523918] systemd[1]: Starting Load Kernel Module configfs...472machine # [ 1.537793] systemd[1]: Starting Journal Service...473machine # [ 1.549921] systemd[1]: Starting Load Kernel Modules...474machine # [ 1.555799] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os475machine # [ 1.573035] netfs: FS-Cache loaded476machine # [ 1.580120] 9pnet: Installing 9P2000 support477machine # [ 1.592910] systemd[1]: Starting Coldplug All udev Devices...478machine # [ 1.602895] systemd-journald[67]: Collecting audit messages is disabled.479machine # [ 1.607843] systemd[1]: Finished Create List of Static Device Nodes.480machine # [ 1.614395] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.481machine # [ 1.620162] systemd[1]: Finished Load Kernel Module 9pnet_virtio.482machine # [ 1.627182] systemd[1]: modprobe@configfs.service: Deactivated successfully.483machine # [ 1.633083] systemd[1]: Finished Load Kernel Module configfs.484machine # [ 1.638170] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config485machine # [ 1.640556] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.486machine # [ 1.649899] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev487machine # [ 1.651501] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...488machine # [ 1.680207] systemd[1]: Finished Load Kernel Modules.489machine # [ 1.686914] systemd[1]: Starting Apply Kernel Variables...490machine # [ 1.700806] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.491machine # [ 1.709914] systemd[1]: Starting Create Static Device Nodes in /dev...492machine # [ 1.578077] systemd-modules-load[68]: Inserted module 'dm_mod'493machine # [ 1.579335] systemd-modules-load[68]: Inserted module 'virtio_balloon'494machine # [ 1.582127] systemd-modules-load[68]: Inserted module 'virtio_gpu'495machine # [ 1.725801] systemd[1]: Finished Apply Kernel Variables.496machine # [ 1.731099] systemd[1]: Started Journal Service.497machine # [ 1.602107] systemd[1]: Finished Create Static Device Nodes in /dev.498machine # [ 1.603098] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 1.604122] systemd[1]: Reached target Local File Systems.500machine # [ 1.607130] systemd[1]: Starting Create System Files and Directories...501machine # [ 1.615070] systemd[1]: Starting Rule-based Manager for Device Events and Files...502machine # [ 1.638427] systemd[1]: Finished Create System Files and Directories.503machine # [ 1.657861] systemd-udevd[83]: Using default interface naming scheme 'v261'.504machine # [ 1.683115] systemd[1]: Started Rule-based Manager for Device Events and Files.505machine # [ 1.732662] systemd[1]: Finished Coldplug All udev Devices.506machine # [ 1.733560] systemd[1]: Reached target System Initialization.507machine # [ 1.734406] systemd[1]: Reached target Basic System.508machine # [ 2.045555] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12509machine # [ 2.049034] virtio_blk virtio5: 1/0/0 default/read/poll queues510machine # [ 2.066461] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)511machine # [ 2.074365] serio: i8042 KBD port at 0x60,0x64 irq 1512machine # [ 2.078665] serio: i8042 AUX port at 0x60,0x64 irq 12513machine # [ 2.087765] ehci-pci 0000:00:1d.7: EHCI Host Controller514machine # [ 2.088482] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1515machine # [ 2.090151] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000516machine # [ 2.097748] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00517machine # [ 2.098634] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18518machine # [ 2.100733] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1519machine # [ 2.101692] usb usb1: Product: EHCI Host Controller520machine # [ 2.103602] usb usb1: Manufacturer: Linux 6.18.48 ehci_hcd521machine # [ 2.105500] usb usb1: SerialNumber: 0000:00:1d.7522machine # [ 2.106384] hub 1-0:1.0: USB hub found523machine # [ 2.107979] hub 1-0:1.0: 6 ports detected524machine # [ 2.115155] uhci_hcd 0000:00:1d.0: UHCI Host Controller525machine # [ 2.115890] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2526machine # [ 2.129158] uhci_hcd 0000:00:1d.0: detected 2 ports527machine # [ 2.136396] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200528machine # [ 2.141501] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18529machine # [ 2.144420] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1530machine # [ 2.145416] usb usb2: Product: UHCI Host Controller531machine # [ 2.160511] usb usb2: Manufacturer: Linux 6.18.48 uhci_hcd532machine # [ 2.161368] usb usb2: SerialNumber: 0000:00:1d.0533machine # [ 2.171009] hub 2-0:1.0: USB hub found534machine # [ 2.029475] systemd[1]: Starting Virtual Console Setup...535machine # [ 2.032901] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.536machine # [ 2.176676] hub 2-0:1.0: 2 ports detected537machine # [ 2.179496] SCSI subsystem initialized538machine # [ 2.182132] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0539machine # [ 2.049294] (udev-worker)[88]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.540machine # [ 2.051212] (udev-worker)[88]: Network interface NamePolicy= disabled on kernel command line.541machine # [ 2.069244] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.542machine # [ 2.072559] systemd[1]: Finished Virtual Console Setup.543machine # [ 2.219083] uhci_hcd 0000:00:1d.1: UHCI Host Controller544machine # [ 2.219831] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3545machine # [ 2.234974] uhci_hcd 0000:00:1d.1: detected 2 ports546machine # [ 2.240839] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220547machine # [ 2.242791] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18548machine # [ 2.243891] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1549machine # [ 2.250737] usb usb3: Product: UHCI Host Controller550machine # [ 2.251411] usb usb3: Manufacturer: Linux 6.18.48 uhci_hcd551machine # [ 2.110066] systemd[1]: Found device /dev/disk/by-label/nixos.552machine # [ 2.110874] systemd[1]: Reached target Initrd Root Device.553machine # [ 2.112361] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...554machine # [ 2.259734] usb usb3: SerialNumber: 0000:00:1d.1555machine # [ 2.261782] hub 3-0:1.0: USB hub found556machine # [ 2.267759] hub 3-0:1.0: 2 ports detected557machine # [ 2.271658] uhci_hcd 0000:00:1d.2: UHCI Host Controller558machine # [ 2.272471] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4559machine # [ 2.279817] uhci_hcd 0000:00:1d.2: detected 2 ports560machine # [ 2.280580] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240561machine # [ 2.146443] systemd-fsck[107]: nixos: clean, 12/65536 files, 13019/262144 blocks562machine # [ 2.290242] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18563machine # [ 2.292581] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1564machine # [ 2.295181] usb usb4: Product: UHCI Host Controller565machine # [ 2.156176] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.566machine # [ 2.299885] usb usb4: Manufacturer: Linux 6.18.48 uhci_hcd567machine # [ 2.300614] usb usb4: SerialNumber: 0000:00:1d.2568machine # [ 2.304027] hub 4-0:1.0: USB hub found569machine # [ 2.306837] hub 4-0:1.0: 2 ports detected570machine # [ 2.310108] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode571machine # [ 2.320405] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)572machine # [ 2.326470] ahci 0000:00:1f.2: flags: 64bit ncq only573machine # [ 2.334448] scsi host0: ahci574machine # [ 2.337785] scsi host1: ahci575machine # [ 2.341013] scsi host2: ahci576machine # [ 2.345750] usb 1-1: new high-speed USB device number 2 using ehci-pci577machine # [ 2.347892] scsi host3: ahci578machine # [ 2.350423] scsi host4: ahci579machine # [ 2.352559] scsi host5: ahci580machine # [ 2.354462] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1581machine # [ 2.357674] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1582machine # [ 2.358936] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1583machine # [ 2.360122] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1584machine # [ 2.361266] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1585machine # [ 2.362401] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1586machine # [ 2.475055] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00587machine # [ 2.477875] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10588machine # [ 2.481064] usb 1-1: Product: QEMU USB Tablet589machine # [ 2.482852] usb 1-1: Manufacturer: QEMU590machine # [ 2.484447] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1591machine # [ 2.512814] hid: raw HID events driver (C) Jiri Kosina592machine # [ 2.465349] systemd[1]: Mounting /sysroot...593machine # [ 2.673763] ata4: SATA link down (SStatus 0 SControl 300)594machine # [ 2.676228] ata1: SATA link down (SStatus 0 SControl 300)595machine # [ 2.678382] ata2: SATA link down (SStatus 0 SControl 300)596machine # [ 2.680800] ata6: SATA link down (SStatus 0 SControl 300)597machine # [ 2.682919] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)598machine # [ 2.685139] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100599machine # [ 2.687433] ata3.00: applying bridge limits600machine # [ 2.689306] ata5: SATA link down (SStatus 0 SControl 300)601machine # [ 2.691477] ata3.00: configured for UDMA/100602machine # [ 2.693783] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5603machine # [ 2.748786] usbcore: registered new interface driver usbhid604machine # [ 2.749580] usbhid: USB HID core driver605machine # [ 2.775617] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:1d.7/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2606machine # [ 2.780544] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0607machine # [ 2.788929] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray608machine # [ 2.798083] cdrom: Uniform CD-ROM driver Revision: 3.20609machine # [ 2.800793] EXT4-fs (vda): mounted filesystem 224a9abd-006e-4a38-aecd-0d821e9d21c4 r/w with ordered data mode. Quota mode: none.610machine # [ 2.662764] systemd[1]: Mounted /sysroot.611machine # [ 2.664460] systemd[1]: Reached target Initrd Root File System.612machine # [ 2.668725] systemd[1]: Starting Mountpoints Configured in the Real Root...613machine # [ 2.684294] systemd-sysroot-fstab-check[140]: /sysroot should be mounted in the initrd, will request daemon-reload.614machine # [ 2.687335] systemd[1]: Reload requested from client PID 140 ('systemd-sysroot') (unit initrd-parse-etc.service)...615machine # [ 2.689726] systemd[1]: Reloading...616machine # [ 2.763940] systemd[1]: Reloading finished in 74 ms.617machine # [ 2.771699] systemd-sysroot-fstab-check[140]: Requesting initrd-fs.target/start/replace...618machine # [ 2.773759] systemd-sysroot-fstab-check[140]: Requesting swap.target/start/replace...619machine # [ 2.777641] systemd[1]: initrd-parse-etc.service: Deactivated successfully.620machine # [ 2.778985] systemd[1]: Finished Mountpoints Configured in the Real Root.621machine # [ 2.780120] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.622machine # [ 2.783864] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio623machine # [ 3.466552] systemd[1]: Mounting /sysroot/nix/.ro-store...624machine # [ 3.474165] systemd[1]: Mounting /sysroot/nix/.rw-store...625machine # [ 3.481162] systemd[1]: Mounting /sysroot/run...626machine # [ 3.491327] systemd[1]: Mounting /sysroot/tmp/shared...627machine # [ 3.499592] systemd[1]: Mounting /sysroot/tmp/xchg...628machine # [ 3.683170] 9p: Installing v9fs 9p2000 file system support629machine # [ 3.546345] systemd[1]: Mounted /sysroot/nix/.rw-store.630machine # [ 3.547380] systemd[1]: Mounted /sysroot/run.631machine # [ 3.553107] systemd[1]: Mounted /sysroot/nix/.ro-store.632machine # [ 3.554883] systemd[1]: Mounted /sysroot/tmp/shared.633machine # [ 3.558127] systemd[1]: Mounted /sysroot/tmp/xchg.634machine # [ 3.560794] systemd[1]: Starting rw-sysroot-nix-store.service...635machine # [ 3.571318] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.636machine # [ 3.572730] systemd[1]: Finished rw-sysroot-nix-store.service.637machine # [ 4.465505] systemd[1]: Mounting /sysroot/nix/store...638machine # [ 4.511149] systemd[1]: Mounted /sysroot/nix/store.639machine # [ 4.513601] systemd[1]: Reached target Initrd File Systems.640machine # [ 4.517231] systemd[1]: Starting Find NixOS closure...641machine # [ 4.523260] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...642machine # [ 4.546046] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.643machine # [ 4.547925] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.644machine # [ 4.556475] systemd[1]: Finished Find NixOS closure.645machine # [ 4.557579] systemd[1]: Reached target Initrd Default Target.646machine # [ 4.559285] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...647machine # [ 4.572314] systemd[1]: Stopped target Initrd Default Target.648machine # [ 4.573572] systemd[1]: Stopped target Basic System.649machine # [ 4.575051] systemd[1]: Stopped target Initrd Root Device.650machine # [ 4.575814] systemd[1]: Stopped target Path Units.651machine # [ 4.576567] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.652machine # [ 4.577556] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.653machine # [ 4.578754] systemd[1]: Stopped target Slice Units.654machine # [ 4.579658] systemd[1]: Stopped target Socket Units.655machine # [ 4.580757] systemd[1]: Stopped target System Initialization.656machine # [ 4.581736] systemd[1]: Stopped target Swaps.657machine # [ 4.583131] systemd[1]: Stopped target Timer Units.658machine # [ 4.583824] systemd[1]: dbus.socket: Deactivated successfully.659machine # [ 4.584728] systemd[1]: Closed D-Bus System Message Bus Socket.660machine # [ 4.585525] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.661machine # [ 4.586895] systemd[1]: Stopped Find NixOS closure.662machine # [ 4.587662] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio663machine # [ 4.589488] systemd[1]: Starting rw-sysroot-nix-store.service...664machine # [ 4.591133] systemd[1]: systemd-sysctl.service: Deactivated successfully.665machine # [ 4.592235] systemd[1]: Stopped Apply Kernel Variables.666machine # [ 4.593338] systemd[1]: systemd-modules-load.service: Deactivated successfully.667machine # [ 4.594495] systemd[1]: Stopped Load Kernel Modules.668machine # [ 4.595540] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.669machine # [ 4.596727] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.670machine # [ 4.598125] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.671machine # [ 4.599169] systemd[1]: Stopped Create System Files and Directories.672machine # [ 4.601095] systemd[1]: Stopped target Local File Systems.673machine # [ 4.601945] systemd[1]: Stopped target Preparation for Local File Systems.674machine # [ 4.603021] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.675machine # [ 4.604190] systemd[1]: Stopped Coldplug All udev Devices.676machine # [ 4.606059] systemd[1]: Stopping Rule-based Manager for Device Events and Files...677machine # [ 4.607065] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.678machine # [ 4.608247] systemd[1]: Stopped Virtual Console Setup.679machine # [ 4.616974] systemd[1]: initrd-cleanup.service: Deactivated successfully.680machine # [ 4.620737] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.681machine # [ 4.626473] systemd[1]: systemd-udevd.service: Deactivated successfully.682machine # [ 4.627590] systemd[1]: Stopped Rule-based Manager for Device Events and Files.683machine # [ 4.629232] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.684machine # [ 4.630614] systemd[1]: Finished rw-sysroot-nix-store.service.685machine # [ 4.634040] systemd[1]: Starting Cleanup udev Database...686machine # [ 4.634813] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.687machine # [ 4.635834] systemd[1]: Stopped Create Static Device Nodes in /dev.688machine # [ 4.636734] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.689machine # [ 4.638135] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.690machine # [ 4.640145] systemd[1]: kmod-static-nodes.service: Deactivated successfully.691machine # [ 4.641232] systemd[1]: Stopped Create List of Static Device Nodes.692machine # [ 4.657213] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.693machine # [ 4.658923] systemd[1]: Finished Cleanup udev Database.694machine # [ 4.660475] systemd[1]: Reached target Switch Root.695machine # [ 4.662128] systemd[1]: Starting NixOS Activation...696machine # [ 4.801519] initrd-nixos-activation-start[193]: booting system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test697machine # [ 4.847921] initrd-nixos-activation-start[193]: running activation script...698machine # [ 5.158397] initrd-nixos-activation-start[216]: setting up /etc...699machine # [ 5.364591] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.700machine # [ 5.366150] systemd[1]: Finished NixOS Activation.701machine # [ 5.367896] systemd[1]: Starting Switch Root...702machine # [ 5.381113] systemd[1]: Switching root.703machine # [ 5.657383] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).704machine # [ 5.796183] NET: Registered PF_VSOCK protocol family705machine # [ 6.178622] systemd[1]: systemd 262-2451b1a running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP +LIBARCHIVE)706machine # [ 6.188013] systemd[1]: Detected virtualization kvm.707machine # [ 6.189793] systemd[1]: Detected architecture x86-64.708machine # [ 6.191643] systemd[1]: Detected first boot.709machine # [ 6.198139] systemd[1]: Initializing machine ID from random generator.710machine # [ 6.318942] systemd[1]: bpf-restrict-fs: LSM BPF program attached711machine # [ 6.437988] systemd[1]: Applying preset policy.712machine # [ 6.886797] systemd[1]: Populated /etc with preset unit settings.713machine # [ 7.341826] systemd[1]: initrd-switch-root.service: Deactivated successfully.714machine # [ 7.343324] systemd[1]: Stopped initrd-switch-root.service.715machine # [ 7.345869] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.716machine # [ 7.348073] systemd[1]: Created slice Slice /system/getty.717machine # [ 7.349387] systemd[1]: Created slice User and Session Slice.718machine # [ 7.350455] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.719machine # [ 7.351654] systemd[1]: Started Forward Password Requests to Wall Directory Watch.720machine # [ 7.352768] systemd[1]: Expecting device /dev/hvc0...721machine # [ 7.353473] systemd[1]: Expecting device /dev/ttyS0...722machine # [ 7.354255] systemd[1]: Reached target Local Encrypted Volumes.723machine # [ 7.355084] systemd[1]: Stopped target initrd-fs.target.724machine # [ 7.355868] systemd[1]: Stopped target initrd-root-fs.target.725machine # [ 7.356661] systemd[1]: Stopped target initrd-switch-root.target.726machine # [ 7.357532] systemd[1]: Reached target Virtual Machines and Containers.727machine # [ 7.358414] systemd[1]: Reached target Path Units.728machine # [ 7.359102] systemd[1]: Reached target Remote File Systems.729machine # [ 7.359889] systemd[1]: Reached target Slice Units.730machine # [ 7.360598] systemd[1]: Reached target Swaps.731machine # [ 7.363369] systemd[1]: Listening on Query the User Interactively for a Password.732machine # [ 7.378528] systemd[1]: Listening on Process Core Dump Socket.733machine # [ 7.381225] systemd[1]: Listening on Credential Encryption/Decryption.734machine # [ 7.383852] systemd[1]: Listening on Factory Reset Management.735machine # [ 7.384797] systemd[1]: Listening on Hostname Service Socket.736machine # [ 7.388251] systemd[1]: Starting Journal Log Access Socket...737machine # [ 7.390120] systemd[1]: Listening on Journal Audit Socket.738machine # [ 7.392882] systemd[1]: Listening on Console Output Muting Service Socket.739machine # [ 7.394184] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.740machine # [ 7.396087] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os741machine # [ 7.397452] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki742machine # [ 7.405478] systemd[1]: Listening on Disk Repartitioning Service Socket.743machine # [ 7.406503] systemd[1]: Listening on udev Varlink Socket.744machine # [ 7.410428] systemd[1]: Mounting Huge Pages File System...745machine # [ 7.413502] systemd[1]: Mounting POSIX Message Queue File System...746machine # [ 7.420413] systemd[1]: Mounting Kernel Debug File System...747machine # [ 7.425863] systemd[1]: Mounting Kernel Trace File System...748machine # [ 7.432511] systemd[1]: Starting Create List of Static Device Nodes...749machine # [ 7.434585] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio750machine # [ 7.444014] systemd[1]: Starting Load Kernel Module configfs...751machine # [ 7.445548] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm752machine # [ 7.450976] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore753machine # [ 7.453798] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse754machine # [ 7.491963] systemd[1]: Mounting FUSE Control File System...755machine # [ 7.498035] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67756machine # [ 7.522513] systemd[1]: Starting Journal Service...757machine # [ 7.529359] systemd[1]: Starting Load Kernel Modules...758machine # [ 7.539682] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...759machine # [ 7.553142] systemd[1]: Starting Remount Root and Kernel File Systems...760machine # [ 7.562510] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os761machine # [ 7.577013] systemd[1]: Starting Coldplug All udev Devices...762machine # [ 7.592674] systemd[1]: Listening on Journal Log Access Socket.763machine # [ 7.594135] systemd-journald[287]: Collecting audit messages is enabled.764machine # [ 7.603468] systemd[1]: Mounted Huge Pages File System.765machine # [ 7.605011] loop: module loaded766machine # [ 7.609489] systemd[1]: Mounted POSIX Message Queue File System.767machine # [ 7.614950] systemd[1]: Mounted Kernel Debug File System.768machine # [ 7.621936] systemd[1]: Mounted Kernel Trace File System.769machine # [ 7.625442] systemd[1]: Started Journal Service.770machine # [ 7.483521] systemd[1]: Queued start job for default target Multi-User System.771machine # [ 7.486715] systemd[1]: systemd-journald.service: Deactivated successfully.772machine # [ 7.631799] EXT4-fs (vda): re-mounted 224a9abd-006e-4a38-aecd-0d821e9d21c4.773machine # [ 7.490475] systemd-modules-load[288]: Inserted module 'loop'774machine # [ 7.495049] systemd[1]: Finished Create List of Static Device Nodes.775machine # [ 7.496436] systemd[1]: modprobe@configfs.service: Deactivated successfully.776machine # [ 7.501817] systemd[1]: Finished Load Kernel Module configfs.777machine # [ 7.504890] systemd[1]: Mounted FUSE Control File System.778machine # [ 7.505994] systemd[1]: Finished Load Kernel Modules.779machine # [ 7.508177] systemd[1]: Finished Remount Root and Kernel File Systems.780machine # [ 7.528083] systemd[1]: Listening on Disk Image Download Service Socket.781machine # [ 7.534425] systemd[1]: Mounting Kernel Configuration File System...782machine # [ 7.543065] systemd[1]: Starting Firewall...783machine # [ 7.556156] systemd[1]: Starting Flush Journal to Persistent Storage...784machine # [ 7.557331] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore785machine # [ 7.568872] systemd-oomd[289]: No swap; memory pressure usage will be degraded786machine # [ 7.578112] systemd[1]: Starting Load/Save OS Random Seed...787machine # [ 7.583132] systemd[1]: Starting Apply Kernel Variables...788machine # [ 7.595315] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...789machine # [ 7.597876] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os790machine # [ 7.600523] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.791machine # [ 7.765520] systemd-journald[287]: Received client request to flush runtime journal.792machine # [ 7.772459] systemd[1]: Mounted Kernel Configuration File System.793machine # [ 7.773436] systemd[1]: Finished Load/Save OS Random Seed.794machine # [ 7.774404] systemd[1]: Reached target First Boot Complete.795machine # [ 7.776608] systemd[1]: Finished Apply Kernel Variables.796machine # [ 7.779174] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.797machine # [ 7.786327] systemd[1]: Starting Create Static Device Nodes in /dev...798machine # [ 7.787918] systemd[1]: Finished Flush Journal to Persistent Storage.799machine # [ 7.861284] systemd[1]: Finished Create Static Device Nodes in /dev.800machine # [ 7.862659] systemd[1]: Reached target Preparation for Local File Systems.801machine # [ 7.868285] systemd[1]: Starting Rule-based Manager for Device Events and Files...802machine # [ 7.918978] systemd[1]: Finished Coldplug All udev Devices.803machine # [ 7.952808] systemd-udevd[329]: Using default interface naming scheme 'v261'.804machine # [ 8.060130] systemd[1]: Started Rule-based Manager for Device Events and Files.805machine # [ 8.187669] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse806machine # [ 8.204526] systemd[1]: Mounting /run/wrappers...807machine # [ 8.241310] systemd[1]: Mounted /run/wrappers.808machine # [ 8.242769] systemd[1]: Reached target Local File Systems.809machine # [ 8.246306] systemd[1]: Listening on Boot Loader Control Service Socket.810machine # [ 8.251496] systemd[1]: Starting register-nix-paths.service...811machine # [ 8.257752] systemd[1]: Starting Create SUID/SGID Wrappers...812machine # [ 8.258614] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.813machine # [ 8.269881] systemd[1]: Starting Save Transient machine-id to Disk...814machine # [ 8.281076] systemd[1]: Starting Create System Files and Directories...815machine # [ 8.359664] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.816machine # [ 8.371690] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.817machine # [ 8.379806] systemd[1]: Finished Save Transient machine-id to Disk.818machine # [ 8.434853] systemd[1]: Finished Create System Files and Directories.819machine # [ 8.440482] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.820machine # [ 8.447671] systemd[1]: Starting Rebuild Journal Catalog...821machine # [ 8.462315] systemd[1]: Starting Record System Boot/Shutdown in UTMP...822machine # [ 8.518526] (udev-worker)[368]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.823machine # [ 8.528407] (udev-worker)[368]: Network interface NamePolicy= disabled on kernel command line.824machine # [ 8.532070] (udev-worker)[360]: Network interface NamePolicy= disabled on kernel command line.825machine # [ 8.549200] systemd[1]: Finished Record System Boot/Shutdown in UTMP.826machine # [ 8.598360] systemd[1]: Finished Rebuild Journal Catalog.827machine # [ 8.605285] systemd[1]: Starting Update is Completed...828machine # [ 8.665330] systemd[1]: Finished Update is Completed.829machine # [ 8.773343] systemd[1]: Condition check resulted in Virtio network device being skipped.830machine # [ 8.775740] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore831machine # [ 8.777904] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.832machine # [ 8.780704] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67833machine # [ 8.783185] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore834machine # [ 8.784890] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os835machine # [ 8.787475] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os836machine # [ 8.853233] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.837machine # [ 8.855299] systemd[1]: Finished Create SUID/SGID Wrappers.838machine # [ 8.925687] systemd[1]: Finished Firewall.839machine # [ 9.094619] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3840machine # [ 9.105745] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console841machine # [ 9.115520] mousedev: PS/2 mouse device common for all mice842machine # [ 9.134846] ACPI: button: Power Button [PWRF]843machine # [ 9.181480] rtc_cmos 00:04: RTC can wake from S4844machine # [ 9.207786] rtc_cmos 00:04: registered as rtc0845machine # [ 9.207850] rtc_cmos 00:04: setting system clock to 2026-09-03T02:41:46 UTC (1788403306)846machine # [ 9.207924] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs847machine # [ 9.210760] systemd-journald[287]: Time jumped backwards, rotating.848machine # [ 9.220297] parport_pc 00:02: reported by Plug and Play ACPI849machine # [ 9.220406] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]850machine # [ 9.230571] Console: switching to colour dummy device 80x25851machine # [ 9.259765] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized852machine # [ 9.270177] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4853machine # [ 9.363353] [drm] Found bochs VGA, ID 0xb0c5.854machine # [ 9.363355] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.855machine # [ 9.230906] systemd[1]: Finished register-nix-paths.service.856machine # [ 9.232167] systemd[1]: Reached target System Initialization.857machine # [ 9.376376] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic858machine # [ 9.377165] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0859machine # [ 9.237240] systemd[1]: Started Discard unused filesystem blocks once a week.860machine # [ 9.238301] systemd[1]: Started Daily Cleanup of Temporary Directories.861machine # [ 9.239205] systemd[1]: Reached target Timer Units.862machine # [ 9.239922] systemd[1]: Listening on D-Bus System Message Bus Socket.863machine # [ 9.240855] systemd[1]: Listening on Nix Daemon Socket.864machine # [ 9.242507] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.865machine # [ 9.243769] systemd[1]: Reached target Socket Units.866machine # [ 9.244762] systemd[1]: Reached target Basic System.867machine # [ 9.390666] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt868machine # [ 9.391340] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD869machine # [ 9.251065] systemd[1]: Started backdoor.service.870machine # [ 9.254798] systemd[1]: Starting Import lastlog data into lastlog2 database...871machine # [ 9.261882] systemd[1]: Starting Name Service Cache Daemon (nsncd)...872machine # [ 9.274256] systemd[1]: Starting Post-Boot Actions...873machine # [ 9.287222] systemd[1]: Started Reset console on configuration changes.874machine # [ 9.300811] systemd[1]: Starting resolvconf update...875machine # [ 9.343540] systemd[1]: Starting D-Bus System Message Bus...876machine # connecting to host...877machine # [ 9.365193] systemd[1]: Finished Post-Boot Actions.878machine # [ 9.386791] nsncd[505]: Sep 03 02:41:46.817 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"879machine # [ 9.389663] systemd[1]: Started Name Service Cache Daemon (nsncd).880machine # [ 9.400042] systemd[1]: Reached target Host and Network Name Lookups.881machine # [ 9.403858] systemd[1]: Reached target User and Group Name Lookups.882machine: Guest shell says: b'Spawning backdoor root shell...\n'883machine: connected to guest root shell884machine: (connecting took 10.05 seconds)885machine: (finished: waiting for the VM to finish booting, in 10.24 seconds)886machine # [ 9.423452] systemd[1]: Starting User Login Management...887machine # [ 9.447460] systemd[1]: Finished Import lastlog data into lastlog2 database.888machine # [ 9.534990] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...889machine # [ 9.554943] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6890machine # [ 9.555194] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5891machine # [ 9.691457] Console: switching to colour frame buffer device 160x50892machine # [ 9.766021] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device893machine # [ 9.604628] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'894machine # [ 9.628272] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"895machine # [ 9.631838] systemd[1]: Stopped target Host and Network Name Lookups.896machine # [ 9.639187] systemd[1]: Stopping Host and Network Name Lookups...897machine # [ 9.640284] systemd[1]: Stopped target User and Group Name Lookups.898machine # [ 9.642944] systemd[1]: Stopping User and Group Name Lookups...899machine # [ 9.644215] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...900machine # [ 9.645576] systemd[1]: nscd.service: Deactivated successfully.901machine # [ 9.647425] systemd[1]: Stopped Name Service Cache Daemon (nsncd).902machine # [ 9.651400] systemd-logind[532]: New seat seat0.903machine # [ 9.653184] systemd[1]: Started User Login Management.904machine # [ 9.653926] systemd-logind[532]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)905machine # [ 9.656504] systemd-logind[532]: Watching system buttons on /dev/input/event2 (Power Button)906machine # [ 9.658928] systemd[1]: Started D-Bus System Message Bus.907machine # [ 9.668615] systemd[1]: Starting linger-users.service...908machine # [ 9.674273] systemd[1]: Starting Name Service Cache Daemon (nsncd)...909machine # [ 9.696072] systemd[1]: Starting Virtual Console Setup...910machine # [ 9.701411] dbus-broker-launch[513]: Ready911machine # [ 9.750813] systemd[1]: Finished resolvconf update.912machine # [ 9.763340] systemd[1]: Reached target Preparation for Network.913machine # [ 9.773831] systemd[1]: Starting DHCP Client...914machine # [ 9.780989] nsncd[596]: Sep 03 02:41:47.215 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"915machine # [ 9.785886] systemd[1]: Starting Address configuration of eth1...916machine # [ 9.797323] systemd[1]: Starting Extra networking commands....917machine # [ 9.802384] systemd[1]: Started Name Service Cache Daemon (nsncd).918machine # [ 9.804742] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.919machine # [ 9.952661] ppdev: user-space parallel port driver920machine # [ 9.812522] systemd[1]: Stopped Virtual Console Setup.921machine # [ 9.873962] systemd[1]: Created slice Slice /user/1001.922machine # [ 9.874784] systemd[1]: Reached target Host and Network Name Lookups.923machine # [ 9.875609] systemd[1]: Reached target User and Group Name Lookups.924machine # [ 9.883908] systemd[1]: Starting Virtual Console Setup...925machine # [ 9.884950] systemd[1]: Starting User Runtime Directory /run/user/1001...926machine # [ 10.043321] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)927machine # [ 9.903128] systemd[1]: linger-users.service: Deactivated successfully.928machine # [ 9.905920] systemd[1]: Finished linger-users.service.929machine # [ 9.918706] systemd-logind[532]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)930machine # [ 10.075919] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)931machine # [ 9.954346] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.932machine # [ 9.955496] systemd[1]: Stopped Virtual Console Setup.933machine # [ 9.967088] systemd[1]: Starting Virtual Console Setup...934machine # [ 10.011162] systemd[1]: Finished User Runtime Directory /run/user/1001.935machine # [ 10.018063] systemd[1]: Starting User Manager for UID 1001...936machine # [ 10.049744] network-addresses-eth1-start[609]: adding address 192.168.1.1/24... done937machine # [ 10.075832] network-addresses-eth1-start[609]: adding address 2001:db8:1::1/64... done938machine # [ 10.091520] (systemd)[636]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)939machine # [ 10.112602] systemd-logind[532]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.940machine # [ 10.117318] systemd[1]: Finished Address configuration of eth1.941machine # [ 10.152251] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy942machine # [ 10.246120] systemd[1]: Finished Extra networking commands..943machine # [ 10.250624] systemd[1]: Reached target Network.944machine # [ 10.253124] systemd[1]: Starting Permit User Sessions...945machine # [ 10.317234] systemd[1]: Finished Permit User Sessions.946machine # [ 10.319522] dhcpcd[645]: dhcpcd-10.3.2 starting947machine # [ 10.466427] kvm_amd: TSC scaling supported948machine # [ 10.467101] kvm_amd: Nested Virtualization enabled949machine # [ 10.467997] kvm_amd: Nested Paging enabled950machine # [ 10.468565] kvm_amd: LBR virtualization supported951machine # [ 10.331921] systemd[1]: Started Getty on tty1.952machine # [ 10.333753] systemd[1]: Reached target Login Prompts.953machine # [ 10.478888] kvm_amd: Virtual VMLOAD VMSAVE supported954machine # [ 10.479447] kvm_amd: Virtual GIF supported955machine # [ 10.339752] dhcpcd[703]: dev: loaded udev956machine # [ 10.484948] kvm_amd: Virtual NMI enabled957machine # [ 10.513629] 8021q: 802.1Q VLAN Support v1.8958machine # [ 10.514269] 8021q: adding VLAN 0 to HW filter on device eth1959machine # [ 10.402915] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.960machine # [ 10.553559] EDAC MC: Ver: 3.0.0961machine # [ 10.415643] systemd[636]: Queued start job for default target Main User Target.962machine # [ 10.421363] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy963machine # [ 10.427076] systemd[636]: Created slice User Application Slice.964machine # [ 10.427944] systemd[636]: Started Daily Cleanup of User's Temporary Directories.965machine # [ 10.435459] systemd[636]: Reached target Paths.966machine # [ 10.437227] systemd[636]: Reached target Timers.967machine # [ 10.437959] systemd[636]: Starting D-Bus User Message Bus Socket...968machine # [ 10.439192] systemd[636]: Starting Create User Files and Directories...969machine # [ 10.646938] cfg80211: Loading compiled-in X.509 certificates for regulatory database970machine # [ 10.516652] systemd[636]: Finished Create User Files and Directories.971machine # [ 10.671867] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'972machine # [ 10.672504] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'973machine # [ 10.675577] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2974machine # [ 10.676419] cfg80211: failed to load regulatory.db975machine # [ 10.556039] systemd[636]: Listening on D-Bus User Message Bus Socket.976machine # [ 10.556955] systemd[636]: Reached target Sockets.977machine # [ 10.560492] systemd[636]: Reached target Basic System.978machine # [ 10.561574] systemd[1]: Started User Manager for UID 1001.979machine # [ 10.562924] systemd[636]: Starting Run user-specific NixOS activation...980machine # [ 10.739408] 8021q: adding VLAN 0 to HW filter on device eth0981machine # [ 10.598979] dhcpcd[703]: eth0: waiting for carrier982machine # [ 10.600180] dhcpcd[703]: eth0: carrier acquired983machine # [ 10.603578] systemd[636]: Finished Run user-specific NixOS activation.984machine # [ 10.604940] systemd[636]: Reached target Main User Target.985machine # [ 10.606454] systemd[636]: Startup finished in 467ms.986machine # [ 10.614233] dhcpcd[703]: DUID 00:01:00:01:32:2b:9a:ec:52:54:00:12:34:56987machine # [ 10.615245] dhcpcd[703]: eth0: IAID 00:12:34:56988machine # [ 10.616060] dhcpcd[703]: eth0: adding address fe80::5054:ff:fe12:3456989machine # [ 10.675202] systemd-vconsole-setup[634]: Configuration of first virtual console was skipped, ignoring remaining ones.990machine # [ 10.679857] systemd[1]: Finished Virtual Console Setup.991machine # [ 10.706759] dhcpcd[703]: eth0: soliciting a DHCP lease992machine # [ 10.867844] NET: Registered PF_PACKET protocol family993machine # [ 10.729505] dhcpcd[703]: eth0: offered 10.0.2.15 from 10.0.2.2994machine # [ 10.731150] dhcpcd[703]: eth0: probing address 10.0.2.15/24995machine # [ 13.554149] dhcpcd[703]: eth0: soliciting an IPv6 router996machine # [ 13.556429] dhcpcd[703]: eth0: Router Advertisement from fe80::2997machine # [ 13.558453] dhcpcd[703]: eth0: adding address fec0::5054:ff:fe12:3456/64998machine # [ 13.560657] dhcpcd[703]: eth0: adding route to fec0::/64999machine # [ 13.562541] dhcpcd[703]: eth0: adding default route via fe80::21000machine # [ 16.655840] dhcpcd[703]: eth0: leased 10.0.2.15 for 86400 seconds1001machine # [ 16.657325] dhcpcd[703]: eth0: adding route to 10.0.2.0/241002machine # [ 16.658229] dhcpcd[703]: eth0: adding default route via 10.0.2.21003machine # [ 16.760037] systemd[1]: Started DHCP Client.1004machine # [ 16.762077] systemd[1]: Reached target Multi-User System.1005machine # [ 16.762820] systemd[1]: Startup finished in 844ms (kernel) + 4.751s (initrd) + 11.165s (userspace) = 16.761s.1006machine: (finished: waiting for unit multi-user.target, in 18.13 seconds)1007machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test1008machine # Checking switch inhibitors... done1009machine # [ 17.813722] nixos[851]: switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test1010machine # [ 17.816249] systemd[1]: Stopped target Local File Systems.1011machine # [ 17.818069] systemd[1]: Stopped target Remote File Systems.1012machine # activating the configuration...1013machine # setting up /etc...1014machine # [ 18.196401] systemd[1]: Reload requested from client PID 851 ('.switch-to-conf') (unit backdoor.service)...1015machine # [ 18.197912] systemd[1]: Reloading...1016machine # [ 18.673946] systemd[1]: Reloading finished in 474 ms.1017machine # reloading user units for usertest...1018machine # [ 18.704531] systemd[636]: Created slice User Core Session Slice.1019machine # [ 18.707513] systemd[636]: Starting D-Bus User Message Bus...1020machine # [ 18.755958] dbus-broker-launch[921]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.import1.service'1021machine # [ 18.758599] dbus-broker-launch[921]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1022machine # [ 18.761313] dbus-broker-launch[921]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1023machine # [ 18.764167] dbus-broker-launch[921]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1024machine # [ 18.767457] systemd[636]: Started D-Bus User Message Bus.1025machine # [ 18.768771] dbus-broker-launch[921]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1026machine # [ 18.770277] dbus-broker-launch[921]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1027machine # [ 18.778276] dbus-broker-launch[921]: Ready1028machine # [ 18.863779] systemd[636]: Reexecution requested from client PID 920 ('switch-to-confi')...1029machine # [ 18.865659] systemd[636]: Reexecuting.1030machine # [ 18.890703] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1031machine # [ 19.040849] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1032machine # [ 19.046480] systemd[636]: Reload requested from client PID 920 ('switch-to-confi')...1033machine # [ 19.047728] systemd[636]: Reloading...1034machine # [ 19.189880] systemd[636]: Reloading finished in 140 ms.1035machine # [ 19.194784] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1036machine # restarting the following user units: nixos-activation.service1037machine # [ 19.202121] systemd[636]: Stopped Run user-specific NixOS activation.1038machine # [ 19.202991] systemd[636]: Stopping Run user-specific NixOS activation...1039machine # [ 19.205800] systemd[636]: Starting Run user-specific NixOS activation...1040machine # [ 19.239643] systemd[636]: Finished Run user-specific NixOS activation.1041machine # restarting sysinit-reactivation.target1042machine # [ 19.252972] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1043machine # [ 19.344181] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1044machine # [ 19.348119] systemd[1]: Reached target Reactivate sysinit units.1045machine # [ 19.350893] systemd[1]: Reached target Local File Systems.1046machine # [ 19.353074] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1047machine # [ 19.357294] systemd[1]: Starting Create SUID/SGID Wrappers...1048machine # [ 19.358231] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1049machine # [ 19.359384] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671050machine # [ 19.361301] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1051machine # [ 19.362700] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1052machine # [ 19.363843] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1053machine # [ 19.367132] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1054machine # [ 19.369221] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1055machine # [ 19.373801] systemd[1]: Reached target Remote File Systems.1056machine # [ 19.660126] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1057machine # [ 19.661445] systemd[1]: Finished Create SUID/SGID Wrappers.1058machine # [ 19.665988] systemd[1]: Starting linger-users.service...1059machine # [ 19.716258] systemd[1]: linger-users.service: Deactivated successfully.1060machine # [ 19.717457] systemd[1]: Finished linger-users.service.1061machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1062machine # [ 19.978772] nixos[851]: finished switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test1063machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test, in 2.68 seconds)1064machine: must succeed: rm /etc/NIXOS1065machine: (finished: must succeed: rm /etc/NIXOS, in 0.04 seconds)1066machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test/bin/switch-to-configuration test1067machine # this should succeed (config: /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test, action: test)1068machine # Checking switch inhibitors... done1069machine # [ 20.523675] nixos[998]: switching to system configuration /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test1070machine # activating the configuration...1071machine # [ 20.527349] systemd[1]: Stopped target Local File Systems.1072machine # [ 20.528241] systemd[1]: Stopped target Remote File Systems.1073machine # removing user ‘usertest’1074machine # setting up /etc...1075machine # [ 20.911519] systemd[1]: Reload requested from client PID 998 ('.switch-to-conf') (unit backdoor.service)...1076machine # [ 20.913206] systemd[1]: Reloading...1077machine # [ 21.398260] systemd[1]: Reloading finished in 483 ms.1078machine # reloading user units for usertest...1079machine # [ 21.520746] systemd[636]: Reexecution requested from client PID 1070 ('switch-to-confi')...1080machine # [ 21.522151] systemd[636]: Reexecuting.1081machine # [ 21.547337] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1082machine # [ 21.684433] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1083machine # [ 21.689537] systemd[636]: Reload requested from client PID 1070 ('switch-to-confi')...1084machine # [ 21.691204] systemd[636]: Reloading...1085machine # [ 21.815058] systemd[636]: Reloading finished in 122 ms.1086machine # [ 21.819983] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1087machine # restarting the following user units: nixos-activation.service1088machine # [ 21.827384] systemd[636]: Stopped Run user-specific NixOS activation.1089machine # [ 21.828391] systemd[636]: Stopping Run user-specific NixOS activation...1090machine # [ 21.831461] systemd[636]: Starting Run user-specific NixOS activation...1091machine # [ 21.865331] systemd[636]: Finished Run user-specific NixOS activation.1092machine # restarting sysinit-reactivation.target1093machine # [ 21.869609] systemd[1]: Stopped target Reactivate sysinit units.1094machine # [ 21.870721] systemd[1]: Stopping Reactivate sysinit units...1095machine # reloading the following units: dbus-broker.service[ 21.872309] systemd[1]: Reached target Reactivate sysinit units.1096machine # 1097machine # [ 21.874650] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1098machine # [ 21.876193] systemd[1]: Reloading D-Bus System Message Bus...1099machine # [ 21.894978] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1100machine # [ 21.897120] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1101machine # [ 21.898189] dbus-broker-launch[513]: Invalid user-name in /nix/store/h586j6zx3nwqd354pg3jzsx05yvfqlr4-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1102machine # [ 21.922355] systemd[1]: Reloaded D-Bus System Message Bus.1103machine # restarting the following units: network-addresses-eth1.service1104machine # [ 21.928099] systemd[1]: Stopping Address configuration of eth1...1105machine # [ 21.967309] network-addresses-eth1-pre-stop[1082]: deleting address 192.168.1.1/24... done1106machine # [ 21.977057] network-addresses-eth1-pre-stop[1082]: deleting address 2001:db8:1::1/64... done1107machine # [ 21.984890] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1108machine # [ 21.986619] systemd[1]: Stopped Address configuration of eth1.1109machine # [ 21.990081] systemd[1]: Starting Address configuration of eth1...1110machine # [ 22.051197] network-addresses-eth1-start[1087]: adding address 192.168.1.2/24... done1111machine # [ 22.062067] network-addresses-eth1-start[1087]: adding address 2001:db8:1::2/64... done1112machine # [ 22.074880] systemd[1]: Finished Address configuration of eth1.1113machine # [ 22.076992] systemd[1]: Reached target Remote File Systems.1114machine # [ 22.083304] systemd[1]: Starting linger-users.service...1115machine # [ 22.090259] systemd[1]: Reached target Local File Systems.1116machine # [ 22.091144] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1117machine # [ 22.094813] systemd[1]: Starting Create SUID/SGID Wrappers...1118machine # [ 22.095676] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1119machine # [ 22.096852] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671120machine # [ 22.098852] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1121machine # [ 22.100827] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1122machine # [ 22.103478] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1123machine # [ 22.112052] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1124machine # [ 22.113726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1125machine # [ 22.143890] linger-users-start[1101]: id: ‘usertest’: no such user1126machine # [ 22.145834] linger-users-start[1098]: Removing linger for missing user usertest1127machine # [ 22.154274] systemd[1]: linger-users.service: Deactivated successfully.1128machine # [ 22.155674] systemd[1]: Finished linger-users.service.1129machine # [ 22.424896] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1130machine # [ 22.426623] systemd[1]: Finished Create SUID/SGID Wrappers.1131machine # [ 22.681789] nixos[998]: finished switching to system configuration /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test1132machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/9w14g6ph6iqv67ndrh96m8j6md1xhxm9-nixos-system-other-test/bin/switch-to-configuration test, in 2.67 seconds)1133machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&11134machine # [ 23.129442] nixos[1165]: switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test1135machine # [ 23.131774] systemd[1]: Stopped target Remote File Systems.1136machine # [ 23.133393] systemd[1]: Stopped target Local File Systems.1137machine # [ 23.507804] systemd[1]: Reload requested from client PID 1165 ('.switch-to-conf') (unit backdoor.service)...1138machine # [ 23.509484] systemd[1]: Reloading...1139machine # [ 23.897839] systemd[1]: Reloading finished in 386 ms.1140machine # [ 24.003178] systemd[636]: Reexecution requested from client PID 1235 ('switch-to-confi')...1141machine # [ 24.005734] systemd[636]: Reexecuting.1142machine # [ 24.029731] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1143machine # [ 24.150119] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1144machine # [ 24.155905] systemd[636]: Reload requested from client PID 1235 ('switch-to-confi')...1145machine # [ 24.157122] systemd[636]: Reloading...1146machine # [ 24.271606] systemd[636]: Reloading finished in 111 ms.1147machine # [ 24.275790] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1148machine # [ 24.283275] systemd[636]: Stopped Run user-specific NixOS activation.1149machine # [ 24.285079] systemd[636]: Stopping Run user-specific NixOS activation...1150machine # [ 24.286977] systemd[636]: Starting Run user-specific NixOS activation...1151machine # [ 24.320988] systemd[636]: Finished Run user-specific NixOS activation.1152machine # [ 24.324235] systemd[1]: Stopped target Reactivate sysinit units.1153machine # [ 24.325412] systemd[1]: Stopping Reactivate sysinit units...1154machine # [ 24.326419] systemd[1]: Reached target Reactivate sysinit units.1155machine # [ 24.328935] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.1156machine # [ 24.330171] systemd[1]: Reloading D-Bus System Message Bus...1157machine # [ 24.342765] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...1158machine # [ 24.344569] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'1159machine # [ 24.345599] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1160machine # [ 24.361502] systemd[1]: Reloaded D-Bus System Message Bus.1161machine # [ 24.366328] systemd[1]: Stopping Address configuration of eth1...1162machine # [ 24.403523] network-addresses-eth1-pre-stop[1247]: deleting address 192.168.1.2/24... done1163machine # [ 24.413289] network-addresses-eth1-pre-stop[1247]: deleting address 2001:db8:1::2/64... done1164machine # [ 24.421098] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1165machine # [ 24.422834] systemd[1]: Stopped Address configuration of eth1.1166machine # [ 24.426489] systemd[1]: Starting Address configuration of eth1...1167machine # [ 24.472450] network-addresses-eth1-start[1252]: adding address 192.168.1.1/24... done1168machine # [ 24.480976] network-addresses-eth1-start[1252]: adding address 2001:db8:1::1/64... done1169machine # [ 24.490848] systemd[1]: Finished Address configuration of eth1.1170machine # [ 24.493300] systemd[1]: Reached target Remote File Systems.1171machine # [ 24.495611] systemd[1]: Reached target Local File Systems.1172machine # [ 24.501423] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1173machine # [ 24.503170] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1174machine # [ 24.507646] systemd[1]: Starting linger-users.service...1175machine # [ 24.509972] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1176machine # [ 24.515607] systemd[1]: Starting Create SUID/SGID Wrappers...1177machine # [ 24.516721] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1178machine # [ 24.517854] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671179machine # [ 24.520685] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1180machine # [ 24.523196] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1181machine # [ 24.526327] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1182machine # [ 24.563057] systemd[1]: linger-users.service: Deactivated successfully.1183machine # [ 24.564623] systemd[1]: Finished linger-users.service.1184machine # [ 24.759902] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1185machine # [ 24.761663] systemd[1]: Finished Create SUID/SGID Wrappers.1186machine # [ 25.018589] nixos[1165]: finished switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test1187machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.34 seconds)1188subtest: socket-activated services1189machine: must fail: [ -S /run/test.sock ]1190machine: (finished: must fail: [ -S /run/test.sock ], in 0.04 seconds)1191machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11192machine # [ 25.501968] nixos[1324]: switching to system configuration /nix/store/h4ymvbvy9761qw3llczjw1vfiy26v3kc-nixos-system-machine-test1193machine # [ 25.504300] systemd[1]: Stopped target Remote File Systems.1194machine # [ 25.505971] systemd[1]: Stopped target Local File Systems.1195machine # [ 25.965326] systemd[1]: Reload requested from client PID 1324 ('.switch-to-conf') (unit backdoor.service)...1196machine # [ 25.967102] systemd[1]: Reloading...1197machine # [ 26.495892] systemd[1]: Reloading finished in 526 ms.1198machine # [ 26.627507] systemd[636]: Reexecution requested from client PID 1393 ('switch-to-confi')...1199machine # [ 26.628794] systemd[636]: Reexecuting.1200machine # [ 26.656729] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1201machine # [ 26.804503] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1202machine # [ 26.809716] systemd[636]: Reload requested from client PID 1393 ('switch-to-confi')...1203machine # [ 26.810922] systemd[636]: Reloading...1204machine # [ 26.951042] systemd[636]: Reloading finished in 138 ms.1205machine # [ 26.956789] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1206machine # [ 26.963767] systemd[636]: Stopped Run user-specific NixOS activation.1207machine # [ 26.964622] systemd[636]: Stopping Run user-specific NixOS activation...1208machine # [ 26.967111] systemd[636]: Starting Run user-specific NixOS activation...1209machine # [ 27.001351] systemd[636]: Finished Run user-specific NixOS activation.1210machine # [ 27.004772] systemd[1]: Stopped target Reactivate sysinit units.1211machine # [ 27.006286] systemd[1]: Stopping Reactivate sysinit units...1212machine # [ 27.007296] systemd[1]: Reached target Reactivate sysinit units.1213machine # [ 27.010093] systemd[1]: Reached target Local File Systems.1214machine # [ 27.012238] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1215machine # [ 27.015690] systemd[1]: Starting Create SUID/SGID Wrappers...1216machine # [ 27.016581] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1217machine # [ 27.018220] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671218machine # [ 27.020839] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1219machine # [ 27.022908] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1220machine # [ 27.024826] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1221machine # [ 27.031628] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1222machine # [ 27.032946] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1223machine # [ 27.035708] systemd[1]: Reached target Remote File Systems.1224machine # [ 27.264652] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1225machine # [ 27.266411] systemd[1]: Finished Create SUID/SGID Wrappers.1226machine # [ 27.268465] systemd[1]: Listening on socket-activated.socket.1227machine # [ 27.272468] systemd[1]: Starting linger-users.service...1228machine # [ 27.319220] systemd[1]: linger-users.service: Deactivated successfully.1229machine # [ 27.320726] systemd[1]: Finished linger-users.service.1230machine # [ 27.578188] nixos[1324]: finished switching to system configuration /nix/store/h4ymvbvy9761qw3llczjw1vfiy26v3kc-nixos-system-machine-test1231machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 2.52 seconds)1232machine: must succeed: [ -S /run/test.sock ]1233machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1234machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11235machine # [ 28.167548] nixos[1468]: switching to system configuration /nix/store/l2krsrd7diaabwsb0wib9qc4ihr9d6s2-nixos-system-machine-test1236machine # [ 28.169937] systemd[1]: Stopped target Remote File Systems.1237machine # [ 28.171628] systemd[1]: Stopped target Local File Systems.1238machine # [ 28.601327] systemd[1]: Reload requested from client PID 1468 ('.switch-to-conf') (unit backdoor.service)...1239machine # [ 28.602955] systemd[1]: Reloading...1240machine # [ 29.122857] systemd[1]: Reloading finished in 518 ms.1241machine # [ 29.219530] systemd[636]: Reexecution requested from client PID 1537 ('switch-to-confi')...1242machine # [ 29.220772] systemd[636]: Reexecuting.1243machine # [ 29.245177] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1244machine # [ 29.358296] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1245machine # [ 29.363643] systemd[636]: Reload requested from client PID 1537 ('switch-to-confi')...1246machine # [ 29.364873] systemd[636]: Reloading...1247machine # [ 29.473991] systemd[636]: Reloading finished in 107 ms.1248machine # [ 29.479797] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1249machine # [ 29.487065] systemd[636]: Stopped Run user-specific NixOS activation.1250machine # [ 29.488027] systemd[636]: Stopping Run user-specific NixOS activation...1251machine # [ 29.490176] systemd[636]: Starting Run user-specific NixOS activation...1252machine # [ 29.518307] systemd[636]: Finished Run user-specific NixOS activation.1253machine # [ 29.521496] systemd[1]: Stopped target Reactivate sysinit units.1254machine # [ 29.523671] systemd[1]: Stopping Reactivate sysinit units...1255machine # [ 29.524520] systemd[1]: Reached target Reactivate sysinit units.1256machine # [ 29.525710] systemd[1]: Reached target Remote File Systems.1257machine # [ 29.528624] systemd[1]: Reached target Local File Systems.1258machine # [ 29.530969] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1259machine # [ 29.535331] systemd[1]: Starting Create SUID/SGID Wrappers...1260machine # [ 29.536189] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1261machine # [ 29.538257] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671262machine # [ 29.541326] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1263machine # [ 29.544274] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1264machine # [ 29.545458] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1265machine # [ 29.547423] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1266machine # [ 29.548811] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1267machine # [ 29.814613] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1268machine # [ 29.816311] systemd[1]: Finished Create SUID/SGID Wrappers.1269machine # [ 29.821508] systemd[1]: Starting linger-users.service...1270machine # [ 29.860788] systemd[1]: linger-users.service: Deactivated successfully.1271machine # [ 29.862235] systemd[1]: Finished linger-users.service.1272machine # [ 30.118786] nixos[1468]: finished switching to system configuration /nix/store/l2krsrd7diaabwsb0wib9qc4ihr9d6s2-nixos-system-machine-test1273machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 2.52 seconds)1274machine: must succeed: [ -S /run/test.sock ]1275machine: (finished: must succeed: [ -S /run/test.sock ], in 0.01 seconds)1276machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1277machine # [ 30.168688] systemd[1]: Started A socket-activated service.1278machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.72 seconds)1279machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11280machine # [ 31.306173] nixos[1616]: switching to system configuration /nix/store/h4ymvbvy9761qw3llczjw1vfiy26v3kc-nixos-system-machine-test1281machine # [ 31.308815] systemd[1]: Stopped target Local File Systems.1282machine # [ 31.310437] systemd[1]: Stopped target Remote File Systems.1283machine # [ 31.672781] systemd[1]: Reload requested from client PID 1616 ('.switch-to-conf') (unit backdoor.service)...1284machine # [ 31.674478] systemd[1]: Reloading...1285machine # [ 32.066196] systemd[1]: Reloading finished in 390 ms.1286machine # [ 32.176079] systemd[636]: Reexecution requested from client PID 1686 ('switch-to-confi')...1287machine # [ 32.177435] systemd[636]: Reexecuting.1288machine # [ 32.203555] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1289machine # [ 32.322682] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1290machine # [ 32.326532] systemd[636]: Reload requested from client PID 1686 ('switch-to-confi')...1291machine # [ 32.328175] systemd[636]: Reloading...1292machine # [ 32.450850] systemd[636]: Reloading finished in 121 ms.1293machine # [ 32.456100] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1294machine # [ 32.464830] systemd[636]: Stopped Run user-specific NixOS activation.1295machine # [ 32.465724] systemd[636]: Stopping Run user-specific NixOS activation...1296machine # [ 32.467932] systemd[636]: Starting Run user-specific NixOS activation...1297machine # [ 32.500173] systemd[636]: Finished Run user-specific NixOS activation.1298machine # [ 32.503481] systemd[1]: Stopped target Reactivate sysinit units.1299machine # [ 32.504663] systemd[1]: Stopping Reactivate sysinit units...1300machine # [ 32.505695] systemd[1]: Reached target Reactivate sysinit units.1301machine # [ 32.509184] systemd[1]: Stopping A socket-activated service...1302machine # [ 32.511752] systemd[1]: socket-activated.service: Deactivated successfully.1303machine # [ 32.512767] systemd[1]: Stopped A socket-activated service.1304machine # [ 32.516690] systemd[1]: Started A socket-activated service.1305machine # [ 32.524465] systemd[1]: Reached target Remote File Systems.1306machine # [ 32.527452] systemd[1]: Reached target Local File Systems.1307machine # [ 32.533476] systemd[1]: Starting linger-users.service...1308machine # [ 32.537118] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1309machine # [ 32.542214] systemd[1]: Starting Create SUID/SGID Wrappers...1310machine # [ 32.544223] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1311machine # [ 32.547234] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671312machine # [ 32.550254] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1313machine # [ 32.553113] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1314machine # [ 32.555158] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1315machine # [ 32.556525] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1316machine # [ 32.557726] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1317machine # [ 32.618490] systemd[1]: linger-users.service: Deactivated successfully.1318machine # [ 32.620451] systemd[1]: Finished linger-users.service.1319machine # [ 32.851870] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1320machine # [ 32.853141] systemd[1]: Finished Create SUID/SGID Wrappers.1321machine # [ 33.107968] nixos[1616]: finished switching to system configuration /nix/store/h4ymvbvy9761qw3llczjw1vfiy26v3kc-nixos-system-machine-test1322machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 2.25 seconds)1323machine: must succeed: [ -S /run/test.sock ]1324machine: (finished: must succeed: [ -S /run/test.sock ], in 0.01 seconds)1325machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1326machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.52 seconds)1327machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11328machine # [ 34.109301] nixos[1766]: switching to system configuration /nix/store/3kixawdafjnzzinngh23y88s4sqr8ww0-nixos-system-machine-test1329machine # [ 34.111719] systemd[1]: Stopped target Remote File Systems.1330machine # [ 34.113277] systemd[1]: socket-activated.socket: Deactivated successfully.1331machine # [ 34.114799] systemd[1]: Closed socket-activated.socket.1332machine # [ 34.117458] systemd[1]: Stopping A socket-activated service...1333machine # [ 34.119584] systemd[1]: Stopped target Local File Systems.1334machine # [ 34.121304] systemd[1]: socket-activated.service: Deactivated successfully.1335machine # [ 34.122723] systemd[1]: Stopped A socket-activated service.1336machine # [ 34.519085] systemd[1]: Reload requested from client PID 1766 ('.switch-to-conf') (unit backdoor.service)...1337machine # [ 34.521150] systemd[1]: Reloading...1338machine # [ 35.009139] systemd[1]: Reloading finished in 485 ms.1339machine # [ 35.128243] systemd[636]: Reexecution requested from client PID 1836 ('switch-to-confi')...1340machine # [ 35.130963] systemd[636]: Reexecuting.1341machine # [ 35.158416] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1342machine # [ 35.289181] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1343machine # [ 35.294269] systemd[636]: Reload requested from client PID 1836 ('switch-to-confi')...1344machine # [ 35.295743] systemd[636]: Reloading...1345machine # [ 35.412410] systemd[636]: Reloading finished in 114 ms.1346machine # [ 35.416800] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1347machine # [ 35.423067] systemd[636]: Stopped Run user-specific NixOS activation.1348machine # [ 35.424903] systemd[636]: Stopping Run user-specific NixOS activation...1349machine # [ 35.427082] systemd[636]: Starting Run user-specific NixOS activation...1350machine # [ 35.461704] systemd[636]: Finished Run user-specific NixOS activation.1351machine # [ 35.464914] systemd[1]: Stopped target Reactivate sysinit units.1352machine # [ 35.466649] systemd[1]: Stopping Reactivate sysinit units...1353machine # [ 35.467754] systemd[1]: Reached target Reactivate sysinit units.1354machine # [ 35.470988] systemd[1]: Listening on socket-activated.socket.1355machine # [ 35.474454] systemd[1]: Reached target Remote File Systems.1356machine # [ 35.478220] systemd[1]: Starting linger-users.service...1357machine # [ 35.479781] systemd[1]: Reached target Local File Systems.1358machine # [ 35.484326] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1359machine # [ 35.487323] systemd[1]: Starting Create SUID/SGID Wrappers...1360machine # [ 35.489208] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1361machine # [ 35.490347] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671362machine # [ 35.492316] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1363machine # [ 35.496114] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1364machine # [ 35.497330] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1365machine # [ 35.503207] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1366machine # [ 35.504592] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1367machine # [ 35.553453] systemd[1]: linger-users.service: Deactivated successfully.1368machine # [ 35.555186] systemd[1]: Finished linger-users.service.1369machine # [ 35.794695] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1370machine # [ 35.797247] systemd[1]: Finished Create SUID/SGID Wrappers.1371machine # [ 36.053753] nixos[1766]: finished switching to system configuration /nix/store/3kixawdafjnzzinngh23y88s4sqr8ww0-nixos-system-machine-test1372machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 2.41 seconds)1373machine: must succeed: [ -S /run/test.sock ]1374machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1375machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1376machine # [ 36.129119] systemd[1]: Started A socket-activated service.1377machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.66 seconds)1378machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11379machine # [ 37.203483] nixos[1915]: switching to system configuration /nix/store/ad5a67cxldmzfhdrjf58zis4xjajn1z2-nixos-system-machine-test1380machine # [ 37.205766] systemd[1]: Stopped target Remote File Systems.1381machine # [ 37.207462] systemd[1]: Stopped target Local File Systems.1382machine # [ 37.670856] systemd[1]: Reload requested from client PID 1915 ('.switch-to-conf') (unit backdoor.service)...1383machine # [ 37.672465] systemd[1]: Reloading...1384machine # [ 38.219090] systemd[1]: Reloading finished in 543 ms.1385machine # [ 38.330230] systemd[636]: Reexecution requested from client PID 1985 ('switch-to-confi')...1386machine # [ 38.332131] systemd[636]: Reexecuting.1387machine # [ 38.361052] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1388machine # [ 38.495844] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1389machine # [ 38.501837] systemd[636]: Reload requested from client PID 1985 ('switch-to-confi')...1390machine # [ 38.503588] systemd[636]: Reloading...1391machine # [ 38.624763] systemd[636]: Reloading finished in 119 ms.1392machine # [ 38.629782] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1393machine # [ 38.637091] systemd[636]: Stopped Run user-specific NixOS activation.1394machine # [ 38.637953] systemd[636]: Stopping Run user-specific NixOS activation...1395machine # [ 38.640105] systemd[636]: Starting Run user-specific NixOS activation...1396machine # [ 38.678914] systemd[636]: Finished Run user-specific NixOS activation.1397machine # [ 38.682938] systemd[1]: Stopped target Reactivate sysinit units.1398machine # [ 38.684605] systemd[1]: Stopping Reactivate sysinit units...1399machine # [ 38.685799] systemd[1]: Reached target Reactivate sysinit units.1400machine # [ 38.690384] systemd[1]: Reloading A socket-activated service...1401machine # [ 38.741387] systemd[1]: Reloaded A socket-activated service.1402machine # [ 38.746488] systemd[1]: Reached target Remote File Systems.1403machine # [ 38.748380] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1404machine # [ 38.749761] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1405machine # [ 38.752399] systemd[1]: Starting linger-users.service...1406machine # [ 38.756054] systemd[1]: Reached target Local File Systems.1407machine # [ 38.764106] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1408machine # [ 38.767064] systemd[1]: Starting Create SUID/SGID Wrappers...1409machine # [ 38.767849] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1410machine # [ 38.770484] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671411machine # [ 38.773298] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1412machine # [ 38.776769] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1413machine # [ 38.777929] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1414machine # [ 38.820923] systemd[1]: linger-users.service: Deactivated successfully.1415machine # [ 38.821949] systemd[1]: Finished linger-users.service.1416machine # [ 39.077475] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1417machine # [ 39.080545] systemd[1]: Finished Create SUID/SGID Wrappers.1418machine # [ 39.340986] nixos[1915]: finished switching to system configuration /nix/store/ad5a67cxldmzfhdrjf58zis4xjajn1z2-nixos-system-machine-test1419machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 2.60 seconds)1420machine: must succeed: [ -S /run/test.sock ]1421machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1422machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1423machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.54 seconds)1424machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11425machine # [ 40.420634] nixos[2065]: switching to system configuration /nix/store/zfrjs9z6h18miyir5lfzkbfrd8rzkvcw-nixos-system-machine-test1426machine # [ 40.423278] systemd[1]: Stopped target Local File Systems.1427machine # [ 40.424988] systemd[1]: Stopped target Remote File Systems.1428machine # [ 40.426722] systemd[1]: socket-activated.socket: Deactivated successfully.1429machine # [ 40.427623] systemd[1]: Closed socket-activated.socket.1430machine # [ 40.840830] systemd[1]: Reload requested from client PID 2065 ('.switch-to-conf') (unit backdoor.service)...1431machine # [ 40.842473] systemd[1]: Reloading...1432machine # [ 41.324842] systemd[1]: Reloading finished in 480 ms.1433machine # [ 41.433074] systemd[636]: Reexecution requested from client PID 2135 ('switch-to-confi')...1434machine # [ 41.435540] systemd[636]: Reexecuting.1435machine # [ 41.461795] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1436machine # [ 41.598208] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1437machine # [ 41.602410] systemd[636]: Reload requested from client PID 2135 ('switch-to-confi')...1438machine # [ 41.604195] systemd[636]: Reloading...1439machine # [ 41.729409] systemd[636]: Reloading finished in 123 ms.1440machine # [ 41.733855] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1441machine # [ 41.740191] systemd[636]: Stopped Run user-specific NixOS activation.1442machine # [ 41.742127] systemd[636]: Stopping Run user-specific NixOS activation...1443machine # [ 41.744250] systemd[636]: Starting Run user-specific NixOS activation...1444machine # [ 41.776829] systemd[636]: Finished Run user-specific NixOS activation.1445machine # [ 41.780309] systemd[1]: Stopped target Reactivate sysinit units.1446machine # [ 41.781633] systemd[1]: Stopping Reactivate sysinit units...1447machine # [ 41.782735] systemd[1]: Reached target Reactivate sysinit units.1448machine # [ 41.786343] systemd[1]: Stopping A socket-activated service...1449machine # [ 41.788808] systemd[1]: socket-activated.service: Deactivated successfully.1450machine # [ 41.789778] systemd[1]: Stopped A socket-activated service.1451machine # [ 41.793751] systemd[1]: Started A socket-activated service.1452machine # [ 41.797158] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1453machine # [ 41.798625] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1454machine # [ 41.804055] systemd[1]: Reached target Remote File Systems.1455machine # [ 41.807354] systemd[1]: Starting linger-users.service...1456machine # [ 41.809634] systemd[1]: Reached target Local File Systems.1457machine # [ 41.819542] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1458machine # [ 41.822855] systemd[1]: Starting Create SUID/SGID Wrappers...1459machine # [ 41.824394] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1460machine # [ 41.826310] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671461machine # [ 41.828541] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1462machine # [ 41.833287] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1463machine # [ 41.834676] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1464machine # [ 41.897900] systemd[1]: linger-users.service: Deactivated successfully.1465machine # [ 41.900198] systemd[1]: Finished linger-users.service.1466machine # [ 42.133988] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1467machine # [ 42.135741] systemd[1]: Finished Create SUID/SGID Wrappers.1468machine # [ 42.390086] nixos[2065]: finished switching to system configuration /nix/store/zfrjs9z6h18miyir5lfzkbfrd8rzkvcw-nixos-system-machine-test1469machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 2.47 seconds)1470machine: must succeed: systemctl is-active socket-activated.service1471machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.03 seconds)1472machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11473machine # [ 42.919038] nixos[2211]: switching to system configuration /nix/store/3kixawdafjnzzinngh23y88s4sqr8ww0-nixos-system-machine-test1474machine # [ 42.921609] systemd[1]: Stopped target Remote File Systems.1475machine # [ 42.924499] systemd[1]: Stopping A socket-activated service...1476machine # [ 42.926647] systemd[1]: Stopped target Local File Systems.1477machine # [ 42.928173] systemd[1]: socket-activated.service: Deactivated successfully.1478machine # [ 42.929531] systemd[1]: Stopped A socket-activated service.1479machine # [ 43.290571] systemd[1]: Reload requested from client PID 2211 ('.switch-to-conf') (unit backdoor.service)...1480machine # [ 43.292257] systemd[1]: Reloading...1481machine # [ 43.699447] systemd[1]: Reloading finished in 405 ms.1482machine # [ 43.796114] systemd[636]: Reexecution requested from client PID 2282 ('switch-to-confi')...1483machine # [ 43.798590] systemd[636]: Reexecuting.1484machine # [ 43.822934] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1485machine # [ 43.952955] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1486machine # [ 43.959282] systemd[636]: Reload requested from client PID 2282 ('switch-to-confi')...1487machine # [ 43.961166] systemd[636]: Reloading...1488machine # [ 44.102115] systemd[636]: Reloading finished in 139 ms.1489machine # [ 44.105103] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1490machine # [ 44.113063] systemd[636]: Stopped Run user-specific NixOS activation.1491machine # [ 44.113969] systemd[636]: Stopping Run user-specific NixOS activation...1492machine # [ 44.116519] systemd[636]: Starting Run user-specific NixOS activation...1493machine # [ 44.149210] systemd[636]: Finished Run user-specific NixOS activation.1494machine # [ 44.152629] systemd[1]: Stopped target Reactivate sysinit units.1495machine # [ 44.154304] systemd[1]: Stopping Reactivate sysinit units...1496machine # [ 44.155408] systemd[1]: Reached target Reactivate sysinit units.1497machine # [ 44.157960] systemd[1]: Reached target Remote File Systems.1498machine # [ 44.161484] systemd[1]: Starting linger-users.service...1499machine # [ 44.168340] systemd[1]: Reached target Local File Systems.1500machine # [ 44.169980] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1501machine # [ 44.173690] systemd[1]: Starting Create SUID/SGID Wrappers...1502machine # [ 44.174546] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1503machine # [ 44.175798] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671504machine # [ 44.177719] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1505machine # [ 44.180762] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1506machine # [ 44.185543] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1507machine # [ 44.186881] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1508machine # [ 44.188311] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1509machine # [ 44.236039] systemd[1]: linger-users.service: Deactivated successfully.1510machine # [ 44.237780] systemd[1]: Finished linger-users.service.1511machine # [ 44.471912] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1512machine # [ 44.473539] systemd[1]: Finished Create SUID/SGID Wrappers.1513machine # [ 44.475850] systemd[1]: Listening on socket-activated.socket.1514machine # [ 44.730528] nixos[2211]: finished switching to system configuration /nix/store/3kixawdafjnzzinngh23y88s4sqr8ww0-nixos-system-machine-test1515machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 2.31 seconds)1516machine: must succeed: [ -S /run/test.sock ]1517machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1518machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1519machine # [ 44.780782] systemd[1]: Started A socket-activated service.1520machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.66 seconds)1521(finished: subtest: socket-activated services, in 20.38 seconds)1522subtest: socket-activated services with Accept=yes1523machine: must fail: [ -S /run/accept-test.sock ]1524machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.02 seconds)1525machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11526machine # [ 45.889626] nixos[2363]: switching to system configuration /nix/store/bd7lxi1hqclv10qsm8s7shrf2n3b0s4n-nixos-system-machine-test1527machine # [ 45.892232] systemd[1]: Stopped target Local File Systems.1528machine # [ 45.895943] systemd[1]: Stopping A socket-activated service...1529machine # [ 45.897756] systemd[1]: Stopped target Remote File Systems.1530machine # [ 45.900515] systemd[1]: socket-activated.service: Deactivated successfully.1531machine # [ 45.901969] systemd[1]: Stopped A socket-activated service.1532machine # [ 45.903859] systemd[1]: socket-activated.socket: Deactivated successfully.1533machine # [ 45.905266] systemd[1]: Closed socket-activated.socket.1534machine # [ 46.341086] systemd[1]: Reload requested from client PID 2363 ('.switch-to-conf') (unit backdoor.service)...1535machine # [ 46.342722] systemd[1]: Reloading...1536machine # [ 46.862049] systemd[1]: Reloading finished in 517 ms.1537machine # [ 46.963089] systemd[636]: Reexecution requested from client PID 2433 ('switch-to-confi')...1538machine # [ 46.964411] systemd[636]: Reexecuting.1539machine # [ 46.991845] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1540machine # [ 47.140388] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1541machine # [ 47.145846] systemd[636]: Reload requested from client PID 2433 ('switch-to-confi')...1542machine # [ 47.147764] systemd[636]: Reloading...1543machine # [ 47.291259] systemd[636]: Reloading finished in 142 ms.1544machine # [ 47.297024] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1545machine # [ 47.305131] systemd[636]: Stopped Run user-specific NixOS activation.1546machine # [ 47.306209] systemd[636]: Stopping Run user-specific NixOS activation...1547machine # [ 47.309601] systemd[636]: Starting Run user-specific NixOS activation...1548machine # [ 47.349090] systemd[636]: Finished Run user-specific NixOS activation.1549machine # [ 47.352539] systemd[1]: Stopped target Reactivate sysinit units.1550machine # [ 47.354079] systemd[1]: Stopping Reactivate sysinit units...1551machine # [ 47.354870] systemd[1]: Reached target Reactivate sysinit units.1552machine # [ 47.356640] systemd[1]: Reached target Remote File Systems.1553machine # [ 47.363898] systemd[1]: Listening on accept-socket.socket.1554machine # [ 47.365405] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 47.367096] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1556machine # [ 47.371161] systemd[1]: Reached target Local File Systems.1557machine # [ 47.373234] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1558machine # [ 47.377420] systemd[1]: Starting Create SUID/SGID Wrappers...1559machine # [ 47.378236] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1560machine # [ 47.379415] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671561machine # [ 47.382886] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1562machine # [ 47.385170] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1563machine # [ 47.388361] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1564machine # [ 47.700920] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1565machine # [ 47.704329] systemd[1]: Finished Create SUID/SGID Wrappers.1566machine # [ 47.706549] systemd[1]: Starting linger-users.service...1567machine # [ 47.758932] systemd[1]: linger-users.service: Deactivated successfully.1568machine # [ 47.760526] systemd[1]: Finished linger-users.service.1569machine # [ 48.018387] nixos[2363]: finished switching to system configuration /nix/store/bd7lxi1hqclv10qsm8s7shrf2n3b0s4n-nixos-system-machine-test1570machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 2.60 seconds)1571machine: must succeed: [ -S /run/accept-test.sock ]1572machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.02 seconds)1573machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1574machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.02 seconds)1575machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1576machine # [ 48.090454] systemd[1]: Created slice Slice /system/accept-socket.1577machine # [ 48.093919] systemd[1]: Started A per-connection socket-activated service (PID 2507/UID 0).1578machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.05 seconds)1579machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11580machine # [ 48.605375] nixos[2519]: switching to system configuration /nix/store/n3dgq4s557462frhnc54vb7cjmmjffsj-nixos-system-machine-test1581machine # [ 48.607785] systemd[1]: accept-socket.socket: Deactivated successfully.1582machine # [ 48.609135] systemd[1]: Closed accept-socket.socket.1583machine # [ 48.610330] systemd[1]: Stopped target Remote File Systems.1584machine # [ 48.612436] systemd[1]: Stopping A per-connection socket-activated service (PID 2507/UID 0)...1585machine # [ 48.615191] systemd[1]: accept-socket@0-1-2507_2508-0.service: Deactivated successfully.1586machine # [ 48.616827] systemd[1]: Stopped A per-connection socket-activated service (PID 2507/UID 0).1587machine # [ 48.621060] systemd[1]: Stopped target Local File Systems.1588machine # [ 49.061523] systemd[1]: Reload requested from client PID 2519 ('.switch-to-conf') (unit backdoor.service)...1589machine # [ 49.063170] systemd[1]: Reloading...1590machine # [ 49.578637] systemd[1]: Reloading finished in 513 ms.1591machine # [ 49.698078] systemd[636]: Reexecution requested from client PID 2590 ('switch-to-confi')...1592machine # [ 49.699932] systemd[636]: Reexecuting.1593machine # [ 49.726488] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1594machine # [ 49.858785] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1595machine # [ 49.864550] systemd[636]: Reload requested from client PID 2590 ('switch-to-confi')...1596machine # [ 49.865998] systemd[636]: Reloading...1597machine # [ 49.998122] systemd[636]: Reloading finished in 130 ms.1598machine # [ 50.003793] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1599machine # [ 50.009886] systemd[636]: Stopped Run user-specific NixOS activation.1600machine # [ 50.010853] systemd[636]: Stopping Run user-specific NixOS activation...1601machine # [ 50.013857] systemd[636]: Starting Run user-specific NixOS activation...1602machine # [ 50.045357] systemd[636]: Finished Run user-specific NixOS activation.1603machine # [ 50.048486] systemd[1]: Stopped target Reactivate sysinit units.1604machine # [ 50.050578] systemd[1]: Stopping Reactivate sysinit units...1605machine # [ 50.051747] systemd[1]: Reached target Reactivate sysinit units.1606machine # [ 50.052845] systemd[1]: Reached target Remote File Systems.1607machine # [ 50.058111] systemd[1]: Reached target Local File Systems.1608machine # [ 50.058899] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1609machine # [ 50.061407] systemd[1]: Starting Create SUID/SGID Wrappers...1610machine # [ 50.063431] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1611machine # [ 50.065341] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671612machine # [ 50.068328] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1613machine # [ 50.070117] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1614machine # [ 50.072151] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1615machine # [ 50.074108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1616machine # [ 50.075353] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1617machine # [ 50.303911] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1618machine # [ 50.305653] systemd[1]: Finished Create SUID/SGID Wrappers.1619machine # [ 50.308658] systemd[1]: Listening on accept-socket.socket.1620machine # [ 50.312475] systemd[1]: Starting linger-users.service...1621machine # [ 50.352695] systemd[1]: linger-users.service: Deactivated successfully.1622machine # [ 50.353686] systemd[1]: Finished linger-users.service.1623machine # [ 50.610992] nixos[2519]: finished switching to system configuration /nix/store/n3dgq4s557462frhnc54vb7cjmmjffsj-nixos-system-machine-test1624machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 2.50 seconds)1625machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1626machine # [ 50.665186] systemd[1]: Started A per-connection socket-activated service (PID 2661/UID 0).1627machine # [ 50.710921] systemd[1]: accept-socket@1-2-2661_2662-0.service: Deactivated successfully.1628machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.10 seconds)1629(finished: subtest: socket-activated services with Accept=yes, in 5.31 seconds)1630subtest: mounts1631machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11632machine # [ 51.260865] nixos[2668]: switching to system configuration /nix/store/vvfjzbqr6cb3ywdr8kzj7qks95jpp4iw-nixos-system-machine-test1633machine # [ 51.263261] systemd[1]: Stopped target Local File Systems.1634machine # [ 51.265062] systemd[1]: accept-socket.socket: Deactivated successfully.1635machine # [ 51.266297] systemd[1]: Closed accept-socket.socket.1636machine # [ 51.267789] systemd[1]: Stopped target Remote File Systems.1637machine # [ 51.694499] systemd[1]: Reload requested from client PID 2668 ('.switch-to-conf') (unit backdoor.service)...1638machine # [ 51.696183] systemd[1]: Reloading...1639machine # [ 52.170207] systemd[1]: Reloading finished in 471 ms.1640machine # [ 52.282712] systemd[636]: Reexecution requested from client PID 2737 ('switch-to-confi')...1641machine # [ 52.284703] systemd[636]: Reexecuting.1642machine # [ 52.313773] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1643machine # [ 52.444400] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1644machine # [ 52.449814] systemd[636]: Reload requested from client PID 2737 ('switch-to-confi')...1645machine # [ 52.451095] systemd[636]: Reloading...1646machine # [ 52.584790] systemd[636]: Reloading finished in 132 ms.1647machine # [ 52.589789] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1648machine # [ 52.596766] systemd[636]: Stopped Run user-specific NixOS activation.1649machine # [ 52.597615] systemd[636]: Stopping Run user-specific NixOS activation...1650machine # [ 52.599900] systemd[636]: Starting Run user-specific NixOS activation...1651machine # [ 52.628845] systemd[636]: Finished Run user-specific NixOS activation.1652machine # [ 52.632710] systemd[1]: Stopped target Reactivate sysinit units.1653machine # [ 52.633860] systemd[1]: Stopping Reactivate sysinit units...1654machine # [ 52.634787] systemd[1]: Reached target Reactivate sysinit units.1655machine # [ 52.639362] systemd[1]: Mounting Testmount...1656machine # [ 52.641333] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1657machine # [ 52.644302] systemd[1]: Starting Create SUID/SGID Wrappers...1658machine # [ 52.645995] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671659machine # [ 52.649680] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1660machine # [ 52.651705] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1661machine # [ 52.653740] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1662machine # [ 52.657578] systemd[1]: Reached target Remote File Systems.1663machine # [ 52.662567] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1664machine # [ 52.663861] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1665machine # [ 52.695874] systemd[1]: Mounted Testmount.1666machine # [ 52.698905] systemd[1]: Reached target Local File Systems.1667machine # [ 52.699781] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1668machine # [ 52.948953] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1669machine # [ 52.950152] systemd[1]: Finished Create SUID/SGID Wrappers.1670machine # [ 52.954670] systemd[1]: Starting linger-users.service...1671machine # [ 52.997742] systemd[1]: linger-users.service: Deactivated successfully.1672machine # [ 52.999548] systemd[1]: Finished linger-users.service.1673machine # [ 53.257112] nixos[2668]: finished switching to system configuration /nix/store/vvfjzbqr6cb3ywdr8kzj7qks95jpp4iw-nixos-system-machine-test1674machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 2.55 seconds)1675machine: must succeed: mount | grep 'on /testmount'1676machine: (finished: must succeed: mount | grep 'on /testmount', in 0.04 seconds)1677machine: must succeed: systemctl stop testmount.mount1678machine # [ 53.334650] systemd[1]: Unmounting Testmount...1679machine # [ 53.365827] systemd[1]: testmount.mount: Deactivated successfully.1680machine # [ 53.366685] systemd[1]: Unmounted Testmount.1681machine: (finished: must succeed: systemctl stop testmount.mount, in 0.07 seconds)1682machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11683machine # [ 53.775273] nixos[2821]: switching to system configuration /nix/store/vvfjzbqr6cb3ywdr8kzj7qks95jpp4iw-nixos-system-machine-test1684machine # [ 53.777612] systemd[1]: Stopped target Remote File Systems.1685machine # [ 53.779054] systemd[1]: Stopped target Local File Systems.1686machine # [ 54.158056] systemd[1]: Reload requested from client PID 2821 ('.switch-to-conf') (unit backdoor.service)...1687machine # [ 54.159698] systemd[1]: Reloading...1688machine # [ 54.610181] systemd[1]: Reloading finished in 448 ms.1689machine # [ 54.723968] systemd[636]: Reexecution requested from client PID 2890 ('switch-to-confi')...1690machine # [ 54.726585] systemd[636]: Reexecuting.1691machine # [ 54.751350] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1692machine # [ 54.885447] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1693machine # [ 54.890243] systemd[636]: Reload requested from client PID 2890 ('switch-to-confi')...1694machine # [ 54.891927] systemd[636]: Reloading...1695machine # [ 55.028928] systemd[636]: Reloading finished in 136 ms.1696machine # [ 55.033789] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1697machine # [ 55.040719] systemd[636]: Stopped Run user-specific NixOS activation.1698machine # [ 55.041621] systemd[636]: Stopping Run user-specific NixOS activation...1699machine # [ 55.043986] systemd[636]: Starting Run user-specific NixOS activation...1700machine # [ 55.074928] systemd[636]: Finished Run user-specific NixOS activation.1701machine # [ 55.078289] systemd[1]: Stopped target Reactivate sysinit units.1702machine # [ 55.079485] systemd[1]: Stopping Reactivate sysinit units...1703machine # [ 55.080509] systemd[1]: Reached target Reactivate sysinit units.1704machine # [ 55.086156] systemd[1]: Mounting Testmount...1705machine # [ 55.086770] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1706machine # [ 55.091178] systemd[1]: Starting Create SUID/SGID Wrappers...1707machine # [ 55.092164] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671708machine # [ 55.094172] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1709machine # [ 55.095568] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1710machine # [ 55.096706] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1711machine # [ 55.101364] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1712machine # [ 55.103413] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1713machine # [ 55.108374] systemd[1]: Reached target Remote File Systems.1714machine # [ 55.140698] systemd[1]: Mounted Testmount.1715machine # [ 55.142868] systemd[1]: Reached target Local File Systems.1716machine # [ 55.143979] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1717machine # [ 55.377662] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1718machine # [ 55.381151] systemd[1]: Finished Create SUID/SGID Wrappers.1719machine # [ 55.383433] systemd[1]: Starting linger-users.service...1720machine # [ 55.422664] systemd[1]: linger-users.service: Deactivated successfully.1721machine # [ 55.424104] systemd[1]: Finished linger-users.service.1722machine # [ 55.682296] nixos[2821]: finished switching to system configuration /nix/store/vvfjzbqr6cb3ywdr8kzj7qks95jpp4iw-nixos-system-machine-test1723machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 2.32 seconds)1724machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11725machine # [ 56.167926] nixos[2963]: switching to system configuration /nix/store/9mbiarmksfm752d4bqf71zqvcmrww6yw-nixos-system-machine-test1726machine # [ 56.170684] systemd[1]: Stopped target Local File Systems.1727machine # [ 56.172443] systemd[1]: Stopped target Remote File Systems.1728machine # [ 56.575169] systemd[1]: Reload requested from client PID 2963 ('.switch-to-conf') (unit backdoor.service)...1729machine # [ 56.577130] systemd[1]: Reloading...1730machine # [ 57.063079] systemd[1]: Reloading finished in 483 ms.1731machine # [ 57.171898] systemd[636]: Reexecution requested from client PID 3032 ('switch-to-confi')...1732machine # [ 57.174456] systemd[636]: Reexecuting.1733machine # [ 57.197496] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1734machine # [ 57.324460] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1735machine # [ 57.329440] systemd[636]: Reload requested from client PID 3032 ('switch-to-confi')...1736machine # [ 57.331090] systemd[636]: Reloading...1737machine # [ 57.456357] systemd[636]: Reloading finished in 124 ms.1738machine # [ 57.461806] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1739machine # [ 57.469178] systemd[636]: Stopped Run user-specific NixOS activation.1740machine # [ 57.470057] systemd[636]: Stopping Run user-specific NixOS activation...1741machine # [ 57.472636] systemd[636]: Starting Run user-specific NixOS activation...1742machine # [ 57.514698] systemd[636]: Finished Run user-specific NixOS activation.1743machine # [ 57.518748] systemd[1]: Stopped target Reactivate sysinit units.1744machine # [ 57.520449] systemd[1]: Stopping Reactivate sysinit units...1745machine # [ 57.521763] systemd[1]: Reached target Reactivate sysinit units.1746machine # [ 57.524894] systemd[1]: Reloading Testmount...1747machine # [ 57.551769] systemd[1]: Reloaded Testmount.1748machine # [ 57.555364] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1749machine # [ 57.557164] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1750machine # [ 57.560918] systemd[1]: Reached target Local File Systems.1751machine # [ 57.563132] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1752machine # [ 57.567170] systemd[1]: Starting Create SUID/SGID Wrappers...1753machine # [ 57.567983] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1754machine # [ 57.569099] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671755machine # [ 57.572458] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1756machine # [ 57.574657] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1757machine # [ 57.577375] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1758machine # [ 57.580065] systemd[1]: Reached target Remote File Systems.1759machine # [ 57.833625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1760machine # [ 57.835480] systemd[1]: Finished Create SUID/SGID Wrappers.1761machine # [ 57.839514] systemd[1]: Starting linger-users.service...1762machine # [ 57.879694] systemd[1]: linger-users.service: Deactivated successfully.1763machine # [ 57.881121] systemd[1]: Finished linger-users.service.1764machine # [ 58.137732] nixos[2963]: finished switching to system configuration /nix/store/9mbiarmksfm752d4bqf71zqvcmrww6yw-nixos-system-machine-test1765machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 2.46 seconds)1766machine: must succeed: mount | grep 'on /testmount'1767machine: (finished: must succeed: mount | grep 'on /testmount', in 0.04 seconds)1768machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11769machine # [ 58.659255] nixos[3110]: switching to system configuration /nix/store/pys1x5ydbd24cf6dwrks9s4pfvqmwgys-nixos-system-machine-test1770machine # [ 58.661534] systemd[1]: Stopped target Local File Systems.1771machine # [ 58.663173] systemd[1]: Stopped target Remote File Systems.1772machine # [ 59.033361] systemd[1]: Reload requested from client PID 3110 ('.switch-to-conf') (unit backdoor.service)...1773machine # [ 59.034999] systemd[1]: Reloading...1774machine # [ 59.522064] systemd[1]: Reloading finished in 485 ms.1775machine # [ 59.633626] systemd[636]: Reexecution requested from client PID 3179 ('switch-to-confi')...1776machine # [ 59.636803] systemd[636]: Reexecuting.1777machine # [ 59.661212] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1778machine # [ 59.791162] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1779machine # [ 59.797712] systemd[636]: Reload requested from client PID 3179 ('switch-to-confi')...1780machine # [ 59.799282] systemd[636]: Reloading...1781machine # [ 59.932926] systemd[636]: Reloading finished in 131 ms.1782machine # [ 59.938862] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1783machine # [ 59.946083] systemd[636]: Stopped Run user-specific NixOS activation.1784machine # [ 59.946936] systemd[636]: Stopping Run user-specific NixOS activation...1785machine # [ 59.949797] systemd[636]: Starting Run user-specific NixOS activation...1786machine # [ 59.978755] systemd[636]: Finished Run user-specific NixOS activation.1787machine # [ 59.982241] systemd[1]: Stopped target Reactivate sysinit units.1788machine # [ 59.983435] systemd[1]: Stopping Reactivate sysinit units...1789machine # [ 59.984415] systemd[1]: Reached target Reactivate sysinit units.1790machine # [ 59.987288] systemd[1]: Unmounting Testmount...1791machine # [ 60.011175] systemd[1]: testmount.mount: Deactivated successfully.1792machine # [ 60.012208] systemd[1]: Unmounted Testmount.1793machine # [ 60.015904] systemd[1]: Mounting Testmount...1794machine # [ 60.041178] systemd[1]: Mounted Testmount.1795machine # [ 60.044221] systemd[1]: Reached target Local File Systems.1796machine # [ 60.047494] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1797machine # [ 60.049144] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1798machine # [ 60.051983] systemd[1]: Reached target Remote File Systems.1799machine # [ 60.055555] systemd[1]: Starting linger-users.service...1800machine # [ 60.062215] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1801machine # [ 60.065176] systemd[1]: Starting Create SUID/SGID Wrappers...1802machine # [ 60.067555] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1803machine # [ 60.068787] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671804machine # [ 60.070691] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1805machine # [ 60.072143] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1806machine # [ 60.073295] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1807machine # [ 60.130308] systemd[1]: linger-users.service: Deactivated successfully.1808machine # [ 60.131348] systemd[1]: Finished linger-users.service.1809machine # [ 60.348979] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1810machine # [ 60.350270] systemd[1]: Finished Create SUID/SGID Wrappers.1811machine # [ 60.605050] nixos[3110]: finished switching to system configuration /nix/store/pys1x5ydbd24cf6dwrks9s4pfvqmwgys-nixos-system-machine-test1812machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 2.43 seconds)1813machine: must succeed: mount | grep 'on /testmount'1814machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1815(finished: subtest: mounts, in 9.92 seconds)1816subtest: timers1817machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11818machine # [ 61.114862] nixos[3259]: switching to system configuration /nix/store/rglm8yah56cbf88cqwglwj8ygl8hz2ga-nixos-system-machine-test1819machine # [ 61.117537] systemd[1]: Stopped target Local File Systems.1820machine # [ 61.121024] systemd[1]: Unmounting Testmount...1821machine # [ 61.122601] systemd[1]: Stopped target Remote File Systems.1822machine # [ 61.145839] systemd[1]: testmount.mount: Deactivated successfully.1823machine # [ 61.147432] systemd[1]: Unmounted Testmount.1824machine # [ 61.513833] systemd[1]: Reload requested from client PID 3259 ('.switch-to-conf') (unit backdoor.service)...1825machine # [ 61.515469] systemd[1]: Reloading...1826machine # [ 61.953401] systemd[1]: Reloading finished in 436 ms.1827machine # [ 62.065522] systemd[636]: Reexecution requested from client PID 3330 ('switch-to-confi')...1828machine # [ 62.066773] systemd[636]: Reexecuting.1829machine # [ 62.088836] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1830machine # [ 62.207747] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1831machine # [ 62.212869] systemd[636]: Reload requested from client PID 3330 ('switch-to-confi')...1832machine # [ 62.214613] systemd[636]: Reloading...1833machine # [ 62.336825] systemd[636]: Reloading finished in 120 ms.1834machine # [ 62.340805] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1835machine # [ 62.347163] systemd[636]: Stopped Run user-specific NixOS activation.1836machine # [ 62.349079] systemd[636]: Stopping Run user-specific NixOS activation...1837machine # [ 62.351423] systemd[636]: Starting Run user-specific NixOS activation...1838machine # [ 62.379943] systemd[636]: Finished Run user-specific NixOS activation.1839machine # [ 62.383238] systemd[1]: Stopped target Reactivate sysinit units.1840machine # [ 62.384738] systemd[1]: Stopping Reactivate sysinit units...1841machine # [ 62.386148] systemd[1]: Reached target Reactivate sysinit units.1842machine # [ 62.388804] systemd[1]: Reached target Remote File Systems.1843machine # [ 62.392686] systemd[1]: Started test-timer.timer.1844machine # [ 62.394120] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1845machine # [ 62.395830] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1846machine # [ 62.397736] systemd[1]: Reached target Local File Systems.1847machine # [ 62.401056] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1848machine # [ 62.405346] systemd[1]: Starting Create SUID/SGID Wrappers...1849machine # [ 62.407316] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1850machine # [ 62.408626] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671851machine # [ 62.412328] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1852machine # [ 62.414709] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1853machine # [ 62.416222] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1854machine # [ 62.645565] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1855machine # [ 62.646756] systemd[1]: Finished Create SUID/SGID Wrappers.1856machine # [ 62.650731] systemd[1]: Starting linger-users.service...1857machine # [ 62.691375] systemd[1]: linger-users.service: Deactivated successfully.1858machine # [ 62.692993] systemd[1]: Finished linger-users.service.1859machine # [ 62.950755] nixos[3259]: finished switching to system configuration /nix/store/rglm8yah56cbf88cqwglwj8ygl8hz2ga-nixos-system-machine-test1860machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 2.32 seconds)1861machine: must succeed: systemctl show test-timer.timer1862machine: (finished: must succeed: systemctl show test-timer.timer, in 0.05 seconds)1863machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11864machine # [ 63.471885] nixos[3404]: switching to system configuration /nix/store/ff7qarmvwlfcl5c0g77hrg6h3d5j77mm-nixos-system-machine-test1865machine # [ 63.474501] systemd[1]: Stopped target Local File Systems.1866machine # [ 63.476479] systemd[1]: Stopped target Remote File Systems.1867machine # [ 63.904857] systemd[1]: Reload requested from client PID 3404 ('.switch-to-conf') (unit backdoor.service)...1868machine # [ 63.906768] systemd[1]: Reloading...1869machine # [ 64.989654] systemd[1]: Reloading finished in 1080 ms.1870machine # [ 65.093722] systemd[636]: Reexecution requested from client PID 3473 ('switch-to-confi')...1871machine # [ 65.096482] systemd[636]: Reexecuting.1872machine # [ 65.123134] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1873machine # [ 65.240484] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1874machine # [ 65.245832] systemd[636]: Reload requested from client PID 3473 ('switch-to-confi')...1875machine # [ 65.247333] systemd[636]: Reloading...1876machine # [ 65.357677] systemd[636]: Reloading finished in 109 ms.1877machine # [ 65.364059] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1878machine # [ 65.370556] systemd[636]: Stopped Run user-specific NixOS activation.1879machine # [ 65.372060] systemd[636]: Stopping Run user-specific NixOS activation...1880machine # [ 65.374434] systemd[636]: Starting Run user-specific NixOS activation...1881machine # [ 65.404896] systemd[636]: Finished Run user-specific NixOS activation.1882machine # [ 65.408070] systemd[1]: Stopped target Reactivate sysinit units.1883machine # [ 65.409647] systemd[1]: Stopping Reactivate sysinit units...1884machine # [ 65.410847] systemd[1]: Reached target Reactivate sysinit units.1885machine # [ 65.411976] systemd[1]: test-timer.timer: Deactivated successfully.1886machine # [ 65.413322] systemd[1]: Stopped test-timer.timer.1887machine # [ 65.414430] systemd[1]: Stopping test-timer.timer...1888machine # [ 65.415329] systemd[1]: Started test-timer.timer.1889machine # [ 65.418402] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1890machine # [ 65.420128] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1891machine # [ 65.422100] systemd[1]: Reached target Local File Systems.1892machine # [ 65.426485] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1893machine # [ 65.430880] systemd[1]: Starting Create SUID/SGID Wrappers...1894machine # [ 65.432115] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1895machine # [ 65.433652] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671896machine # [ 65.435928] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1897machine # [ 65.438161] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1898machine # [ 65.440112] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1899machine # [ 65.444600] systemd[1]: Reached target Remote File Systems.1900machine # [ 65.657147] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1901machine # [ 65.658730] systemd[1]: Finished Create SUID/SGID Wrappers.1902machine # [ 65.663064] systemd[1]: Starting linger-users.service...1903machine # [ 65.700822] systemd[1]: linger-users.service: Deactivated successfully.1904machine # [ 65.702331] systemd[1]: Finished linger-users.service.1905machine # [ 65.957123] nixos[3404]: finished switching to system configuration /nix/store/ff7qarmvwlfcl5c0g77hrg6h3d5j77mm-nixos-system-machine-test1906machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 2.96 seconds)1907machine: must succeed: systemctl show test-timer.timer1908machine: (finished: must succeed: systemctl show test-timer.timer, in 0.04 seconds)1909(finished: subtest: timers, in 5.37 seconds)1910subtest: targets1911machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11912machine # [ 66.436456] nixos[3547]: switching to system configuration /nix/store/r53y087c64i81ck927ipawii62kpdm3h-nixos-system-machine-test1913machine # [ 66.439447] systemd[1]: Stopped target Local File Systems.1914machine # [ 66.440692] systemd[1]: Stopped target Remote File Systems.1915machine # [ 66.442226] systemd[1]: test-timer.timer: Deactivated successfully.1916machine # [ 66.443481] systemd[1]: Stopped test-timer.timer.1917machine # [ 66.820679] systemd[1]: Reload requested from client PID 3547 ('.switch-to-conf') (unit backdoor.service)...1918machine # [ 66.822385] systemd[1]: Reloading...1919machine # [ 67.266521] systemd[1]: Reloading finished in 442 ms.1920machine # [ 67.358312] systemd[636]: Reexecution requested from client PID 3616 ('switch-to-confi')...1921machine # [ 67.359635] systemd[636]: Reexecuting.1922machine # [ 67.389166] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1923machine # [ 67.508568] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1924machine # [ 67.513759] systemd[636]: Reload requested from client PID 3616 ('switch-to-confi')...1925machine # [ 67.514906] systemd[636]: Reloading...1926machine # [ 67.646975] systemd[636]: Reloading finished in 130 ms.1927machine # [ 67.652858] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1928machine # [ 67.659537] systemd[636]: Stopped Run user-specific NixOS activation.1929machine # [ 67.660590] systemd[636]: Stopping Run user-specific NixOS activation...1930machine # [ 67.663719] systemd[636]: Starting Run user-specific NixOS activation...1931machine # [ 67.699422] systemd[636]: Finished Run user-specific NixOS activation.1932machine # [ 67.702821] systemd[1]: Stopped target Reactivate sysinit units.1933machine # [ 67.704425] systemd[1]: Stopping Reactivate sysinit units...1934machine # [ 67.705548] systemd[1]: Reached target Reactivate sysinit units.1935machine # [ 67.707939] systemd[1]: Reached target Local File Systems.1936machine # [ 67.710135] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1937machine # [ 67.714196] systemd[1]: Starting Create SUID/SGID Wrappers...1938machine # [ 67.714977] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1939machine # [ 67.716825] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671940machine # [ 67.719069] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1941machine # [ 67.721403] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1942machine # [ 67.722903] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1943machine # [ 67.725314] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1944machine # [ 67.726524] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1945machine # [ 67.735094] systemd[1]: Reached target Remote File Systems.1946machine # [ 68.008640] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1947machine # [ 68.011342] systemd[1]: Finished Create SUID/SGID Wrappers.1948machine # [ 68.014338] systemd[1]: Starting linger-users.service...1949machine # [ 68.063607] systemd[1]: linger-users.service: Deactivated successfully.1950machine # [ 68.065309] systemd[1]: Finished linger-users.service.1951machine # [ 68.320063] nixos[3547]: finished switching to system configuration /nix/store/r53y087c64i81ck927ipawii62kpdm3h-nixos-system-machine-test1952machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 2.32 seconds)1953machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11954machine # [ 68.842830] nixos[3687]: switching to system configuration /nix/store/7ici7m0wplac01d51i65m4b1ghbgph2i-nixos-system-machine-test1955machine # [ 68.846343] systemd[1]: Stopped target Local File Systems.1956machine # [ 68.847294] systemd[1]: Stopped target Remote File Systems.1957machine # [ 69.238916] systemd[1]: Reload requested from client PID 3687 ('.switch-to-conf') (unit backdoor.service)...1958machine # [ 69.240549] systemd[1]: Reloading...1959machine # [ 69.776522] systemd[1]: Reloading finished in 534 ms.1960machine # [ 69.889505] systemd[636]: Reexecution requested from client PID 3756 ('switch-to-confi')...1961machine # [ 69.890756] systemd[636]: Reexecuting.1962machine # [ 69.915240] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1963machine # [ 70.044058] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1964machine # [ 70.049770] systemd[636]: Reload requested from client PID 3756 ('switch-to-confi')...1965machine # [ 70.051235] systemd[636]: Reloading...1966machine # [ 70.173860] systemd[636]: Reloading finished in 120 ms.1967machine # [ 70.178815] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1968machine # [ 70.184991] systemd[636]: Stopped Run user-specific NixOS activation.1969machine # [ 70.186065] systemd[636]: Stopping Run user-specific NixOS activation...1970machine # [ 70.189318] systemd[636]: Starting Run user-specific NixOS activation...1971machine # [ 70.223471] systemd[636]: Finished Run user-specific NixOS activation.1972machine # [ 70.226839] systemd[1]: Stopped target Reactivate sysinit units.1973machine # [ 70.228952] systemd[1]: Stopping Reactivate sysinit units...1974machine # [ 70.230145] systemd[1]: Reached target Reactivate sysinit units.1975machine # [ 70.232092] systemd[1]: Reached target Local File Systems.1976machine # [ 70.234066] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1977machine # [ 70.238412] systemd[1]: Starting Create SUID/SGID Wrappers...1978machine # [ 70.239424] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1979machine # [ 70.240621] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671980machine # [ 70.243667] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1981machine # [ 70.246279] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1982machine # [ 70.248633] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1983machine # [ 70.251577] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1984machine # [ 70.252738] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1985machine # [ 70.253914] systemd[1]: Reached target Remote File Systems.1986machine # [ 70.257763] systemd[1]: Reached target test-target.target.1987machine # [ 70.530647] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1988machine # [ 70.532486] systemd[1]: Finished Create SUID/SGID Wrappers.1989machine # [ 70.537347] systemd[1]: Starting linger-users.service...1990machine # [ 70.587589] systemd[1]: linger-users.service: Deactivated successfully.1991machine # [ 70.589195] systemd[1]: Finished linger-users.service.1992machine # [ 70.844297] nixos[3687]: finished switching to system configuration /nix/store/7ici7m0wplac01d51i65m4b1ghbgph2i-nixos-system-machine-test1993machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 2.52 seconds)1994machine # [ 70.887263] systemd[1]: Started test-service.service.1995machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11996machine # [ 71.365261] nixos[3833]: switching to system configuration /nix/store/546l1xgcz3y2d4gygd74b6gzcdxxvyh6-nixos-system-machine-test1997machine # [ 71.368592] systemd[1]: Stopped target Local File Systems.1998machine # [ 71.369504] systemd[1]: Stopped target Remote File Systems.1999machine # [ 71.853819] systemd[1]: Reload requested from client PID 3833 ('.switch-to-conf') (unit backdoor.service)...2000machine # [ 71.855579] systemd[1]: Reloading...2001machine # [ 72.624293] systemd[1]: Reloading finished in 766 ms.2002machine # [ 72.740168] systemd[636]: Reexecution requested from client PID 3903 ('switch-to-confi')...2003machine # [ 72.741447] systemd[636]: Reexecuting.2004machine # [ 72.768756] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2005machine # [ 72.906415] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2006machine # [ 72.911683] systemd[636]: Reload requested from client PID 3903 ('switch-to-confi')...2007machine # [ 72.913162] systemd[636]: Reloading...2008machine # [ 73.046787] systemd[636]: Reloading finished in 132 ms.2009machine # [ 73.051801] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2010machine # [ 73.059078] systemd[636]: Stopped Run user-specific NixOS activation.2011machine # [ 73.060023] systemd[636]: Stopping Run user-specific NixOS activation...2012machine # [ 73.062684] systemd[636]: Starting Run user-specific NixOS activation...2013machine # [ 73.096964] systemd[636]: Finished Run user-specific NixOS activation.2014machine # [ 73.099989] systemd[1]: Stopped target Reactivate sysinit units.2015machine # [ 73.101545] systemd[1]: Stopping Reactivate sysinit units...2016machine # [ 73.102516] systemd[1]: Reached target Reactivate sysinit units.2017machine # [ 73.105817] systemd[1]: Reached target Remote File Systems.2018machine # [ 73.110094] systemd[1]: Reached target Local File Systems.2019machine # [ 73.112184] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2020machine # [ 73.116056] systemd[1]: Starting Create SUID/SGID Wrappers...2021machine # [ 73.116852] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2022machine # [ 73.118045] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672023machine # [ 73.121423] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2024machine # [ 73.123513] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2025machine # [ 73.126262] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2026machine # [ 73.131209] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2027machine # [ 73.132968] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2028machine # [ 73.377597] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2029machine # [ 73.380333] systemd[1]: Finished Create SUID/SGID Wrappers.2030machine # [ 73.383938] systemd[1]: Starting linger-users.service...2031machine # [ 73.424571] systemd[1]: linger-users.service: Deactivated successfully.2032machine # [ 73.426080] systemd[1]: Finished linger-users.service.2033machine # [ 73.682756] nixos[3833]: finished switching to system configuration /nix/store/546l1xgcz3y2d4gygd74b6gzcdxxvyh6-nixos-system-machine-test2034machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 2.80 seconds)2035machine: must succeed: systemctl is-active test-service.service2036machine: (finished: must succeed: systemctl is-active test-service.service, in 0.04 seconds)2037machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&12038machine # [ 74.209349] nixos[3977]: switching to system configuration /nix/store/3fmfs1qmkg13vrl6z0f7326cpbw3ni7r-nixos-system-machine-test2039machine # [ 74.211853] systemd[1]: Stopped target Remote File Systems.2040machine # [ 74.213858] systemd[1]: Stopped target Local File Systems.2041machine # [ 74.215051] systemd[1]: Stopped target test-target.target.2042machine # [ 74.217065] systemd[1]: Stopping test-service.service...2043machine # [ 74.219212] systemd[1]: test-service.service: Deactivated successfully.2044machine # [ 74.220802] systemd[1]: Stopped test-service.service.2045machine # [ 74.641609] systemd[1]: Reload requested from client PID 3977 ('.switch-to-conf') (unit backdoor.service)...2046machine # [ 74.643346] systemd[1]: Reloading...2047machine # [ 75.746192] systemd[1]: Reloading finished in 1100 ms.2048machine # [ 75.859155] systemd[636]: Reexecution requested from client PID 4047 ('switch-to-confi')...2049machine # [ 75.860399] systemd[636]: Reexecuting.2050machine # [ 75.885087] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2051machine # [ 75.996838] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2052machine # [ 76.003042] systemd[636]: Reload requested from client PID 4047 ('switch-to-confi')...2053machine # [ 76.004460] systemd[636]: Reloading...2054machine # [ 76.137240] systemd[636]: Reloading finished in 130 ms.2055machine # [ 76.141556] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2056machine # [ 76.149069] systemd[636]: Stopped Run user-specific NixOS activation.2057machine # [ 76.150092] systemd[636]: Stopping Run user-specific NixOS activation...2058machine # [ 76.152781] systemd[636]: Starting Run user-specific NixOS activation...2059machine # [ 76.184637] systemd[636]: Finished Run user-specific NixOS activation.2060machine # [ 76.187635] systemd[1]: Stopped target Reactivate sysinit units.2061machine # [ 76.188828] systemd[1]: Stopping Reactivate sysinit units...2062machine # [ 76.189887] systemd[1]: Reached target Reactivate sysinit units.2063machine # [ 76.194054] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2064machine # [ 76.195809] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2065machine # [ 76.200056] systemd[1]: Reached target Local File Systems.2066machine # [ 76.202226] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2067machine # [ 76.205352] systemd[1]: Starting Create SUID/SGID Wrappers...2068machine # [ 76.207111] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2069machine # [ 76.208238] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672070machine # [ 76.211107] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2071machine # [ 76.213033] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2072machine # [ 76.214700] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2073machine # [ 76.220840] systemd[1]: Reached target Remote File Systems.2074machine # [ 76.221610] systemd[1]: Reached target test-target.target.2075machine # [ 76.439842] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2076machine # [ 76.441075] systemd[1]: Finished Create SUID/SGID Wrappers.2077machine # [ 76.445037] systemd[1]: Starting linger-users.service...2078machine # [ 76.482868] systemd[1]: linger-users.service: Deactivated successfully.2079machine # [ 76.484401] systemd[1]: Finished linger-users.service.2080machine # [ 76.738832] nixos[3977]: finished switching to system configuration /nix/store/3fmfs1qmkg13vrl6z0f7326cpbw3ni7r-nixos-system-machine-test2081machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 3.01 seconds)2082machine: must fail: systemctl is-active test-service.servce2083machine: (finished: must fail: systemctl is-active test-service.servce, in 0.03 seconds)2084machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&12085machine # [ 77.195971] nixos[4121]: switching to system configuration /nix/store/ff7qarmvwlfcl5c0g77hrg6h3d5j77mm-nixos-system-machine-test2086machine # [ 77.198426] systemd[1]: Stopped target Local File Systems.2087machine # [ 77.199911] systemd[1]: Stopped target test-target.target.2088machine # [ 77.201463] systemd[1]: Stopped target Remote File Systems.2089machine # [ 77.549546] systemd[1]: Reload requested from client PID 4121 ('.switch-to-conf') (unit backdoor.service)...2090machine # [ 77.551228] systemd[1]: Reloading...2091machine # [ 78.086672] systemd[1]: Reloading finished in 533 ms.2092machine # [ 78.186799] systemd[636]: Reexecution requested from client PID 4190 ('switch-to-confi')...2093machine # [ 78.189467] systemd[636]: Reexecuting.2094machine # [ 78.215614] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2095machine # [ 78.351198] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2096machine # [ 78.356663] systemd[636]: Reload requested from client PID 4190 ('switch-to-confi')...2097machine # [ 78.357922] systemd[636]: Reloading...2098machine # [ 78.493029] systemd[636]: Reloading finished in 132 ms.2099machine # [ 78.497798] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2100machine # [ 78.504850] systemd[636]: Stopped Run user-specific NixOS activation.2101machine # [ 78.505747] systemd[636]: Stopping Run user-specific NixOS activation...2102machine # [ 78.508071] systemd[636]: Starting Run user-specific NixOS activation...2103machine # [ 78.541839] systemd[636]: Finished Run user-specific NixOS activation.2104machine # [ 78.545575] systemd[1]: Stopped target Reactivate sysinit units.2105machine # [ 78.546689] systemd[1]: Stopping Reactivate sysinit units...2106machine # [ 78.547781] systemd[1]: Reached target Reactivate sysinit units.2107machine # [ 78.551762] systemd[1]: Reached target Local File Systems.2108machine # [ 78.553491] systemd[1]: Started test-timer.timer.2109machine # [ 78.555534] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2110machine # [ 78.557360] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2111machine # [ 78.560301] systemd[1]: Reached target Remote File Systems.2112machine # [ 78.565630] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2113machine # [ 78.570305] systemd[1]: Starting Create SUID/SGID Wrappers...2114machine # [ 78.571219] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2115machine # [ 78.572378] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672116machine # [ 78.575500] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2117machine # [ 78.577132] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2118machine # [ 78.578433] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2119machine # [ 78.852626] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2120machine # [ 78.854408] systemd[1]: Finished Create SUID/SGID Wrappers.2121machine # [ 78.858778] systemd[1]: Starting linger-users.service...2122machine # [ 78.902929] systemd[1]: linger-users.service: Deactivated successfully.2123machine # [ 78.904561] systemd[1]: Finished linger-users.service.2124machine # [ 79.161137] nixos[4121]: finished switching to system configuration /nix/store/ff7qarmvwlfcl5c0g77hrg6h3d5j77mm-nixos-system-machine-test2125machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 2.39 seconds)2126(finished: subtest: targets, in 13.16 seconds)2127subtest: paths2128machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&12129machine # [ 79.724649] nixos[4261]: switching to system configuration /nix/store/i6qypvbq8gbwb1mzpcv89vw1lipx3lv7-nixos-system-machine-test2130machine # [ 79.727035] systemd[1]: test-timer.timer: Deactivated successfully.2131machine # [ 79.729770] systemd[1]: Stopped test-timer.timer.2132machine # [ 79.730671] systemd[1]: Stopped target Local File Systems.2133machine # [ 79.731501] systemd[1]: Stopped target Remote File Systems.2134machine # [ 80.147651] systemd[1]: Reload requested from client PID 4261 ('.switch-to-conf') (unit backdoor.service)...2135machine # [ 80.149403] systemd[1]: Reloading...2136machine # [ 82.198690] systemd[1]: Reloading finished in 2047 ms.2137machine # [ 82.313564] systemd[636]: Reexecution requested from client PID 4330 ('switch-to-confi')...2138machine # [ 82.314837] systemd[636]: Reexecuting.2139machine # [ 82.337785] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2140machine # [ 82.452109] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2141machine # [ 82.457849] systemd[636]: Reload requested from client PID 4330 ('switch-to-confi')...2142machine # [ 82.459600] systemd[636]: Reloading...2143machine # [ 82.574057] systemd[636]: Reloading finished in 113 ms.2144machine # [ 82.578800] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2145machine # [ 82.585240] systemd[636]: Stopped Run user-specific NixOS activation.2146machine # [ 82.587059] systemd[636]: Stopping Run user-specific NixOS activation...2147machine # [ 82.589036] systemd[636]: Starting Run user-specific NixOS activation...2148machine # [ 82.618280] systemd[636]: Finished Run user-specific NixOS activation.2149machine # [ 82.621508] systemd[1]: Stopped target Reactivate sysinit units.2150machine # [ 82.622768] systemd[1]: Stopping Reactivate sysinit units...2151machine # [ 82.623744] systemd[1]: Reached target Reactivate sysinit units.2152machine # [ 82.625552] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2153machine # [ 82.627408] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2154machine # [ 82.629975] systemd[1]: Reached target Local File Systems.2155machine # [ 82.632141] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2156machine # [ 82.636237] systemd[1]: Starting Create SUID/SGID Wrappers...2157machine # [ 82.637171] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2158machine # [ 82.639221] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672159machine # [ 82.641405] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2160machine # [ 82.642847] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2161machine # [ 82.643977] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2162machine # [ 82.651104] systemd[1]: Reached target Remote File Systems.2163machine # [ 82.881812] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2164machine # [ 82.883077] systemd[1]: Finished Create SUID/SGID Wrappers.2165machine # [ 82.884959] systemd[1]: Started test-watch.path.2166machine # [ 82.888417] systemd[1]: Starting linger-users.service...2167machine # [ 82.932947] systemd[1]: linger-users.service: Deactivated successfully.2168machine # [ 82.934447] systemd[1]: Finished linger-users.service.2169machine # [ 83.191901] nixos[4261]: finished switching to system configuration /nix/store/i6qypvbq8gbwb1mzpcv89vw1lipx3lv7-nixos-system-machine-test2170machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 4.03 seconds)2171machine: must fail: test -f /testpath-modified2172machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2173machine: must succeed: touch /testpath2174machine: (finished: must succeed: touch /testpath, in 0.04 seconds)2175machine: waiting for success: test -f /testpath-modified2176machine # [ 83.266620] systemd[1]: Starting test-watch.service...2177machine # [ 83.297415] systemd[1]: Finished test-watch.service.2178machine: (finished: waiting for success: test -f /testpath-modified, in 1.07 seconds)2179machine: must succeed: rm /testpath /testpath-modified2180machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.03 seconds)2181machine # [ 84.392879] systemd[1]: test-watch.service: Deactivated successfully.2182machine # Stopping 'test-watch.service', but its triggering units are still active:2183machine # test-watch.path2184machine # [ 84.396139] systemd[1]: Stopped test-watch.service.2185machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12186machine # [ 84.924756] nixos[4426]: switching to system configuration /nix/store/2a682s8a8v4hcj77dyypzrl7mgnd66ws-nixos-system-machine-test2187machine # [ 84.926897] systemd[1]: Stopped target Local File Systems.2188machine # [ 84.928523] systemd[1]: Stopped target Remote File Systems.2189machine # [ 85.364287] systemd[1]: Reload requested from client PID 4426 ('.switch-to-conf') (unit backdoor.service)...2190machine # [ 85.366356] systemd[1]: Reloading...2191machine # [ 85.863937] systemd[1]: Reloading finished in 494 ms.2192machine # [ 85.963384] systemd[636]: Reexecution requested from client PID 4496 ('switch-to-confi')...2193machine # [ 85.964838] systemd[636]: Reexecuting.2194machine # [ 85.991661] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2195machine # [ 86.144966] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2196machine # [ 86.150458] systemd[636]: Reload requested from client PID 4496 ('switch-to-confi')...2197machine # [ 86.152483] systemd[636]: Reloading...2198machine # [ 86.295028] systemd[636]: Reloading finished in 141 ms.2199machine # [ 86.301091] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2200machine # [ 86.307452] systemd[636]: Stopped Run user-specific NixOS activation.2201machine # [ 86.308640] systemd[636]: Stopping Run user-specific NixOS activation...2202machine # [ 86.311747] systemd[636]: Starting Run user-specific NixOS activation...2203machine # [ 86.342620] systemd[636]: Finished Run user-specific NixOS activation.2204machine # [ 86.346181] systemd[1]: Stopped target Reactivate sysinit units.2205machine # [ 86.347542] systemd[1]: Stopping Reactivate sysinit units...2206machine # [ 86.348506] systemd[1]: Reached target Reactivate sysinit units.2207machine # [ 86.352596] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2208machine # [ 86.354597] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2209machine # [ 86.357565] systemd[1]: Reached target Local File Systems.2210machine # [ 86.360262] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2211machine # [ 86.363260] systemd[1]: Starting Create SUID/SGID Wrappers...2212machine # [ 86.365500] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2213machine # [ 86.366764] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672214machine # [ 86.369279] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2215machine # [ 86.371946] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2216machine # [ 86.374120] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2217machine # [ 86.382439] systemd[1]: Reached target Remote File Systems.2218machine # [ 86.615673] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2219machine # [ 86.617243] systemd[1]: Finished Create SUID/SGID Wrappers.2220machine # [ 86.621789] systemd[1]: Starting linger-users.service...2221machine # [ 86.662719] systemd[1]: linger-users.service: Deactivated successfully.2222machine # [ 86.663828] systemd[1]: Finished linger-users.service.2223machine # [ 86.921351] nixos[4426]: finished switching to system configuration /nix/store/2a682s8a8v4hcj77dyypzrl7mgnd66ws-nixos-system-machine-test2224machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 2.53 seconds)2225machine: must succeed: touch /testpath2226machine: (finished: must succeed: touch /testpath, in 0.04 seconds)2227machine: must fail: test -f /testpath-modified2228machine: (finished: must fail: test -f /testpath-modified, in 0.02 seconds)2229machine: must succeed: touch /testpath22230machine: (finished: must succeed: touch /testpath2, in 0.02 seconds)2231machine: waiting for success: test -f /testpath-modified2232machine # [ 87.012209] systemd[1]: Starting test-watch.service...2233machine # [ 87.041067] systemd[1]: Finished test-watch.service.2234machine: (finished: waiting for success: test -f /testpath-modified, in 1.06 seconds)2235(finished: subtest: paths, in 8.90 seconds)2236subtest: slices2237machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2238machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.02 seconds)2239machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12240machine # [ 88.651599] nixos[4591]: switching to system configuration /nix/store/ag40cjyxv9f10ayy0k1agslxngjpjz6d-nixos-system-machine-test2241machine # [ 88.654539] systemd[1]: Stopped target Local File Systems.2242machine # [ 88.655742] systemd[1]: test-watch.path: Deactivated successfully.2243machine # [ 88.656571] systemd[1]: Stopped test-watch.path.2244machine # [ 88.658072] systemd[1]: test-watch.service: Deactivated successfully.2245machine # [ 88.660310] systemd[1]: Stopped test-watch.service.2246machine # [ 88.661143] systemd[1]: Stopped target Remote File Systems.2247machine # [ 89.110878] systemd[1]: Reload requested from client PID 4591 ('.switch-to-conf') (unit backdoor.service)...2248machine # [ 89.112584] systemd[1]: Reloading...2249machine # [ 89.594085] systemd[1]: Reloading finished in 478 ms.2250machine # [ 89.692096] systemd[636]: Reexecution requested from client PID 4660 ('switch-to-confi')...2251machine # [ 89.693352] systemd[636]: Reexecuting.2252machine # [ 89.718031] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2253machine # [ 89.846693] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2254machine # [ 89.851748] systemd[636]: Reload requested from client PID 4660 ('switch-to-confi')...2255machine # [ 89.853419] systemd[636]: Reloading...2256machine # [ 89.991754] systemd[636]: Reloading finished in 136 ms.2257machine # [ 89.996839] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2258machine # [ 90.003507] systemd[636]: Stopped Run user-specific NixOS activation.2259machine # [ 90.005056] systemd[636]: Stopping Run user-specific NixOS activation...2260machine # [ 90.007620] systemd[636]: Starting Run user-specific NixOS activation...2261machine # [ 90.040265] systemd[636]: Finished Run user-specific NixOS activation.2262machine # [ 90.043600] systemd[1]: Stopped target Reactivate sysinit units.2263machine # [ 90.044979] systemd[1]: Stopping Reactivate sysinit units...2264machine # [ 90.046111] systemd[1]: Reached target Reactivate sysinit units.2265machine # [ 90.047840] systemd[1]: Reached target Remote File Systems.2266machine # [ 90.051055] systemd[1]: Reached target Local File Systems.2267machine # [ 90.053191] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2268machine # [ 90.056850] systemd[1]: Starting Create SUID/SGID Wrappers...2269machine # [ 90.057757] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2270machine # [ 90.058925] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672271machine # [ 90.061696] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2272machine # [ 90.063933] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2273machine # [ 90.065495] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2274machine # [ 90.072926] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2275machine # [ 90.074610] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2276machine # [ 90.307946] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2277machine # [ 90.309692] systemd[1]: Finished Create SUID/SGID Wrappers.2278machine # [ 90.313947] systemd[1]: Starting linger-users.service...2279machine # [ 90.354883] systemd[1]: linger-users.service: Deactivated successfully.2280machine # [ 90.356479] systemd[1]: Finished linger-users.service.2281machine # [ 90.614611] nixos[4591]: finished switching to system configuration /nix/store/ag40cjyxv9f10ayy0k1agslxngjpjz6d-nixos-system-machine-test2282machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 2.53 seconds)2283machine: must fail: systemctl start testservice.service2284machine # [ 90.672343] systemd[1]: Created slice Slice /testslice.2285machine # [ 90.816994] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02286machine # [ 90.819377] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.48 #1-NixOS PREEMPT(lazy)2287machine # [ 90.819387] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/20142288machine # [ 90.819390] Call Trace:2289machine # [ 90.819399] <TASK>2290machine # [ 90.819404] dump_stack_lvl+0x5d/0x802291machine # [ 90.819442] dump_header+0x43/0x1aa2292machine # [ 90.819467] out_of_memory.cold+0x8/0x782293machine # [ 90.819479] mem_cgroup_out_of_memory+0xc2/0xd02294machine # [ 90.819509] try_charge_memcg+0x442/0x6602295machine # [ 90.819526] obj_cgroup_charge_account+0xf8/0x4202296machine # [ 90.819532] __memcg_slab_post_alloc_hook+0x105/0x3702297machine # [ 90.819538] ? mem_cgroup_css_alloc+0x28f/0x8e02298machine # [ 90.819570] __kmalloc_cache_noprof+0x4ba/0x5f02299machine # [ 90.819581] ? srso_alias_return_thunk+0x5/0xfbef52300machine # [ 90.819586] ? __xa_alloc+0xa3/0x1402301machine # [ 90.819604] ? mem_cgroup_css_alloc+0x28f/0x8e02302machine # [ 90.819608] ? srso_alias_return_thunk+0x5/0xfbef52303machine # [ 90.819611] mem_cgroup_css_alloc+0x28f/0x8e02304machine # [ 90.819619] cgroup_apply_control_enable+0x132/0x3302305machine # [ 90.819636] cgroup_mkdir+0x1ef/0x5602306machine # [ 90.819647] kernfs_iop_mkdir+0x76/0xe02307machine # [ 90.819669] vfs_mkdir+0x19a/0x2702308machine # [ 90.819684] do_mkdirat+0x14d/0x1902309machine # [ 90.819691] __x64_sys_mkdir+0x49/0x702310machine # [ 90.819695] do_syscall_64+0xd6/0x7c02311machine # [ 90.819708] ? srso_alias_return_thunk+0x5/0xfbef52312machine # [ 90.819711] ? do_syscall_64+0x110/0x7c02313machine # [ 90.819715] ? srso_alias_return_thunk+0x5/0xfbef52314machine # [ 90.819718] ? srso_alias_return_thunk+0x5/0xfbef52315machine # [ 90.819723] entry_SYSCALL_64_after_hwframe+0x77/0x7f2316machine # [ 90.819727] RIP: 0033:0x7fa3202aa02b2317machine # [ 90.819748] 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 482318machine # [ 90.819751] RSP: 002b:00007fff7e6a36d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000532319machine # [ 90.819759] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fa3202aa02b2320machine # [ 90.819761] RDX: 0000000000000000 RSI: 00000000000001ed RDI: 0000557503e04d202321machine # [ 90.819763] RBP: 00007fff7e6a3700 R08: 0000000000000000 R09: 00000000000000002322machine # [ 90.819765] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000000002323machine # [ 90.819766] R13: 00000000000001a8 R14: 0000000000000000 R15: 0000000000003fc72324machine # [ 90.819777] </TASK>2325machine # [ 90.847768] memory: usage 0kB, limit 0kB, failcnt 182326machine # [ 90.848247] swap: usage 0kB, limit 9007199254740988kB, failcnt 02327machine # [ 90.848929] Memory cgroup stats for /testslice.slice:2328machine # [ 90.848954] anon 02329machine # [ 90.850481] file 02330machine # [ 90.850911] kernel 02331machine # [ 90.851327] kernel_stack 02332machine # [ 90.852160] pagetables 02333machine # [ 90.852807] sec_pagetables 02334machine # [ 90.853295] percpu 02335machine # [ 90.853930] sock 02336machine # [ 90.854306] vmalloc 02337machine # [ 90.854920] shmem 02338machine # [ 90.855306] zswap 02339machine # [ 90.855943] zswapped 02340machine # [ 90.856351] file_mapped 02341machine # [ 90.857007] file_dirty 02342machine # [ 90.857395] file_writeback 02343machine # [ 90.858096] swapcached 02344machine # [ 90.858683] anon_thp 02345machine # [ 90.859073] file_thp 02346machine # [ 90.859680] shmem_thp 02347machine # [ 90.860064] inactive_anon 02348machine # [ 90.860699] active_anon 02349machine # [ 90.861103] inactive_file 02350machine # [ 90.861774] active_file 02351machine # [ 90.862215] unevictable 02352machine # [ 90.862877] slab_reclaimable 02353machine # [ 90.863322] slab_unreclaimable 02354machine # [ 90.864024] slab 02355machine # [ 90.864378] hugetlb 02356machine # [ 90.865001] workingset_refault_anon 02357machine # [ 90.865758] workingset_refault_file 02358machine # [ 90.866265] workingset_activate_anon 02359machine # [ 90.867021] workingset_activate_file 02360machine # [ 90.867712] workingset_restore_anon 02361machine # [ 90.868223] workingset_restore_file 02362machine # [ 90.868940] workingset_nodereclaim 02363machine # [ 90.869669] pgdemote_kswapd 02364machine # [ 90.870089] pgdemote_direct 02365machine # [ 90.870776] pgdemote_khugepaged 02366machine # [ 90.871243] pgdemote_proactive 02367machine # [ 90.871904] pgpromote_success 02368machine # [ 90.872337] pgscan 02369machine # [ 90.872907] pgsteal 02370machine # [ 90.873271] pswpin 02371machine # [ 90.873876] pswpout 02372machine # [ 90.874248] pgscan_kswapd 02373machine # [ 90.874884] pgscan_direct 02374machine # [ 90.875269] pgscan_khugepaged 02375machine # [ 90.875936] pgscan_proactive 02376machine # [ 90.876352] pgsteal_kswapd 02377machine # [ 90.876975] pgsteal_direct 02378machine # [ 90.877412] pgsteal_khugepaged 02379machine # [ 90.878067] pgsteal_proactive 02380machine # [ 90.878681] pgfault 02381machine # [ 90.879002] pgmajfault 02382machine # [ 90.879358] pgrefill 02383machine # [ 90.879972] pgactivate 02384machine # [ 90.880348] pgdeactivate 02385machine # [ 90.880946] pglazyfree 02386machine # [ 90.881322] pglazyfreed 02387machine # [ 90.881929] swpin_zero 02388machine # [ 90.882292] swpout_zero 02389machine # [ 90.882915] zswpin 02390machine # [ 90.883247] zswpout 02391machine # [ 90.883812] zswpwb 02392machine # [ 90.884146] thp_fault_alloc 02393machine # [ 90.884754] thp_collapse_alloc 02394machine # [ 90.885207] thp_swpout 02395machine # [ 90.885813] thp_swpout_fallback 02396machine # [ 90.886258] numa_pages_migrated 02397machine # [ 90.886914] numa_pte_updates 02398machine # [ 90.887318] numa_hint_faults 02399machine # [ 90.887989] Tasks state (memory values in pages):2400machine # [ 90.888826] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2401machine # [ 90.890125] Out of memory and no killable processes...2402machine # [ 90.748622] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2403machine # [ 90.750200] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2404machine # [ 90.751530] systemd[1]: testservice.service: Failed with result 'resources'.2405machine # [ 90.753292] systemd[1]: Failed to start testservice.service.2406machine # Job for testservice.service failed because of unavailable resources or another system error.2407machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2408machine: (finished: must fail: systemctl start testservice.service, in 0.14 seconds)2409machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12410machine # [ 91.236131] nixos[4735]: switching to system configuration /nix/store/bj22fa8bvd92rzkgw5k8lbddgg7ln2q0-nixos-system-machine-test2411machine # [ 91.238528] systemd[1]: Stopped target Remote File Systems.2412machine # [ 91.240256] systemd[1]: Stopped target Local File Systems.2413machine # [ 91.647264] systemd[1]: Reload requested from client PID 4735 ('.switch-to-conf') (unit backdoor.service)...2414machine # [ 91.648916] systemd[1]: Reloading...2415machine # [ 93.304854] systemd[1]: Reloading finished in 1654 ms.2416machine # [ 93.411938] systemd[636]: Reexecution requested from client PID 4804 ('switch-to-confi')...2417machine # [ 93.413194] systemd[636]: Reexecuting.2418machine # [ 93.439147] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2419machine # [ 93.569415] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2420machine # [ 93.574069] systemd[636]: Reload requested from client PID 4804 ('switch-to-confi')...2421machine # [ 93.575495] systemd[636]: Reloading...2422machine # [ 93.703924] systemd[636]: Reloading finished in 126 ms.2423machine # [ 93.708801] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2424machine # [ 93.715060] systemd[636]: Stopped Run user-specific NixOS activation.2425machine # [ 93.715962] systemd[636]: Stopping Run user-specific NixOS activation...2426machine # [ 93.719182] systemd[636]: Starting Run user-specific NixOS activation...2427machine # [ 93.753223] systemd[636]: Finished Run user-specific NixOS activation.2428machine # [ 93.756376] systemd[1]: Stopped target Reactivate sysinit units.2429machine # [ 93.757696] systemd[1]: Stopping Reactivate sysinit units...2430machine # [ 93.758649] systemd[1]: Reached target Reactivate sysinit units.2431machine # [ 93.760065] systemd[1]: Reached target Local File Systems.2432machine # [ 93.761630] systemd[1]: Reached target Remote File Systems.2433machine # [ 93.770327] systemd[1]: Starting linger-users.service...2434machine # [ 93.778074] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2435machine # [ 93.781386] systemd[1]: Starting Create SUID/SGID Wrappers...2436machine # [ 93.782330] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2437machine # [ 93.783412] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672438machine # [ 93.786607] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2439machine # [ 93.788951] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2440machine # [ 93.793322] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2441machine # [ 93.794466] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2442machine # [ 93.795661] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2443machine # [ 93.842673] systemd[1]: linger-users.service: Deactivated successfully.2444machine # [ 93.844494] systemd[1]: Finished linger-users.service.2445machine # [ 94.082583] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2446machine # [ 94.084230] systemd[1]: Finished Create SUID/SGID Wrappers.2447machine # [ 94.341692] nixos[4735]: finished switching to system configuration /nix/store/bj22fa8bvd92rzkgw5k8lbddgg7ln2q0-nixos-system-machine-test2448machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 3.58 seconds)2449machine: must succeed: systemctl start testservice.service2450machine # [ 94.392154] systemd[1]: Starting testservice.service...2451machine # [ 94.417837] systemd[1]: Finished testservice.service.2452machine: (finished: must succeed: systemctl start testservice.service, in 0.08 seconds)2453machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2454machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.01 seconds)2455(finished: subtest: slices, in 6.37 seconds)2456subtest: dbus reloads2457machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&12458machine # [ 94.917338] nixos[4884]: switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test2459machine # [ 94.919380] systemd[1]: testservice.service: Deactivated successfully.2460machine # [ 94.921465] systemd[1]: Stopped testservice.service.2461machine # [ 94.922988] systemd[1]: Stopped target Local File Systems.2462machine # [ 94.924978] systemd[1]: Removed slice Slice /testslice.2463machine # [ 94.926938] systemd[1]: Stopped target Remote File Systems.2464machine # [ 95.274724] systemd[1]: Reload requested from client PID 4884 ('.switch-to-conf') (unit backdoor.service)...2465machine # [ 95.276686] systemd[1]: Reloading...2466machine # [ 95.698438] systemd[1]: Reloading finished in 419 ms.2467machine # [ 95.803054] systemd[636]: Reexecution requested from client PID 4953 ('switch-to-confi')...2468machine # [ 95.805728] systemd[636]: Reexecuting.2469machine # [ 95.828089] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2470machine # [ 95.942220] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2471machine # [ 95.947648] systemd[636]: Reload requested from client PID 4953 ('switch-to-confi')...2472machine # [ 95.949626] systemd[636]: Reloading...2473machine # [ 96.082946] systemd[636]: Reloading finished in 132 ms.2474machine # [ 96.089028] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2475machine # [ 96.095309] systemd[636]: Stopped Run user-specific NixOS activation.2476machine # [ 96.097055] systemd[636]: Stopping Run user-specific NixOS activation...2477machine # [ 96.099256] systemd[636]: Starting Run user-specific NixOS activation...2478machine # [ 96.132226] systemd[636]: Finished Run user-specific NixOS activation.2479machine # [ 96.135311] systemd[1]: Stopped target Reactivate sysinit units.2480machine # [ 96.136715] systemd[1]: Stopping Reactivate sysinit units...2481machine # [ 96.137670] systemd[1]: Reached target Reactivate sysinit units.2482machine # [ 96.142633] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2483machine # [ 96.144105] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2484machine # [ 96.146639] systemd[1]: Reached target Remote File Systems.2485machine # [ 96.150095] systemd[1]: Starting linger-users.service...2486machine # [ 96.155174] systemd[1]: Reached target Local File Systems.2487machine # [ 96.160555] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2488machine # [ 96.165794] systemd[1]: Starting Create SUID/SGID Wrappers...2489machine # [ 96.166608] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2490machine # [ 96.167741] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672491machine # [ 96.169712] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2492machine # [ 96.172291] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2493machine # [ 96.173741] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2494machine # [ 96.216874] systemd[1]: linger-users.service: Deactivated successfully.2495machine # [ 96.218315] systemd[1]: Finished linger-users.service.2496machine # [ 96.460533] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2497machine # [ 96.463273] systemd[1]: Finished Create SUID/SGID Wrappers.2498machine # [ 96.719463] nixos[4884]: finished switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test2499machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.29 seconds)2500machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12501machine # [ 97.274688] nixos[5024]: switching to system configuration /nix/store/grjh99q4h9c9jc4nhq6pln5j0qfaf9mi-nixos-system-machine-test2502machine # [ 97.277121] systemd[1]: Stopped target Local File Systems.2503machine # [ 97.278799] systemd[1]: Stopped target Remote File Systems.2504machine # [ 97.694467] systemd[1]: Reload requested from client PID 5024 ('.switch-to-conf') (unit backdoor.service)...2505machine # [ 97.696139] systemd[1]: Reloading...2506machine # [ 98.205859] systemd[1]: Reloading finished in 507 ms.2507machine # [ 98.312650] systemd[636]: Reexecution requested from client PID 5093 ('switch-to-confi')...2508machine # [ 98.315355] systemd[636]: Reexecuting.2509machine # [ 98.342251] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2510machine # [ 98.467833] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2511machine # [ 98.473437] systemd[636]: Reload requested from client PID 5093 ('switch-to-confi')...2512machine # [ 98.474644] systemd[636]: Reloading...2513machine # [ 98.595742] systemd[636]: Reloading finished in 119 ms.2514machine # [ 98.599797] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2515machine # [ 98.607068] systemd[636]: Stopped Run user-specific NixOS activation.2516machine # [ 98.607933] systemd[636]: Stopping Run user-specific NixOS activation...2517machine # [ 98.610259] systemd[636]: Starting Run user-specific NixOS activation...2518machine # [ 98.642093] systemd[636]: Finished Run user-specific NixOS activation.2519machine # [ 98.645301] systemd[1]: Stopped target Reactivate sysinit units.2520machine # [ 98.646977] systemd[1]: Stopping Reactivate sysinit units...2521machine # [ 98.649475] systemd[1]: Reached target Reactivate sysinit units.2522machine # [ 98.650661] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.2523machine # [ 98.651642] systemd[1]: Reloading D-Bus System Message Bus...2524machine # [ 98.661756] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...2525machine # [ 98.663613] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'2526machine # [ 98.664662] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2527machine # [ 98.678476] systemd[1]: Reloaded D-Bus System Message Bus.2528machine # [ 98.683057] systemd[1]: Reached target Local File Systems.2529machine # [ 98.685231] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2530machine # [ 98.689087] systemd[1]: Starting Create SUID/SGID Wrappers...2531machine # [ 98.689883] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2532machine # [ 98.692022] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672533machine # [ 98.693935] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2534machine # [ 98.695328] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2535machine # [ 98.696471] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2536machine # [ 98.704064] systemd[1]: Reached target Remote File Systems.2537machine # [ 98.707504] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2538machine # [ 98.709351] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2539machine # [ 98.971625] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2540machine # [ 98.973286] systemd[1]: Finished Create SUID/SGID Wrappers.2541machine # [ 98.977898] systemd[1]: Starting linger-users.service...2542machine # [ 99.021866] systemd[1]: linger-users.service: Deactivated successfully.2543machine # [ 99.023412] systemd[1]: Finished linger-users.service.2544machine # [ 99.278077] nixos[5024]: finished switching to system configuration /nix/store/grjh99q4h9c9jc4nhq6pln5j0qfaf9mi-nixos-system-machine-test2545machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 2.55 seconds)2546(finished: subtest: dbus reloads, in 4.84 seconds)2547subtest: generators2548machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12549machine # [ 99.736145] nixos[5164]: switching to system configuration /nix/store/z0mg22mhf49xrz2zdcq01is4r3dd3kv6-nixos-system-machine-test2550machine # [ 99.738550] systemd[1]: Stopped target Local File Systems.2551machine # [ 99.740397] systemd[1]: Stopped target Remote File Systems.2552machine # [ 100.153835] systemd[1]: Reload requested from client PID 5164 ('.switch-to-conf') (unit backdoor.service)...2553machine # [ 100.155489] systemd[1]: Reloading...2554machine # [ 100.631805] systemd[1]: Reloading finished in 474 ms.2555machine # [ 100.740131] systemd[636]: Reexecution requested from client PID 5235 ('switch-to-confi')...2556machine # [ 100.742588] systemd[636]: Reexecuting.2557machine # [ 100.765466] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2558machine # [ 100.892102] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2559machine # [ 100.898661] systemd[636]: Reload requested from client PID 5235 ('switch-to-confi')...2560machine # [ 100.899857] systemd[636]: Reloading...2561machine # [ 101.025063] systemd[636]: Reloading finished in 123 ms.2562machine # [ 101.030805] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2563machine # [ 101.037060] systemd[636]: Stopped Run user-specific NixOS activation.2564machine # [ 101.038045] systemd[636]: Stopping Run user-specific NixOS activation...2565machine # [ 101.041116] systemd[636]: Starting Run user-specific NixOS activation...2566machine # [ 101.076142] systemd[636]: Finished Run user-specific NixOS activation.2567machine # [ 101.079461] systemd[1]: Stopped target Reactivate sysinit units.2568machine # [ 101.080969] systemd[1]: Stopping Reactivate sysinit units...2569machine # [ 101.082274] systemd[1]: Reached target Reactivate sysinit units.2570machine # [ 101.085195] dbus-broker-launch[513]: Caught SIGHUP, trigger reload.2571machine # [ 101.086251] systemd[1]: Reloading D-Bus System Message Bus...2572machine # [ 101.097445] dbus-broker-launch[513]: Looking up NSS user entry for 'systemd-timesync'...2573machine # [ 101.099282] dbus-broker-launch[513]: NSS returned no entry for 'systemd-timesync'2574machine # [ 101.100301] dbus-broker-launch[513]: Invalid user-name in /nix/store/cbyfllapzm5ln1bq2va5ghalqcswmygw-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2575machine # [ 101.118081] systemd[1]: Reloaded D-Bus System Message Bus.2576machine # [ 101.123872] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2577machine # [ 101.125582] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2578machine # [ 101.128211] systemd[1]: Reached target Local File Systems.2579machine # [ 101.130466] systemd[1]: Reached target Remote File Systems.2580machine # [ 101.133900] systemd[1]: Starting linger-users.service...2581machine # [ 101.136780] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2582machine # [ 101.145092] systemd[1]: Starting Create SUID/SGID Wrappers...2583machine # [ 101.145873] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2584machine # [ 101.146991] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672585machine # [ 101.148929] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2586machine # [ 101.150354] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2587machine # [ 101.151526] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2588machine # [ 101.197627] systemd[1]: linger-users.service: Deactivated successfully.2589machine # [ 101.199595] systemd[1]: Finished linger-users.service.2590machine # [ 101.426857] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2591machine # [ 101.427976] systemd[1]: Finished Create SUID/SGID Wrappers.2592machine # [ 101.685454] nixos[5164]: finished switching to system configuration /nix/store/z0mg22mhf49xrz2zdcq01is4r3dd3kv6-nixos-system-machine-test2593machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 2.41 seconds)2594machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2595machine # [ 101.735899] systemd[1]: Started simple-generated.service.2596machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.06 seconds)2597machine: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&12598machine # [ 102.216210] nixos[5313]: switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test2599machine # [ 102.219553] systemd[1]: Stopped target Remote File Systems.2600machine # [ 102.220498] systemd[1]: Stopped target Local File Systems.2601machine # [ 102.568902] systemd[1]: Reload requested from client PID 5313 ('.switch-to-conf') (unit backdoor.service)...2602machine # [ 102.570571] systemd[1]: Reloading...2603machine # [ 103.021060] systemd[1]: Reloading finished in 448 ms.2604machine # [ 103.123773] systemd[636]: Reexecution requested from client PID 5383 ('switch-to-confi')...2605machine # [ 103.125726] systemd[636]: Reexecuting.2606machine # [ 103.151683] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2607machine # [ 103.285729] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2608machine # [ 103.291719] systemd[636]: Reload requested from client PID 5383 ('switch-to-confi')...2609machine # [ 103.292888] systemd[636]: Reloading...2610machine # [ 103.428621] systemd[636]: Reloading finished in 133 ms.2611machine # [ 103.432793] systemd[636]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2612machine # [ 103.439113] systemd[636]: Stopped Run user-specific NixOS activation.2613machine # [ 103.440121] systemd[636]: Stopping Run user-specific NixOS activation...2614machine # [ 103.443346] systemd[636]: Starting Run user-specific NixOS activation...2615machine # [ 103.478973] systemd[636]: Finished Run user-specific NixOS activation.2616machine # [ 103.482240] systemd[1]: Stopped target Reactivate sysinit units.2617machine # [ 103.483719] systemd[1]: Stopping Reactivate sysinit units...2618machine # [ 103.485359] systemd[1]: Reached target Reactivate sysinit units.2619machine # [ 103.489471] systemd[1]: Reached target Local File Systems.2620machine # [ 103.491885] systemd[1]: Reached target Remote File Systems.2621machine # [ 103.495547] systemd[1]: Starting linger-users.service...2622machine # [ 103.502826] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2623machine # [ 103.505133] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2624machine # [ 103.508637] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2625machine # [ 103.512806] systemd[1]: Starting Create SUID/SGID Wrappers...2626machine # [ 103.514350] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2627machine # [ 103.516441] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672628machine # [ 103.520095] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2629machine # [ 103.521667] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2630machine # [ 103.522867] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2631machine # [ 103.566700] systemd[1]: linger-users.service: Deactivated successfully.2632machine # [ 103.567762] systemd[1]: Finished linger-users.service.2633machine # [ 103.815260] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2634machine # [ 103.817975] systemd[1]: Finished Create SUID/SGID Wrappers.2635machine # [ 104.071465] nixos[5313]: finished switching to system configuration /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test2636machine: (finished: must succeed: /nix/store/58vlgms7zw6jb907j9k9a2dxpagp7zv7-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.32 seconds)2637machine: must succeed: systemctl is-active simple-generated.service2638machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.02 seconds)2639(finished: subtest: generators, in 4.82 seconds)2640(finished: run the VM test script, in 104.93 seconds)2641test script finished in 104.96s2642cleanup2643kill QemuMachine (pid 45)2644machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2645(finished: cleanup, in 0.14 seconds)2646additionally exposed symbols:2647 machine, other,2648 vlan1,2649 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