vm-test-run-switch-test-units
checks.x86_64-linux.switchTest-units
· build #31
· 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 # Disk image does not exist, creating the virtualisation disk image...11machine: QEMU running (pid 45)12machine # Formatting '/build/vm-state-machine/tmp.5Cnz4JvsAu', 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: 7e9f6291-572d-414b-a6d4-b7e76573747517machine # 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.50 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT_DYNAMIC Mon Sep 7 15:23:00 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/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/init regInfo=/nix/store/7g43lpqf716ywf0d34314z5b0vdjpqi5-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.000001] kvm-clock: using sched offset of 564455043 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.000813] last_pfn = 0x3ffd8 max_arch_pfn = 0x1000000000065machine # [ 0.000850] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs66machine # [ 0.000853] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT67machine # [ 0.002763] found SMP MP-table at [mem 0x000f5450-0x000f545f]68machine # [ 0.002774] Using GB pages for direct mapping69machine # [ 0.002871] RAMDISK: [mem 0x3e427000-0x3ffcffff]70machine # [ 0.002879] ACPI: Early table checksum verification disabled71machine # [ 0.002882] ACPI: RSDP 0x00000000000F5270 000014 (v00 BOCHS )72machine # [ 0.002887] ACPI: RSDT 0x000000003FFE2422 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)73machine # [ 0.002890] ACPI: FACP 0x000000003FFE221A 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001)74machine # [ 0.002898] ACPI: DSDT 0x000000003FFE0040 0021DA (v01 BOCHS BXPC 00000001 BXPC 00000001)75machine # [ 0.002900] ACPI: FACS 0x000000003FFE0000 00004076machine # [ 0.002902] ACPI: APIC 0x000000003FFE230E 000078 (v03 BOCHS BXPC 00000001 BXPC 00000001)77machine # [ 0.002903] ACPI: HPET 0x000000003FFE2386 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001)78machine # [ 0.002905] ACPI: MCFG 0x000000003FFE23BE 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001)79machine # [ 0.002906] ACPI: WAET 0x000000003FFE23FA 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001)80machine # [ 0.002908] ACPI: Reserving FACP table memory at [mem 0x3ffe221a-0x3ffe230d]81machine # [ 0.002909] ACPI: Reserving DSDT table memory at [mem 0x3ffe0040-0x3ffe2219]82machine # [ 0.002909] ACPI: Reserving FACS table memory at [mem 0x3ffe0000-0x3ffe003f]83machine # [ 0.002910] ACPI: Reserving APIC table memory at [mem 0x3ffe230e-0x3ffe2385]84machine # [ 0.002910] ACPI: Reserving HPET table memory at [mem 0x3ffe2386-0x3ffe23bd]85machine # [ 0.002911] ACPI: Reserving MCFG table memory at [mem 0x3ffe23be-0x3ffe23f9]86machine # [ 0.002911] ACPI: Reserving WAET table memory at [mem 0x3ffe23fa-0x3ffe2421]87machine # [ 0.003401] No NUMA configuration found88machine # [ 0.003402] Faking a node at [mem 0x0000000000000000-0x000000003ffd7fff]89machine # [ 0.003405] NODE_DATA(0) allocated [mem 0x3ffd2780-0x3ffd7cff]90machine # [ 0.006120] Zone ranges:91machine # [ 0.006121] DMA [mem 0x0000000000001000-0x0000000000ffffff]92machine # [ 0.006123] DMA32 [mem 0x0000000001000000-0x000000003ffd7fff]93machine # [ 0.006124] Normal empty94machine # [ 0.006125] Device empty95machine # [ 0.006125] Movable zone start for each node96machine # [ 0.006126] Early memory node ranges97machine # [ 0.006127] node 0: [mem 0x0000000000001000-0x000000000009efff]98machine # [ 0.006128] node 0: [mem 0x0000000000100000-0x000000003ffd7fff]99machine # [ 0.006129] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffd7fff]100machine # [ 0.006150] On node 0, zone DMA: 1 pages in unavailable ranges101machine # [ 0.006461] On node 0, zone DMA: 97 pages in unavailable ranges102machine # [ 0.027534] On node 0, zone DMA32: 40 pages in unavailable ranges103machine # [ 0.028658] ACPI: PM-Timer IO Port: 0x608104machine # [ 0.028671] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])105machine # [ 0.028703] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23106machine # [ 0.028706] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)107machine # [ 0.028708] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)108machine # [ 0.028709] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)109machine # [ 0.028710] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)110machine # [ 0.028711] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)111machine # [ 0.028713] ACPI: Using ACPI (MADT) for SMP configuration information112machine # [ 0.028714] ACPI: HPET id: 0x8086a201 base: 0xfed00000113machine # [ 0.028718] TSC deadline timer available114machine # [ 0.028722] CPU topo: Max. logical packages: 1115machine # [ 0.028723] CPU topo: Max. logical dies: 1116machine # [ 0.028724] CPU topo: Max. dies per package: 1117machine # [ 0.028727] CPU topo: Max. threads per core: 1118machine # [ 0.028727] CPU topo: Num. cores per package: 1119machine # [ 0.028728] CPU topo: Num. threads per package: 1120machine # [ 0.028728] CPU topo: Allowing 1 present CPUs plus 0 hotplug CPUs121machine # [ 0.028746] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()122machine # [ 0.028784] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]123machine # [ 0.028786] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]124machine # [ 0.028787] [mem 0x40000000-0xafffffff] available for PCI devices125machine # [ 0.028789] Booting paravirtualized kernel on KVM126machine # [ 0.028792] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns127machine # [ 0.033270] setup_percpu: NR_CPUS:384 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1128machine # [ 0.036295] percpu: Embedded 98 pages/cpu s278528 r8192 d114688 u2097152129machine # [ 0.036342] kvm-guest: PV spinlocks disabled, single CPU130machine # [ 0.036344] 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/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/init regInfo=/nix/store/7g43lpqf716ywf0d34314z5b0vdjpqi5-closure-info/registration console=ttyS0,115200n8 console=tty0131machine # [ 0.036439] Unknown kernel command line parameters "regInfo=/nix/store/7g43lpqf716ywf0d34314z5b0vdjpqi5-closure-info/registration", will be passed to user space.132machine # [ 0.036451] random: crng init done133machine # [ 0.036452] printk: log buffer data + meta data: 262144 + 917504 = 1179648 bytes134machine # [ 0.036473] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)135machine # [ 0.036486] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)136machine # [ 0.036519] Fallback order for Node 0: 0137machine # [ 0.036521] Built 1 zonelists, mobility grouping on. Total pages: 262006138machine # [ 0.036522] Policy zone: DMA32139machine # [ 0.039378] mem auto-init: stack:all(zero), heap alloc:on, heap free:off140machine # [ 0.042027] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1141machine # [ 0.044676] allocated 2097152 bytes of page_ext142machine # [ 0.055258] ftrace: allocating 48731 entries in 192 pages143machine # [ 0.055261] ftrace: allocated 192 pages with 2 groups144machine # [ 0.056244] Dynamic Preempt: lazy145machine # [ 0.056445] rcu: Preemptible hierarchical RCU implementation.146machine # [ 0.056446] rcu: RCU event tracing is enabled.147machine # [ 0.056446] rcu: RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=1.148machine # [ 0.056448] Trampoline variant of Tasks RCU enabled.149machine # [ 0.056448] Rude variant of Tasks RCU enabled.150machine # [ 0.056449] Tracing variant of Tasks RCU enabled.151machine # [ 0.056450] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.152machine # [ 0.056450] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1153machine # [ 0.056474] RCU Tasks: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.154machine # [ 0.056476] RCU Tasks Rude: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.155machine # [ 0.056477] RCU Tasks Trace: Setting shift to 0 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=1.156machine # [ 0.061513] NR_IRQS: 24832, nr_irqs: 256, preallocated irqs: 16157machine # [ 0.061819] rcu: srcu_init: Setting srcu_struct sizes based on contention.158machine # [ 0.061827] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns159machine # [ 0.061940] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)160machine # [ 0.069330] Console: colour VGA+ 80x25161machine # [ 0.069334] printk: legacy console [tty0] enabled162machine # [ 0.112472] printk: legacy console [ttyS0] enabled163machine # [ 0.312146] ACPI: Core revision 20250807164machine # [ 0.313749] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns165machine # [ 0.316627] APIC: Switch to symmetric I/O mode setup166machine # [ 0.318397] x2apic enabled167machine # [ 0.319654] APIC: Switched APIC routing to: physical x2apic168machine # [ 0.322685] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1169machine # [ 0.324588] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns170machine # [ 0.327748] Calibrating delay loop (skipped) preset value.. 4800.02 BogoMIPS (lpj=2400010)171machine # [ 0.328867] x86/cpu: User Mode Instruction Prevention (UMIP) activated172machine # [ 0.330996] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127173machine # [ 0.332746] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0174machine # [ 0.334751] mitigations: Enabled attack vectors: user_kernel, user_user, guest_host, guest_guest, SMT mitigations: auto175machine # [ 0.336747] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl176machine # [ 0.338747] Transient Scheduler Attacks: Mitigation: Clear CPU buffers177machine # [ 0.339746] Spectre V2 : Mitigation: Enhanced / Automatic IBRS178machine # [ 0.340746] Speculative Return Stack Overflow: Mitigation: Safe RET179machine # [ 0.341746] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization180machine # [ 0.343757] Spectre V2 : Enabling IBPB for BPF181machine # [ 0.344748] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier182machine # [ 0.346747] active return thunk: srso_alias_return_thunk183machine # [ 0.347775] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'184machine # [ 0.349747] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'185machine # [ 0.350746] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'186machine # [ 0.351746] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'187machine # [ 0.353746] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'188machine # [ 0.355746] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'189machine # [ 0.357746] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'190machine # [ 0.359746] x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'191machine # [ 0.360747] x86/fpu: Supporting XSAVE feature 0x1000: 'Control-flow Kernel registers (KVM only)'192machine # [ 0.361747] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256193machine # [ 0.363746] x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64194machine # [ 0.365746] x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512195machine # [ 0.367746] x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024196machine # [ 0.368746] x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8197machine # [ 0.369746] x86/fpu: xstate_offset[11]: 2440, xstate_sizes[11]: 16198machine # [ 0.370746] x86/fpu: xstate_offset[12]: 2456, xstate_sizes[12]: 24199machine # [ 0.371746] x86/fpu: Enabled xstate features 0x1ae7, context size is 2480 bytes, using 'compacted' format.200machine # [ 0.407947] Freeing SMP alternatives memory: 44K201machine # [ 0.408749] pid_max: default: 32768 minimum: 301202machine # [ 0.409843] LSM: initializing lsm=capability,landlock,yama,bpf,ima203machine # [ 0.410857] landlock: Up and running.204machine # [ 0.411747] Yama: becoming mindful.205machine # [ 0.412960] LSM support for eBPF active206machine # [ 0.414841] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)207machine # [ 0.415774] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)208machine # [ 0.418520] smpboot: CPU0: AMD EPYC 9654 96-Core Processor (family: 0x19, model: 0x11, stepping: 0x1)209machine # [ 0.419316] Performance Events: Fam17h+ core perfctr, AMD PMU driver.210machine # [ 0.419751] ... version: 2211machine # [ 0.420748] ... bit width: 48212machine # [ 0.421806] ... generic counters: 6213machine # [ 0.422748] ... generic bitmap: 000000000000003f214machine # [ 0.423748] ... fixed-purpose counters: 0215machine # [ 0.424748] ... fixed-purpose bitmap: 0000000000000000216machine # [ 0.425748] ... value mask: 0000ffffffffffff217machine # [ 0.426748] ... max period: 00007fffffffffff218machine # [ 0.427748] ... global_ctrl mask: 000000000000003f219machine # [ 0.428868] signal: max sigframe size: 3376220machine # [ 0.429920] rcu: Hierarchical SRCU implementation.221machine # [ 0.430753] rcu: Max phase no-delay instances is 400.222machine # [ 0.436408] smp: Bringing up secondary CPUs ...223machine # [ 0.436764] smp: Brought up 1 node, 1 CPU224machine # [ 0.437751] smpboot: Total of 1 processors activated (4800.02 BogoMIPS)225machine # [ 0.438937] Memory: 941788K/1048024K available (17223K kernel code, 2726K rwdata, 13584K rodata, 3644K init, 2988K bss, 98848K reserved, 0K cma-reserved)226machine # [ 0.440032] devtmpfs: initialized227machine # [ 0.441002] x86/mm: Memory block size: 128MB228machine # [ 0.442703] posixtimers hash table entries: 512 (order: 1, 8192 bytes, linear)229machine # [ 0.443786] futex hash table entries: 256 (16384 bytes on 1 NUMA nodes, total 16 KiB, linear).230machine # [ 0.444894] pinctrl core: initialized pinctrl subsystem231machine # [ 0.446178] PM: RTC time: 02:47:55, date: 2026-09-10232machine # [ 0.449910] NET: Registered PF_NETLINK/PF_ROUTE protocol family233machine # [ 0.451143] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations234machine # [ 0.451772] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations235machine # [ 0.452921] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations236machine # [ 0.453762] audit: initializing netlink subsys (disabled)237machine # [ 0.455006] thermal_sys: Registered thermal governor 'fair_share'238machine # [ 0.455008] thermal_sys: Registered thermal governor 'bang_bang'239machine # [ 0.455752] audit: type=2000 audit(1789008474.910:1): state=initialized audit_enabled=0 res=1240machine # [ 0.457751] thermal_sys: Registered thermal governor 'step_wise'241machine # [ 0.457753] thermal_sys: Registered thermal governor 'user_space'242machine # [ 0.458749] thermal_sys: Registered thermal governor 'power_allocator'243machine # [ 0.459769] cpuidle: using governor menu244machine # [ 0.462949] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5245machine # [ 0.464098] PCI: ECAM [mem 0xb0000000-0xbfffffff] (base 0xb0000000) for domain 0000 [bus 00-ff]246machine # [ 0.464752] PCI: ECAM [mem 0xb0000000-0xbfffffff] reserved as E820 entry247machine # [ 0.465762] PCI: Using configuration type 1 for base access248machine # [ 0.466967] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.249machine # [ 0.473773] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages250machine # [ 0.474749] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page251machine # [ 0.479748] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages252machine # [ 0.480749] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page253machine # [ 0.492126] ACPI: Added _OSI(Module Device)254machine # [ 0.492750] ACPI: Added _OSI(Processor Device)255machine # [ 0.494651] ACPI: Added _OSI(Processor Aggregator Device)256machine # [ 0.500394] ACPI: 1 ACPI AML tables successfully acquired and loaded257machine # [ 0.506176] ACPI: Interpreter enabled258machine # [ 0.506766] ACPI: PM: (supports S0 S3 S4 S5)259machine # [ 0.509748] ACPI: Using IOAPIC for interrupt routing260machine # [ 0.510847] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug261machine # [ 0.513747] PCI: Using E820 reservations for host bridge windows262machine # [ 0.514903] ACPI: Enabled 2 GPEs in block 00 to 3F263machine # [ 0.521286] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])264machine # [ 0.521755] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]265machine # [ 0.522832] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR]266machine # [ 0.523878] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability]267machine # [ 0.525431] PCI host bridge to bus 0000:00268machine # [ 0.525755] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]269machine # [ 0.526750] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]270machine # [ 0.527749] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]271machine # [ 0.528755] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window]272machine # [ 0.529749] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]273machine # [ 0.530781] pci_bus 0000:00: root bus resource [mem 0x100000000-0x8ffffffff window]274machine # [ 0.531750] pci_bus 0000:00: root bus resource [bus 00-ff]275machine # [ 0.532939] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint276machine # [ 0.534683] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint277machine # [ 0.537846] pci 0000:00:01.0: BAR 0 [mem 0xfd000000-0xfdffffff pref]278machine # [ 0.538776] pci 0000:00:01.0: BAR 2 [mem 0xfebd0000-0xfebd0fff]279machine # [ 0.539877] pci 0000:00:01.0: ROM [mem 0xfebc0000-0xfebcffff pref]280machine # [ 0.540998] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]281machine # [ 0.542818] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint282machine # [ 0.545780] pci 0000:00:02.0: BAR 0 [io 0xc140-0xc15f]283machine # [ 0.546768] pci 0000:00:02.0: BAR 1 [mem 0xfebd1000-0xfebd1fff]284machine # [ 0.547802] pci 0000:00:02.0: BAR 4 [mem 0xfe000000-0xfe003fff 64bit pref]285machine # [ 0.548763] pci 0000:00:02.0: ROM [mem 0xfeb40000-0xfeb7ffff pref]286machine # [ 0.551097] pci 0000:00:03.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint287machine # [ 0.553914] pci 0000:00:03.0: BAR 0 [io 0xc160-0xc17f]288machine # [ 0.554764] pci 0000:00:03.0: BAR 1 [mem 0xfebd2000-0xfebd2fff]289machine # [ 0.555806] pci 0000:00:03.0: BAR 4 [mem 0xfe004000-0xfe007fff 64bit pref]290machine # [ 0.558091] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint291machine # [ 0.560624] pci 0000:00:04.0: BAR 0 [io 0xc080-0xc0bf]292machine # [ 0.561763] pci 0000:00:04.0: BAR 1 [mem 0xfebd3000-0xfebd3fff]293machine # [ 0.562803] pci 0000:00:04.0: BAR 4 [mem 0xfe008000-0xfe00bfff 64bit pref]294machine # [ 0.565024] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint295machine # [ 0.567749] pci 0000:00:05.0: BAR 0 [io 0xc180-0xc19f]296machine # [ 0.568764] pci 0000:00:05.0: BAR 1 [mem 0xfebd4000-0xfebd4fff]297machine # [ 0.569828] pci 0000:00:05.0: BAR 4 [mem 0xfe00c000-0xfe00ffff 64bit pref]298machine # [ 0.571961] pci 0000:00:06.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint299machine # [ 0.574782] pci 0000:00:06.0: BAR 0 [io 0xc1a0-0xc1bf]300machine # [ 0.575763] pci 0000:00:06.0: BAR 1 [mem 0xfebd5000-0xfebd5fff]301machine # [ 0.576802] pci 0000:00:06.0: BAR 4 [mem 0xfe010000-0xfe013fff 64bit pref]302machine # [ 0.579048] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint303machine # [ 0.581674] pci 0000:00:07.0: BAR 0 [io 0xc000-0xc07f]304machine # [ 0.582763] pci 0000:00:07.0: BAR 1 [mem 0xfebd6000-0xfebd6fff]305machine # [ 0.583806] pci 0000:00:07.0: BAR 4 [mem 0xfe014000-0xfe017fff 64bit pref]306machine # [ 0.586199] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint307machine # [ 0.588312] pci 0000:00:08.0: BAR 0 [io 0xc1c0-0xc1df]308machine # [ 0.588764] pci 0000:00:08.0: BAR 1 [mem 0xfebd7000-0xfebd7fff]309machine # [ 0.589802] pci 0000:00:08.0: BAR 4 [mem 0xfe018000-0xfe01bfff 64bit pref]310machine # [ 0.590764] pci 0000:00:08.0: ROM [mem 0xfeb80000-0xfebbffff pref]311machine # [ 0.592973] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint312machine # [ 0.594794] pci 0000:00:09.0: BAR 1 [mem 0xfebd8000-0xfebd8fff]313machine # [ 0.595802] pci 0000:00:09.0: BAR 4 [mem 0xfe01c000-0xfe01ffff 64bit pref]314machine # [ 0.597973] pci 0000:00:0a.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint315machine # [ 0.600429] pci 0000:00:0a.0: BAR 0 [io 0xc0c0-0xc0ff]316machine # [ 0.600763] pci 0000:00:0a.0: BAR 1 [mem 0xfebd9000-0xfebd9fff]317machine # [ 0.601808] pci 0000:00:0a.0: BAR 4 [mem 0xfe020000-0xfe023fff 64bit pref]318machine # [ 0.603992] pci 0000:00:0b.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint319machine # [ 0.606191] pci 0000:00:0b.0: BAR 0 [io 0xc1e0-0xc1ff]320machine # [ 0.606764] pci 0000:00:0b.0: BAR 1 [mem 0xfebda000-0xfebdafff]321machine # [ 0.607802] pci 0000:00:0b.0: BAR 4 [mem 0xfe024000-0xfe027fff 64bit pref]322machine # [ 0.610007] pci 0000:00:1d.0: [8086:2934] type 00 class 0x0c0300 conventional PCI endpoint323machine # [ 0.613447] pci 0000:00:1d.0: BAR 4 [io 0xc200-0xc21f]324machine # [ 0.615191] pci 0000:00:1d.1: [8086:2935] type 00 class 0x0c0300 conventional PCI endpoint325machine # [ 0.616815] pci 0000:00:1d.1: BAR 4 [io 0xc220-0xc23f]326machine # [ 0.618093] pci 0000:00:1d.2: [8086:2936] type 00 class 0x0c0300 conventional PCI endpoint327machine # [ 0.619706] pci 0000:00:1d.2: BAR 4 [io 0xc240-0xc25f]328machine # [ 0.621149] pci 0000:00:1d.7: [8086:293a] type 00 class 0x0c0320 conventional PCI endpoint329machine # [ 0.623332] pci 0000:00:1d.7: BAR 0 [mem 0xfebdb000-0xfebdbfff]330machine # [ 0.624502] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint331machine # [ 0.626448] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO332machine # [ 0.628247] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint333machine # [ 0.630073] pci 0000:00:1f.2: BAR 4 [io 0xc260-0xc27f]334machine # [ 0.630764] pci 0000:00:1f.2: BAR 5 [mem 0xfebdc000-0xfebdcfff]335machine # [ 0.632445] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint336machine # [ 0.634801] pci 0000:00:1f.3: BAR 4 [io 0x0700-0x073f]337machine # [ 0.642473] ACPI: PCI: Interrupt link LNKA configured for IRQ 10338machine # [ 0.643917] ACPI: PCI: Interrupt link LNKB configured for IRQ 10339machine # [ 0.644920] ACPI: PCI: Interrupt link LNKC configured for IRQ 11340machine # [ 0.645915] ACPI: PCI: Interrupt link LNKD configured for IRQ 11341machine # [ 0.646915] ACPI: PCI: Interrupt link LNKE configured for IRQ 10342machine # [ 0.647902] ACPI: PCI: Interrupt link LNKF configured for IRQ 10343machine # [ 0.648914] ACPI: PCI: Interrupt link LNKG configured for IRQ 11344machine # [ 0.649959] ACPI: PCI: Interrupt link LNKH configured for IRQ 11345machine # [ 0.650815] ACPI: PCI: Interrupt link GSIA configured for IRQ 16346machine # [ 0.651772] ACPI: PCI: Interrupt link GSIB configured for IRQ 17347machine # [ 0.652770] ACPI: PCI: Interrupt link GSIC configured for IRQ 18348machine # [ 0.653771] ACPI: PCI: Interrupt link GSID configured for IRQ 19349machine # [ 0.654765] ACPI: PCI: Interrupt link GSIE configured for IRQ 20350machine # [ 0.655775] ACPI: PCI: Interrupt link GSIF configured for IRQ 21351machine # [ 0.656770] ACPI: PCI: Interrupt link GSIG configured for IRQ 22352machine # [ 0.657766] ACPI: PCI: Interrupt link GSIH configured for IRQ 23353machine # [ 0.659964] iommu: Default domain type: Translated354machine # [ 0.660760] iommu: DMA domain TLB invalidation policy: lazy mode355machine # [ 0.662078] ACPI: bus type USB registered356machine # [ 0.662823] usbcore: registered new interface driver usbfs357machine # [ 0.663771] usbcore: registered new interface driver hub358machine # [ 0.664798] usbcore: registered new device driver usb359machine # [ 0.666876] NetLabel: Initializing360machine # [ 0.667748] NetLabel: domain hash size = 128361machine # [ 0.668748] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO362machine # [ 0.669786] NetLabel: unlabeled traffic allowed by default363machine # [ 0.670765] PCI: Using ACPI for IRQ routing364machine # [ 0.779048] pci 0000:00:01.0: vgaarb: setting as boot VGA device365machine # [ 0.779743] pci 0000:00:01.0: vgaarb: bridge control possible366machine # [ 0.779743] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none367machine # [ 0.779754] vgaarb: loaded368machine # [ 0.780955] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0369machine # [ 0.781749] hpet0: 3 comparators, 64-bit 100.000000 MHz counter370machine # [ 0.785854] clocksource: Switched to clocksource kvm-clock371machine # [ 0.790667] VFS: Disk quotas dquot_6.6.0372machine # [ 0.793714] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)373machine # [ 0.796219] pnp: PnP ACPI init374machine # [ 0.797566] ACPI: IRQ 4 override to edge(!), high(!)375machine # [ 0.799372] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved376machine # [ 0.802161] pnp: PnP ACPI: found 6 devices377machine # [ 0.810660] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns378machine # [ 0.813516] clocksource: Switched to clocksource acpi_pm379machine # [ 0.815381] NET: Registered PF_INET protocol family380machine # [ 0.817232] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)381machine # [ 0.837108] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)382machine # [ 0.840019] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)383machine # [ 0.842564] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)384machine # [ 0.845191] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear)385machine # [ 0.847565] TCP: Hash tables configured (established 8192 bind 8192)386machine # [ 0.849690] MPTCP token hash table entries: 1024 (order: 3, 24576 bytes, linear)387machine # [ 0.853316] UDP hash table entries: 512 (order: 3, 32768 bytes, linear)388machine # [ 0.855400] UDP-Lite hash table entries: 512 (order: 3, 32768 bytes, linear)389machine # [ 0.857568] NET: Registered PF_UNIX/PF_LOCAL protocol family390machine # [ 0.859310] NET: Registered PF_XDP protocol family391machine # [ 0.860873] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]392machine # [ 0.862787] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]393machine # [ 0.864733] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]394machine # [ 0.866825] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window]395machine # [ 0.868954] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]396machine # [ 0.870988] pci_bus 0000:00: resource 9 [mem 0x100000000-0x8ffffffff window]397machine # [ 0.873832] ACPI: \_SB_.GSIA: Enabled at IRQ 16398machine # [ 0.877871] ACPI: \_SB_.GSIB: Enabled at IRQ 17399machine # [ 0.881086] ACPI: \_SB_.GSIC: Enabled at IRQ 18400machine # [ 0.884009] ACPI: \_SB_.GSID: Enabled at IRQ 19401machine # [ 0.886559] PCI: CLS 0 bytes, default 64402machine # [ 0.888123] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229840d1729, max_idle_ns: 440795276167 ns403machine # [ 0.891256] Trying to unpack rootfs image as initramfs...404machine # [ 0.940046] Initialise system trusted keyrings405machine # [ 0.944016] workingset: timestamp_bits=40 max_order=18 bucket_order=0406machine # [ 0.971246] Key type asymmetric registered407machine # [ 0.972606] Asymmetric key parser 'x509' registered408machine # [ 0.976040] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)409machine # [ 0.986007] io scheduler mq-deadline registered410machine # [ 0.987489] io scheduler kyber registered411machine # [ 0.991588] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled412machine # [ 0.993900] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A413machine # [ 1.002896] Linux agpgart interface v0.103414machine # [ 1.006127] ACPI: bus type drm_connector registered415machine # [ 1.010307] usbcore: registered new interface driver usbserial_generic416machine # [ 1.012289] usbserial: USB Serial support registered for generic417machine # [ 1.017975] amd_pstate: The CPPC feature is supported but currently disabled by the BIOS.418machine # [ 1.017975] Please enable it if your BIOS has the CPPC option.419machine # [ 1.022028] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled420machine # [ 1.031115] drop_monitor: Initializing network drop monitor service421machine # [ 1.033221] NET: Registered PF_INET6 protocol family422machine # [ 1.039087] Segment Routing with IPv6423machine # [ 1.040339] In-situ OAM (IOAM) with IPv6424machine # [ 1.045351] IPI shorthand broadcast: enabled425machine # [ 1.054299] sched_clock: Marking stable (795029262, 258916801)->(1274194320, -220248257)426machine # [ 1.063157] registered taskstats version 1427machine # [ 1.064832] Loading compiled-in X.509 certificates428machine # [ 1.087195] Demotion targets for Node 0: null429machine # [ 1.088750] Key type .fscrypt registered430machine # [ 1.091966] Key type fscrypt-provisioning registered431machine # [ 1.093728] ima: No TPM chip found, activating TPM-bypass!432machine # [ 1.099964] ima: Allocated hash algorithm: sha1433machine # [ 1.101485] ima: No architecture policies found434machine # [ 1.105171] PM: Magic number: 10:215:763435machine # [ 1.109527] RAS: Correctable Errors collector initialized.436machine # [ 1.121220] clk: Disabling unused clocks437machine # [ 1.123975] PM: genpd: Disabling unused power domains438machine # [ 1.279434] Freeing initrd memory: 28324K439machine # [ 1.283564] Freeing unused decrypted memory: 2028K440machine # [ 1.287230] Freeing unused kernel image (initmem) memory: 3644K441machine # [ 1.289143] Write protecting the kernel read-only data: 32768k442machine # [ 1.292020] Freeing unused kernel image (text/rodata gap) memory: 1208K443machine # [ 1.294570] Freeing unused kernel image (rodata/data gap) memory: 752K444machine # [ 1.347981] x86/mm: Checked W+X mappings: passed, no W+X pages found.445machine # [ 1.349966] Run /init as init process446machine # [ 1.362578] systemd[1]: Inserted module 'autofs4'447machine # [ 1.380447] fuse: init (API version 7.45)448machine # [ 1.387360] ACPI: \_SB_.GSIG: Enabled at IRQ 22449machine # [ 1.391248] ACPI: \_SB_.GSIH: Enabled at IRQ 23450machine # [ 1.396210] ACPI: \_SB_.GSIE: Enabled at IRQ 20451machine # [ 1.399907] ACPI: \_SB_.GSIF: Enabled at IRQ 21452machine # [ 1.436049] systemd[1]: Successfully made /usr/ read-only.453machine # [ 1.775492] systemd[1]: systemd 262-2ff84cf 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)454machine # [ 1.797758] systemd[1]: Detected virtualization kvm.455machine # [ 1.801855] systemd[1]: Detected architecture x86-64.456machine # [ 1.806041] systemd[1]: Running in initrd.457machine # [ 1.810320] systemd[1]: Initializing machine ID from random generator.458machine # [ 1.815642] systemd[1]: Hostname set to <machine>.459machine # [ 1.905421] systemd[1]: bpf-restrict-fs: LSM BPF program attached460machine # [ 1.949367] systemd[1]: Queued start job for default target Initrd Default Target.461machine # [ 1.954745] systemd[1]: Created slice Slice /system/modprobe.462machine # [ 1.956887] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.463machine # [ 1.959453] systemd[1]: Expecting device /dev/disk/by-label/nixos...464machine # [ 1.961459] systemd[1]: Reached target Path Units.465machine # [ 1.964225] systemd[1]: Reached target Slice Units.466machine # [ 1.965846] systemd[1]: Reached target Swaps.467machine # [ 1.967375] systemd[1]: Reached target Timer Units.468machine # [ 1.969141] systemd[1]: Listening on D-Bus System Message Bus Socket.469machine # [ 1.971367] systemd[1]: Listening on Journal Socket (/dev/log).470machine # [ 1.973480] systemd[1]: Listening on Journal Sockets.471machine # [ 1.975239] systemd[1]: Listening on udev Kernel Socket.472machine # [ 1.977009] systemd[1]: Reached target Socket Units.473machine # [ 1.979793] systemd[1]: Starting Create List of Static Device Nodes...474machine # [ 2.001258] systemd[1]: Starting Load Kernel Module 9pnet_virtio...475machine # [ 2.011115] systemd[1]: Starting Load Kernel Module configfs...476machine # [ 2.025240] systemd[1]: Starting Journal Service...477machine # [ 2.039590] systemd[1]: Starting Load Kernel Modules...478machine # [ 2.052071] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os479machine # [ 2.057708] netfs: FS-Cache loaded480machine # [ 2.065371] systemd[1]: Starting Coldplug All udev Devices...481machine # [ 2.077226] 9pnet: Installing 9P2000 support482machine # [ 2.092544] systemd[1]: Finished Create List of Static Device Nodes.483machine # [ 2.095985] systemd-journald[67]: Collecting audit messages is disabled.484machine # [ 2.110335] systemd[1]: modprobe@configfs.service: Deactivated successfully.485machine # [ 2.120631] systemd[1]: Finished Load Kernel Module configfs.486machine # [ 2.126785] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.487machine # [ 2.133601] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.488machine # [ 2.142611] systemd[1]: Finished Load Kernel Module 9pnet_virtio.489machine # [ 2.148977] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev490machine # [ 2.155849] systemd[1]: Kernel Configuration File System skipped, unmet condition check ConditionPathExists=/sys/kernel/config491machine # [ 2.176210] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...492machine # [ 2.196259] systemd[1]: Finished Load Kernel Modules.493machine # [ 2.208460] systemd[1]: Starting Apply Kernel Variables...494machine # [ 2.228103] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.495machine # [ 2.243525] systemd[1]: Starting Create Static Device Nodes in /dev...496machine # [ 2.263107] systemd[1]: Finished Apply Kernel Variables.497machine # [ 2.287137] systemd[1]: Finished Create Static Device Nodes in /dev.498machine # [ 2.292356] systemd[1]: Reached target Preparation for Local File Systems.499machine # [ 2.301076] systemd[1]: Reached target Local File Systems.500machine # [ 2.309191] systemd[1]: Starting Rule-based Manager for Device Events and Files...501machine # [ 2.054827] systemd-modules-load[68]: Inserted module 'dm_mod'502machine # [ 2.058877] systemd-modules-load[68]: Inserted module 'virtio_balloon'503machine # [ 2.060901] systemd-modules-load[68]: Inserted module 'virtio_gpu'504machine # [ 2.329320] systemd[1]: Started Journal Service.505machine # [ 2.095329] systemd[1]: Starting Create System Files and Directories...506machine # [ 2.128942] systemd[1]: Finished Create System Files and Directories.507machine # [ 2.133302] systemd-udevd[74]: Using default interface naming scheme 'v261'.508machine # [ 2.169636] systemd[1]: Started Rule-based Manager for Device Events and Files.509machine # [ 2.212170] systemd[1]: Finished Coldplug All udev Devices.510machine # [ 2.213782] systemd[1]: Reached target System Initialization.511machine # [ 2.215529] systemd[1]: Reached target Basic System.512machine # [ 2.755648] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12513machine # [ 2.781519] serio: i8042 KBD port at 0x60,0x64 irq 1514machine # [ 2.782776] serio: i8042 AUX port at 0x60,0x64 irq 12515machine # [ 2.786604] virtio_blk virtio5: 1/0/0 default/read/poll queues516machine # [ 2.809833] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)517machine # [ 2.816099] ehci-pci 0000:00:1d.7: EHCI Host Controller518machine # [ 2.817400] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1519machine # [ 2.821869] ehci-pci 0000:00:1d.7: irq 19, io mem 0xfebdb000520machine # [ 2.831986] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00521machine # [ 2.833640] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.18522machine # [ 2.838630] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1523machine # [ 2.841769] usb usb1: Product: EHCI Host Controller524machine # [ 2.843178] usb usb1: Manufacturer: Linux 6.18.50 ehci_hcd525machine # [ 2.844684] usb usb1: SerialNumber: 0000:00:1d.7526machine # [ 2.846433] hub 1-0:1.0: USB hub found527machine # [ 2.848044] hub 1-0:1.0: 6 ports detected528machine # [ 2.852189] uhci_hcd 0000:00:1d.0: UHCI Host Controller529machine # [ 2.853513] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2530machine # [ 2.874848] uhci_hcd 0000:00:1d.0: detected 2 ports531machine # [ 2.886585] uhci_hcd 0000:00:1d.0: irq 16, io port 0x0000c200532machine # [ 2.899603] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18533machine # [ 2.914109] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1534machine # [ 2.922089] SCSI subsystem initialized535machine # [ 2.925802] usb usb2: Product: UHCI Host Controller536machine # [ 2.936968] usb usb2: Manufacturer: Linux 6.18.50 uhci_hcd537machine # [ 2.686983] (udev-worker)[91]: Network interface NamePolicy= disabled on kernel command line.538machine # [ 2.693386] systemd[1]: Starting Virtual Console Setup...539machine # [ 2.957761] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0540machine # [ 2.962705] usb usb2: SerialNumber: 0000:00:1d.0541machine # [ 2.712849] (udev-worker)[89]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.542machine # [ 2.718229] (udev-worker)[89]: Network interface NamePolicy= disabled on kernel command line.543machine # [ 2.990117] hub 2-0:1.0: USB hub found544machine # [ 2.743115] systemd-vconsole-setup[99]: Configuration of first virtual console was skipped, ignoring remaining ones.545machine # [ 2.748342] systemd[1]: Finished Virtual Console Setup.546machine # [ 3.012129] hub 2-0:1.0: 2 ports detected547machine # [ 3.023130] uhci_hcd 0000:00:1d.1: UHCI Host Controller548machine # [ 3.039992] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3549machine # [ 3.048714] uhci_hcd 0000:00:1d.1: detected 2 ports550machine # [ 2.793103] systemd[1]: Found device /dev/disk/by-label/nixos.551machine # [ 2.798126] systemd[1]: Reached target Initrd Root Device.552machine # [ 2.801103] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...553machine # [ 3.062110] uhci_hcd 0000:00:1d.1: irq 17, io port 0x0000c220554machine # [ 3.067120] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18555machine # [ 3.069050] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1556machine # [ 3.077979] usb usb3: Product: UHCI Host Controller557machine # [ 3.088415] usb usb3: Manufacturer: Linux 6.18.50 uhci_hcd558machine # [ 3.089890] usb 1-1: new high-speed USB device number 2 using ehci-pci559machine # [ 3.094389] usb usb3: SerialNumber: 0000:00:1d.1560machine # [ 2.844893] systemd-fsck[108]: nixos: clean, 12/65536 files, 13019/262144 blocks561machine # [ 3.106142] hub 3-0:1.0: USB hub found562machine # [ 3.107538] hub 3-0:1.0: 2 ports detected563machine # [ 3.110432] ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode564machine # [ 2.857304] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.565machine # [ 2.861343] systemd[1]: Mounting /sysroot...566machine # [ 3.127682] uhci_hcd 0000:00:1d.2: UHCI Host Controller567machine # [ 3.129161] ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f)568machine # [ 3.134035] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4569machine # [ 3.138254] ahci 0000:00:1f.2: flags: 64bit ncq only570machine # [ 3.142090] uhci_hcd 0000:00:1d.2: detected 2 ports571machine # [ 3.147175] uhci_hcd 0000:00:1d.2: irq 18, io port 0x0000c240572machine # [ 3.156673] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 6.18573machine # [ 3.160058] scsi host0: ahci574machine # [ 3.167555] scsi host1: ahci575machine # [ 3.175422] scsi host2: ahci576machine # [ 3.177956] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1577machine # [ 3.182787] scsi host3: ahci578machine # [ 3.192195] scsi host4: ahci579machine # [ 3.194331] usb usb4: Product: UHCI Host Controller580machine # [ 3.199196] scsi host5: ahci581machine # [ 3.200204] ata1: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc100 irq 44 lpm-pol 1582machine # [ 3.210781] usb usb4: Manufacturer: Linux 6.18.50 uhci_hcd583machine # [ 3.218186] usb usb4: SerialNumber: 0000:00:1d.2584machine # [ 3.222094] ata2: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc180 irq 44 lpm-pol 1585machine # [ 3.226442] hub 4-0:1.0: USB hub found586machine # [ 3.229413] hub 4-0:1.0: 2 ports detected587machine # [ 3.233095] ata3: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc200 irq 44 lpm-pol 1588machine # [ 3.235101] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00589machine # [ 3.237009] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10590machine # [ 3.242091] ata4: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc280 irq 44 lpm-pol 1591machine # [ 3.244182] usb 1-1: Product: QEMU USB Tablet592machine # [ 3.248971] usb 1-1: Manufacturer: QEMU593machine # [ 3.250769] usb 1-1: SerialNumber: 28754-0000:00:1d.7-1594machine # [ 3.275742] ata5: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc300 irq 44 lpm-pol 1595machine # [ 3.286829] EXT4-fs (vda): mounted filesystem 7e9f6291-572d-414b-a6d4-b7e765737475 r/w with ordered data mode. Quota mode: none.596machine # [ 3.292299] ata6: SATA max UDMA/133 abar m4096@0xfebdc000 port 0xfebdc380 irq 44 lpm-pol 1597machine # [ 3.037347] systemd[1]: Mounted /sysroot.598machine # [ 3.041117] systemd[1]: Reached target Initrd Root File System.599machine # [ 3.046561] systemd[1]: Starting Mountpoints Configured in the Real Root...600machine # [ 3.070293] systemd-sysroot-fstab-check[129]: /sysroot should be mounted in the initrd, will request daemon-reload.601machine # [ 3.076616] systemd[1]: Reload requested from client PID 129 ('systemd-sysroot') (unit initrd-parse-etc.service)...602machine # [ 3.079376] systemd[1]: Reloading...603machine # [ 3.363636] hid: raw HID events driver (C) Jiri Kosina604machine # [ 3.162109] systemd[1]: Reloading finished in 86 ms.605machine # [ 3.170546] systemd-sysroot-fstab-check[129]: Requesting initrd-fs.target/start/replace...606machine # [ 3.174798] systemd-sysroot-fstab-check[129]: Requesting swap.target/start/replace...607machine # [ 3.178791] systemd[1]: initrd-parse-etc.service: Deactivated successfully.608machine # [ 3.181334] systemd[1]: Finished Mountpoints Configured in the Real Root.609machine # [ 3.183398] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.610machine # [ 3.187574] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio611machine # [ 3.634474] ata2: SATA link down (SStatus 0 SControl 300)612machine # [ 3.638635] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)613machine # [ 3.643559] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100614machine # [ 3.646833] ata3.00: applying bridge limits615machine # [ 3.649795] ata1: SATA link down (SStatus 0 SControl 300)616machine # [ 3.654230] ata6: SATA link down (SStatus 0 SControl 300)617machine # [ 3.658300] ata4: SATA link down (SStatus 0 SControl 300)618machine # [ 3.663217] ata5: SATA link down (SStatus 0 SControl 300)619machine # [ 3.667424] ata3.00: configured for UDMA/100620machine # [ 3.671786] scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5621machine # [ 3.716483] usbcore: registered new interface driver usbhid622machine # [ 3.720286] usbhid: USB HID core driver623machine # [ 3.731258] 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/input2624machine # [ 3.734204] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:1d.7-1/input0625machine # [ 3.737837] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray626machine # [ 3.748104] cdrom: Uniform CD-ROM driver Revision: 3.20627machine # [ 3.829675] systemd[1]: Mounting /sysroot/nix/.ro-store...628machine # [ 3.840270] systemd[1]: Mounting /sysroot/nix/.rw-store...629machine # [ 3.849481] systemd[1]: Mounting /sysroot/run...630machine # [ 3.867869] systemd[1]: Mounting /sysroot/tmp/shared...631machine # [ 3.879563] systemd[1]: Mounting /sysroot/tmp/xchg...632machine # [ 3.914180] systemd[1]: Mounted /sysroot/nix/.rw-store.633machine # [ 4.178386] 9p: Installing v9fs 9p2000 file system support634machine # [ 3.922933] systemd[1]: Mounted /sysroot/run.635machine # [ 3.932101] systemd[1]: Mounted /sysroot/nix/.ro-store.636machine # [ 3.937167] systemd[1]: Mounted /sysroot/tmp/shared.637machine # [ 3.939089] systemd[1]: Mounted /sysroot/tmp/xchg.638machine # [ 3.944511] systemd[1]: Starting rw-sysroot-nix-store.service...639machine # [ 3.957692] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.640machine # [ 3.960371] systemd[1]: Finished rw-sysroot-nix-store.service.641machine # [ 3.963821] systemd[1]: Mounting /sysroot/nix/store...642machine # [ 3.986363] systemd[1]: Mounted /sysroot/nix/store.643machine # [ 3.989462] systemd[1]: Reached target Initrd File Systems.644machine # [ 3.991201] systemd[1]: Starting Find NixOS closure...645machine # [ 3.994540] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...646machine # [ 4.017583] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.647machine # [ 4.027628] systemd[1]: Finished Find NixOS closure.648machine # [ 4.029934] systemd[1]: Reached target Initrd Default Target.649machine # [ 4.032814] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...650machine # [ 4.050074] systemd[1]: Stopped target Initrd Default Target.651machine # [ 4.052291] systemd[1]: Stopped target Basic System.652machine # [ 4.054343] systemd[1]: Stopped target Initrd Root Device.653machine # [ 4.055991] systemd[1]: Stopped target Path Units.654machine # [ 4.057689] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.655machine # [ 4.061132] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.656machine # [ 4.063321] systemd[1]: Stopped target Slice Units.657machine # [ 4.064758] systemd[1]: Stopped target Socket Units.658machine # [ 4.066455] systemd[1]: Stopped target System Initialization.659machine # [ 4.068502] systemd[1]: Stopped target Swaps.660machine # [ 4.070298] systemd[1]: Stopped target Timer Units.661machine # [ 4.072223] systemd[1]: dbus.socket: Deactivated successfully.662machine # [ 4.073830] systemd[1]: Closed D-Bus System Message Bus Socket.663machine # [ 4.075875] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.664machine # [ 4.078201] systemd[1]: Stopped Find NixOS closure.665machine # [ 4.080263] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio666machine # [ 4.083082] systemd[1]: Starting rw-sysroot-nix-store.service...667machine # [ 4.085373] systemd[1]: systemd-sysctl.service: Deactivated successfully.668machine # [ 4.088231] systemd[1]: Stopped Apply Kernel Variables.669machine # [ 4.089712] systemd[1]: systemd-modules-load.service: Deactivated successfully.670machine # [ 4.091639] systemd[1]: Stopped Load Kernel Modules.671machine # [ 4.093438] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.672machine # [ 4.096196] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.673machine # [ 4.098382] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.674machine # [ 4.101180] systemd[1]: Stopped Create System Files and Directories.675machine # [ 4.102872] systemd[1]: Stopped target Local File Systems.676machine # [ 4.104369] systemd[1]: Stopped target Preparation for Local File Systems.677machine # [ 4.106617] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.678machine # [ 4.110942] systemd[1]: Stopped Coldplug All udev Devices.679machine # [ 4.112508] systemd[1]: Stopping Rule-based Manager for Device Events and Files...680machine # [ 4.114823] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.681machine # [ 4.117443] systemd[1]: Stopped Virtual Console Setup.682machine # [ 4.131938] systemd[1]: initrd-cleanup.service: Deactivated successfully.683machine # [ 4.135962] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.684machine # [ 4.140174] systemd[1]: systemd-udevd.service: Deactivated successfully.685machine # [ 4.142756] systemd[1]: Stopped Rule-based Manager for Device Events and Files.686machine # [ 4.145558] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.687machine # [ 4.149101] systemd[1]: Finished rw-sysroot-nix-store.service.688machine # [ 4.153993] systemd[1]: Starting Cleanup udev Database...689machine # [ 4.155707] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.690machine # [ 4.158326] systemd[1]: Stopped Create Static Device Nodes in /dev.691machine # [ 4.160869] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.692machine # [ 4.162965] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.693machine # [ 4.164935] systemd[1]: kmod-static-nodes.service: Deactivated successfully.694machine # [ 4.167197] systemd[1]: Stopped Create List of Static Device Nodes.695machine # [ 4.185658] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.696machine # [ 4.189102] systemd[1]: Finished Cleanup udev Database.697machine # [ 4.191119] systemd[1]: Reached target Switch Root.698machine # [ 4.193657] systemd[1]: Starting NixOS Activation...699machine # [ 4.365588] initrd-nixos-activation-start[192]: booting system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test700machine # [ 4.435702] initrd-nixos-activation-start[192]: running activation script...701machine # [ 4.807793] initrd-nixos-activation-start[215]: setting up /etc...702machine # [ 5.103348] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.703machine # [ 5.106172] systemd[1]: Finished NixOS Activation.704machine # [ 5.109275] systemd[1]: Starting Switch Root...705machine # [ 5.123604] systemd[1]: Switching root.706machine # [ 5.517231] systemd-journald[67]: Received SIGTERM from PID 1 (systemd).707machine # [ 5.674034] NET: Registered PF_VSOCK protocol family708machine # [ 6.063480] systemd[1]: systemd 262-2ff84cf 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)709machine # [ 6.080190] systemd[1]: Detected virtualization kvm.710machine # [ 6.083495] systemd[1]: Detected architecture x86-64.711machine # [ 6.086861] systemd[1]: Detected first boot.712machine # [ 6.095171] systemd[1]: Initializing machine ID from random generator.713machine # [ 6.216433] systemd[1]: bpf-restrict-fs: LSM BPF program attached714machine # [ 6.350705] systemd[1]: Applying preset policy.715machine # [ 6.845591] systemd[1]: Populated /etc with preset unit settings.716machine # [ 7.335453] systemd[1]: initrd-switch-root.service: Deactivated successfully.717machine # [ 7.337876] systemd[1]: Stopped initrd-switch-root.service.718machine # [ 7.341479] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.719machine # [ 7.345023] systemd[1]: Created slice Slice /system/getty.720machine # [ 7.347136] systemd[1]: Created slice User and Session Slice.721machine # [ 7.348859] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.722machine # [ 7.351028] systemd[1]: Started Forward Password Requests to Wall Directory Watch.723machine # [ 7.352886] systemd[1]: Expecting device /dev/hvc0...724machine # [ 7.366619] systemd[1]: Expecting device /dev/ttyS0...725machine # [ 7.368003] systemd[1]: Reached target Local Encrypted Volumes.726machine # [ 7.369509] systemd[1]: Stopped target initrd-fs.target.727machine # [ 7.370853] systemd[1]: Stopped target initrd-root-fs.target.728machine # [ 7.372324] systemd[1]: Stopped target initrd-switch-root.target.729machine # [ 7.373965] systemd[1]: Reached target Virtual Machines and Containers.730machine # [ 7.375650] systemd[1]: Reached target Path Units.731machine # [ 7.376981] systemd[1]: Reached target Remote File Systems.732machine # [ 7.378421] systemd[1]: Reached target Slice Units.733machine # [ 7.379727] systemd[1]: Reached target Swaps.734machine # [ 7.384000] systemd[1]: Listening on Query the User Interactively for a Password.735machine # [ 7.389091] systemd[1]: Listening on Process Core Dump Socket.736machine # [ 7.393117] systemd[1]: Listening on Credential Encryption/Decryption.737machine # [ 7.396734] systemd[1]: Listening on Factory Reset Management.738machine # [ 7.398387] systemd[1]: Listening on Hostname Service Socket.739machine # [ 7.403538] systemd[1]: Starting Journal Log Access Socket...740machine # [ 7.406165] systemd[1]: Listening on Journal Audit Socket.741machine # [ 7.409797] systemd[1]: Listening on Console Output Muting Service Socket.742machine # [ 7.412186] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.743machine # [ 7.414476] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os744machine # [ 7.416774] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki745machine # [ 7.427955] systemd[1]: Listening on Disk Repartitioning Service Socket.746machine # [ 7.429798] systemd[1]: Listening on udev Varlink Socket.747machine # [ 7.434739] systemd[1]: Mounting Huge Pages File System...748machine # [ 7.439058] systemd[1]: Mounting POSIX Message Queue File System...749machine # [ 7.447505] systemd[1]: Mounting Kernel Debug File System...750machine # [ 7.454826] systemd[1]: Mounting Kernel Trace File System...751machine # [ 7.462834] systemd[1]: Starting Create List of Static Device Nodes...752machine # [ 7.465420] systemd[1]: Load Kernel Module 9pnet_virtio skipped, unmet condition check ConditionKernelModuleLoaded=!9pnet_virtio753machine # [ 7.480869] systemd[1]: Starting Load Kernel Module configfs...754machine # [ 7.483298] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm755machine # [ 7.486372] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore756machine # [ 7.489507] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse757machine # [ 7.501050] systemd[1]: Mounting FUSE Control File System...758machine # [ 7.504129] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67759machine # [ 7.542434] systemd[1]: Starting Journal Service...760machine # [ 7.565058] systemd[1]: Starting Load Kernel Modules...761machine # [ 7.585043] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...762machine # [ 7.601698] systemd[1]: Starting Remount Root and Kernel File Systems...763machine # [ 7.614374] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os764machine # [ 7.620831] systemd-journald[286]: Collecting audit messages is enabled.765machine # [ 7.627598] systemd[1]: Starting Coldplug All udev Devices...766machine # [ 7.656771] systemd[1]: Listening on Journal Log Access Socket.767machine # [ 7.668529] systemd[1]: Mounted Huge Pages File System.768machine # [ 7.674151] loop: module loaded769machine # [ 7.416525] systemd[1]: Queued start job for default target Multi-User System.770machine # [ 7.679276] systemd[1]: Started Journal Service.771machine # [ 7.423722] systemd[1]: systemd-journald.service: Deactivated successfully.772machine # [ 7.688965] EXT4-fs (vda): re-mounted 7e9f6291-572d-414b-a6d4-b7e765737475.773machine # [ 7.434109] systemd-modules-load[287]: Inserted module 'loop'774machine # [ 7.436476] systemd[1]: Mounted POSIX Message Queue File System.775machine # [ 7.440732] systemd[1]: Mounted Kernel Debug File System.776machine # [ 7.445830] systemd[1]: Mounted Kernel Trace File System.777machine # [ 7.450622] systemd[1]: Finished Create List of Static Device Nodes.778machine # [ 7.453894] systemd[1]: modprobe@configfs.service: Deactivated successfully.779machine # [ 7.457502] systemd[1]: Finished Load Kernel Module configfs.780machine # [ 7.461550] systemd[1]: Mounted FUSE Control File System.781machine # [ 7.465345] systemd[1]: Finished Load Kernel Modules.782machine # [ 7.474121] systemd[1]: Finished Remount Root and Kernel File Systems.783machine # [ 7.495109] systemd[1]: Listening on Disk Image Download Service Socket.784machine # [ 7.503104] systemd[1]: Mounting Kernel Configuration File System...785machine # [ 7.509452] systemd[1]: Starting Firewall...786machine # [ 7.520299] systemd-oomd[289]: No swap; memory pressure usage will be degraded787machine # [ 7.528165] systemd[1]: Starting Flush Journal to Persistent Storage...788machine # [ 7.530118] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore789machine # [ 7.547343] systemd[1]: Starting Load/Save OS Random Seed...790machine # [ 7.563457] systemd[1]: Starting Apply Kernel Variables...791machine # [ 7.577572] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...792machine # [ 7.579539] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os793machine # [ 7.592744] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.794machine # [ 7.872053] systemd-journald[286]: Received client request to flush runtime journal.795machine # [ 7.879577] systemd[1]: Mounted Kernel Configuration File System.796machine # [ 7.883931] systemd[1]: Finished Load/Save OS Random Seed.797machine # [ 7.889269] systemd[1]: Reached target First Boot Complete.798machine # [ 7.892297] systemd[1]: Finished Apply Kernel Variables.799machine # [ 7.895305] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.800machine # [ 7.898457] systemd[1]: Starting Create Static Device Nodes in /dev...801machine # [ 7.900451] systemd[1]: Finished Flush Journal to Persistent Storage.802machine # [ 7.903245] systemd[1]: Finished Create Static Device Nodes in /dev.803machine # [ 7.909909] systemd[1]: Reached target Preparation for Local File Systems.804machine # [ 7.917105] systemd[1]: Starting Rule-based Manager for Device Events and Files...805machine # [ 8.018126] systemd-udevd[328]: Using default interface naming scheme 'v261'.806machine # [ 8.025309] systemd[1]: Finished Coldplug All udev Devices.807machine # [ 8.080827] systemd[1]: Mounting /run/wrappers...808machine # [ 8.131309] systemd[1]: Mounted /run/wrappers.809machine # [ 8.133363] systemd[1]: Reached target Local File Systems.810machine # [ 8.138319] systemd[1]: Listening on Boot Loader Control Service Socket.811machine # [ 8.143294] systemd[1]: Starting Create SUID/SGID Wrappers...812machine # [ 8.145569] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.813machine # [ 8.153533] systemd[1]: Starting Save Transient machine-id to Disk...814machine # [ 8.162279] systemd[1]: Starting Create System Files and Directories...815machine # [ 8.192494] systemd[1]: Started Rule-based Manager for Device Events and Files.816machine # [ 8.228164] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.817machine # [ 8.237643] systemd[1]: Finished Save Transient machine-id to Disk.818machine # [ 8.301682] systemd[1]: Finished Create System Files and Directories.819machine # [ 8.313668] systemd[1]: Starting Rebuild Journal Catalog...820machine # [ 8.321590] systemd[1]: Starting Record System Boot/Shutdown in UTMP...821machine # [ 8.402823] systemd[1]: Finished Record System Boot/Shutdown in UTMP.822machine # [ 8.447728] systemd[1]: Finished Rebuild Journal Catalog.823machine # [ 8.457318] systemd[1]: Starting Update is Completed...824machine # [ 8.512964] systemd[1]: Finished Update is Completed.825machine # [ 8.611968] systemd[1]: Load Kernel Module fuse skipped, unmet condition check ConditionKernelModuleLoaded=!fuse826machine # [ 8.721154] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.827machine # [ 8.725121] systemd[1]: Finished Create SUID/SGID Wrappers.828machine # [ 8.729279] systemd[1]: Reached target System Initialization.829machine # [ 8.731959] systemd[1]: Started Discard unused filesystem blocks once a week.830machine # [ 8.734762] systemd[1]: Started Daily Cleanup of Temporary Directories.831machine # [ 8.738251] systemd[1]: Reached target Timer Units.832machine # [ 8.741071] systemd[1]: Listening on D-Bus System Message Bus Socket.833machine # [ 8.744234] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.834machine # [ 8.746652] systemd[1]: Reached target Socket Units.835machine # [ 8.748790] systemd[1]: Reached target Basic System.836machine # [ 8.753205] systemd[1]: Starting Import lastlog data into lastlog2 database...837machine # [ 8.761694] systemd[1]: Starting Name Service Cache Daemon (nsncd)...838machine # [ 8.775507] systemd[1]: Starting Post-Boot Actions...839machine # [ 8.795944] systemd[1]: Started Reset console on configuration changes.840machine # [ 8.816443] systemd[1]: Starting resolvconf update...841machine # [ 8.875300] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.842machine # [ 8.902397] systemd[1]: Finished Post-Boot Actions.843machine # [ 8.911562] systemd[1]: Started Name Service Cache Daemon (nsncd).844machine # [ 8.915402] nsncd[456]: Sep 10 02:48:03.622 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"845machine # [ 8.936663] systemd[1]: Condition check resulted in /dev/ttyS0 being skipped.846machine # [ 8.941247] systemd[1]: Reached target Host and Network Name Lookups.847machine # [ 8.944239] systemd[1]: Reached target User and Group Name Lookups.848machine # [ 8.952974] systemd[1]: Started backdoor.service.849machine # [ 8.974574] systemd[1]: Starting D-Bus System Message Bus...850machine # [ 9.003494] systemd[1]: Starting User Login Management...851machine # [ 9.013056] systemd[1]: Finished Import lastlog data into lastlog2 database.852machine # [ 9.029984] (udev-worker)[414]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.853machine # [ 9.044928] (udev-worker)[414]: Network interface NamePolicy= disabled on kernel command line.854machine # [ 9.047492] (udev-worker)[407]: Network interface NamePolicy= disabled on kernel command line.855machine # connecting to host...856machine: Guest shell says: b'Spawning backdoor root shell...\n'857machine: connected to guest root shell858machine: (connecting took 10.21 seconds)859machine: (finished: waiting for the VM to finish booting, in 10.48 seconds)860machine # [ 9.190411] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...861machine # [ 9.213249] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'862machine # [ 9.216666] dbus-broker-launch[488]: Invalid user-name in /nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"863machine # [ 9.232552] systemd[1]: Stopped target Host and Network Name Lookups.864machine # [ 9.236275] systemd[1]: Stopping Host and Network Name Lookups...865machine # [ 9.241972] systemd[1]: Stopped target User and Group Name Lookups.866machine # [ 9.244658] systemd[1]: Stopping User and Group Name Lookups...867machine # [ 9.248477] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...868machine # [ 9.251672] systemd[1]: nscd.service: Deactivated successfully.869machine # [ 9.254895] systemd[1]: Stopped Name Service Cache Daemon (nsncd).870machine # [ 9.274830] systemd-logind[493]: New seat seat0.871machine # [ 9.277482] systemd[1]: Started D-Bus System Message Bus.872machine # [ 9.284445] systemd[1]: Started User Login Management.873machine # [ 9.302265] systemd[1]: Starting linger-users.service...874machine # [ 9.322116] systemd[1]: Starting Name Service Cache Daemon (nsncd)...875machine # [ 9.337422] dbus-broker-launch[488]: Ready876machine # [ 9.422517] nsncd[569]: Sep 10 02:48:04.134 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"877machine # [ 9.452783] systemd[1]: Started Name Service Cache Daemon (nsncd).878machine # [ 9.458937] systemd[1]: Finished Firewall.879machine # [ 9.463587] systemd[1]: Finished resolvconf update.880machine # [ 9.478366] systemd[1]: Condition check resulted in Virtio network device being skipped.881machine # [ 9.494741] systemd[1]: Reached target Preparation for Network.882machine # [ 9.498726] systemd[1]: Reached target Host and Network Name Lookups.883machine # [ 9.500392] systemd[1]: Reached target User and Group Name Lookups.884machine # [ 9.508188] systemd[1]: Starting DHCP Client...885machine # [ 9.518104] systemd[1]: Starting Address configuration of eth1...886machine # [ 9.534115] systemd[1]: Starting Extra networking commands....887machine # [ 9.625247] systemd[1]: Created slice Slice /user/1001.888machine # [ 9.630116] systemd[1]: Starting User Runtime Directory /run/user/1001...889machine # [ 9.636979] systemd[1]: linger-users.service: Deactivated successfully.890machine # [ 9.659701] systemd[1]: Finished linger-users.service.891machine # [ 9.970021] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3892machine # [ 9.977831] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console893machine # [ 9.992977] mousedev: PS/2 mouse device common for all mice894machine # [ 9.759364] systemd[1]: Finished User Runtime Directory /run/user/1001.895machine # [ 9.761369] systemd[1]: Starting User Manager for UID 1001...896machine # [ 9.844295] network-addresses-eth1-start[603]: adding address 192.168.1.1/24... done897machine # [ 10.029142] ACPI: button: Power Button [PWRF]898machine # [ 10.116087] rtc_cmos 00:04: RTC can wake from S4899machine # [ 10.140351] rtc_cmos 00:04: registered as rtc0900machine # [ 10.140478] rtc_cmos 00:04: setting system clock to 2026-09-10T02:48:04 UTC (1789008484)901machine # [ 10.140596] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs902machine # [ 10.141469] systemd-journald[286]: Time jumped backwards, rotating.903machine # [ 10.163466] parport_pc 00:02: reported by Plug and Play ACPI904machine # [ 10.163599] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]905machine # [ 10.166784] input: QEMU Virtio Keyboard as /devices/pci0000:00/0000:00:09.0/virtio7/input/input4906machine # [ 10.082951] network-addresses-eth1-start[603]: adding address 2001:db8:1::1/64... done907machine # [ 10.105453] (systemd)[616]: pam_unix(systemd-user:session): session opened for user usertest(uid=1001) by (uid=0)908machine # [ 10.119421] systemd-logind[493]: New session '1' of user 'usertest' with class 'manager' and type 'unspecified'.909machine # [ 10.180686] Console: switching to colour dummy device 80x25910machine # [ 10.245418] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized911machine # [ 10.156934] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy912machine # [ 10.405966] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt913machine # [ 10.406110] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD914machine # [ 10.468045] [drm] Found bochs VGA, ID 0xb0c5.915machine # [ 10.468883] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.916machine # [ 10.500577] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input6917machine # [ 10.502695] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input5918machine # [ 10.514683] bochs-drm 0000:00:01.0: [drm] Registered 1 planes with drm panic919machine # [ 10.531685] [drm] Initialized bochs-drm 1.0.0 for 0000:00:01.0 on minor 0920machine # [ 10.465994] systemd[1]: Finished Address configuration of eth1.921machine # [ 10.486742] systemd[616]: Queued start job for default target Main User Target.922machine # [ 10.513192] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy923machine # [ 10.782352] Console: switching to colour frame buffer device 160x50924machine # [ 10.531161] systemd[616]: Created slice User Application Slice.925machine # [ 10.544199] systemd[616]: Started Daily Cleanup of User's Temporary Directories.926machine # [ 10.547838] systemd[616]: Reached target Paths.927machine # [ 10.814406] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device928machine # [ 10.576928] systemd[616]: Reached target Timers.929machine # [ 10.589349] systemd[616]: Starting D-Bus User Message Bus Socket...930machine # [ 10.591824] systemd[616]: Starting Create User Files and Directories...931machine # [ 10.609905] systemd[616]: Finished Create User Files and Directories.932machine # [ 10.873243] ppdev: user-space parallel port driver933machine # [ 10.619208] systemd[616]: Listening on D-Bus User Message Bus Socket.934machine # [ 10.624195] systemd[616]: Reached target Sockets.935machine # [ 10.636519] systemd[616]: Reached target Basic System.936machine # [ 10.638361] systemd[616]: Starting Run user-specific NixOS activation...937machine # [ 10.654657] systemd[1]: Starting Virtual Console Setup...938machine # [ 10.673302] systemd[1]: Started User Manager for UID 1001.939machine # [ 10.936635] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660)940machine # [ 10.684700] dhcpcd[623]: dhcpcd-10.3.2 starting941machine # [ 10.699912] systemd[616]: Finished Run user-specific NixOS activation.942machine # [ 10.716222] systemd[616]: Reached target Main User Target.943machine # [ 10.719629] systemd[616]: Startup finished in 471ms.944machine # [ 10.983624] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=0)945machine # [ 10.736824] systemd-logind[493]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)946machine # [ 10.758483] dhcpcd[700]: dev: loaded udev947machine # [ 10.765932] systemd-logind[493]: Watching system buttons on /dev/input/event2 (Power Button)948machine # [ 10.772720] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.949machine # [ 10.781232] systemd[1]: Stopped Virtual Console Setup.950machine # [ 10.783611] systemd[1]: Starting Virtual Console Setup...951machine # [ 11.053594] 8021q: 802.1Q VLAN Support v1.8952machine # [ 10.796127] systemd-logind[493]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)953machine # [ 10.798443] systemd[1]: Finished Extra networking commands..954machine # [ 11.059956] 8021q: adding VLAN 0 to HW filter on device eth1955machine # [ 10.803242] systemd[1]: Reached target Network.956machine # [ 10.808556] systemd[1]: Starting Permit User Sessions...957machine # [ 10.813496] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.958machine # [ 10.816568] systemd[1]: Stopped Virtual Console Setup.959machine # [ 10.819470] systemd[1]: Starting Virtual Console Setup...960machine # [ 10.869477] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.961machine # [ 10.908649] systemd[1]: Finished Permit User Sessions.962machine # [ 10.947398] systemd[1]: Started Getty on tty1.963machine # [ 10.949077] systemd[1]: Reached target Login Prompts.964machine # [ 11.248797] cfg80211: Loading compiled-in X.509 certificates for regulatory database965machine # [ 11.264286] kvm_amd: TSC scaling supported966machine # [ 11.265300] kvm_amd: Nested Virtualization enabled967machine # [ 11.266648] kvm_amd: Nested Paging enabled968machine # [ 11.268042] kvm_amd: LBR virtualization supported969machine # [ 11.269239] kvm_amd: Virtual VMLOAD VMSAVE supported970machine # [ 11.270593] kvm_amd: Virtual GIF supported971machine # [ 11.271695] kvm_amd: Virtual NMI enabled972machine # [ 11.292387] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'973machine # [ 11.295209] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'974machine # [ 11.300968] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2975machine # [ 11.302572] cfg80211: failed to load regulatory.db976machine # [ 11.346026] EDAC MC: Ver: 3.0.0977machine # [ 11.384317] 8021q: adding VLAN 0 to HW filter on device eth0978machine # [ 11.127544] dhcpcd[700]: eth0: waiting for carrier979machine # [ 11.129620] dhcpcd[700]: eth0: carrier acquired980machine # [ 11.157115] dhcpcd[700]: DUID 00:01:00:01:32:34:d6:e5:52:54:00:12:34:56981machine # [ 11.158967] dhcpcd[700]: eth0: IAID 00:12:34:56982machine # [ 11.160711] dhcpcd[700]: eth0: adding address fe80::5054:ff:fe12:3456983machine # [ 11.469341] dhcpcd[700]: eth0: soliciting a DHCP lease984machine # [ 11.747742] NET: Registered PF_PACKET protocol family985machine # [ 11.494888] dhcpcd[700]: eth0: offered 10.0.2.15 from 10.0.2.2986machine # [ 11.498258] dhcpcd[700]: eth0: probing address 10.0.2.15/24987machine # [ 11.508467] systemd-vconsole-setup[704]: Configuration of first virtual console was skipped, ignoring remaining ones.988machine # [ 11.516474] systemd[1]: Finished Virtual Console Setup.989machine # [ 12.738877] dhcpcd[700]: eth0: soliciting an IPv6 router990machine # [ 12.743676] dhcpcd[700]: eth0: Router Advertisement from fe80::2991machine # [ 12.747721] dhcpcd[700]: eth0: adding address fec0::5054:ff:fe12:3456/64992machine # [ 12.752151] dhcpcd[700]: eth0: adding route to fec0::/64993machine # [ 12.756246] dhcpcd[700]: eth0: adding default route via fe80::2994machine # [ 16.903492] dhcpcd[700]: eth0: leased 10.0.2.15 for 86400 seconds995machine # [ 16.907344] dhcpcd[700]: eth0: adding route to 10.0.2.0/24996machine # [ 16.911885] dhcpcd[700]: eth0: adding default route via 10.0.2.2997machine # [ 17.039925] systemd[1]: Started DHCP Client.998machine # [ 17.043128] systemd[1]: Reached target Multi-User System.999machine # [ 17.045159] systemd[1]: Startup finished in 1.095s (kernel) + 4.255s (initrd) + 11.692s (userspace) = 17.042s.1000machine: (finished: waiting for unit multi-user.target, in 18.84 seconds)1001machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test1002machine # Checking switch inhibitors... done1003machine # [ 18.079394] nixos[851]: switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test1004machine # [ 18.083488] systemd[1]: Stopped target Local File Systems.1005machine # [ 18.086170] systemd[1]: Stopped target Remote File Systems.1006machine # activating the configuration...1007machine # setting up /etc...1008machine # [ 18.495759] systemd[1]: Reload requested from client PID 851 ('.switch-to-conf') (unit backdoor.service)...1009machine # [ 18.498944] systemd[1]: Reloading...1010machine # [ 19.048791] systemd[1]: Reloading finished in 546 ms.1011machine # reloading user units for usertest...1012machine # [ 19.091225] systemd[616]: Created slice User Core Session Slice.1013machine # [ 19.094979] systemd[616]: Starting D-Bus User Message Bus...1014machine # [ 19.149533] dbus-broker-launch[922]: Ignoring duplicate name 'org.freedesktop.import1' in service file '/nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/services/org.freedesktop.import1.service'1015machine # [ 19.154455] dbus-broker-launch[922]: Ignoring duplicate name 'org.freedesktop.machine1' in service file '/nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/services/org.freedesktop.machine1.service'1016machine # [ 19.159289] dbus-broker-launch[922]: Ignoring duplicate name 'org.freedesktop.portable1' in service file '/nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/services/org.freedesktop.portable1.service'1017machine # [ 19.164601] dbus-broker-launch[922]: Ignoring duplicate name 'org.freedesktop.systemd1' in service file '/nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/services/org.freedesktop.systemd1.service'1018machine # [ 19.169894] dbus-broker-launch[922]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +27: Eavesdropping is deprecated and ignored1019machine # [ 19.172734] dbus-broker-launch[922]: Policy to allow eavesdropping in /etc/dbus-1/session.conf +29: Eavesdropping is deprecated and ignored1020machine # [ 19.176229] systemd[616]: Started D-Bus User Message Bus.1021machine # [ 19.186894] dbus-broker-launch[922]: Ready1022machine # [ 19.301830] systemd[616]: Reexecution requested from client PID 921 ('switch-to-confi')...1023machine # [ 19.305632] systemd[616]: Reexecuting.1024machine # [ 19.331351] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1025machine # [ 19.497276] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1026machine # [ 19.504807] systemd[616]: Reload requested from client PID 921 ('switch-to-confi')...1027machine # [ 19.507206] systemd[616]: Reloading...1028machine # [ 19.672770] systemd[616]: Reloading finished in 163 ms.1029machine # [ 19.678045] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1030machine # restarting the following user units: nixos-activation.service1031machine # [ 19.690718] systemd[616]: Stopped Run user-specific NixOS activation.1032machine # [ 19.692391] systemd[616]: Stopping Run user-specific NixOS activation...1033machine # [ 19.696885] systemd[616]: Starting Run user-specific NixOS activation...1034machine # [ 19.738756] systemd[616]: Finished Run user-specific NixOS activation.1035machine # restarting sysinit-reactivation.target1036machine # [ 19.756973] systemd[1]: Starting Re-setup tmpfiles on a system that is already running....1037machine # [ 19.894627] systemd[1]: Finished Re-setup tmpfiles on a system that is already running..1038machine # [ 19.898203] systemd[1]: Reached target Reactivate sysinit units.1039machine # [ 19.904619] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1040machine # [ 19.907713] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1041machine # [ 19.912371] systemd[1]: Reached target Remote File Systems.1042machine # [ 19.917129] systemd[1]: Starting linger-users.service...1043machine # [ 19.922194] systemd[1]: Reached target Local File Systems.1044machine # [ 19.926564] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1045machine # [ 19.931221] systemd[1]: Starting Create SUID/SGID Wrappers...1046machine # [ 19.935632] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1047machine # [ 19.938453] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671048machine # [ 19.945216] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1049machine # [ 19.948862] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1050machine # [ 19.953253] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1051machine # [ 20.023242] systemd[1]: linger-users.service: Deactivated successfully.1052machine # [ 20.026184] systemd[1]: Finished linger-users.service.1053machine # [ 20.308404] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1054machine # [ 20.311325] systemd[1]: Finished Create SUID/SGID Wrappers.1055machine # the following new units were started: sysinit-reactivation.target, systemd-tmpfiles-resetup.service1056machine # [ 20.579274] nixos[851]: finished switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test1057machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test, in 3.07 seconds)1058machine: must succeed: rm /etc/NIXOS1059machine: (finished: must succeed: rm /etc/NIXOS, in 0.04 seconds)1060machine: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/nqml7hkiv7zplnpzfpdb4wfqrpx8rghh-nixos-system-other-test/bin/switch-to-configuration test1061machine # this should succeed (config: /nix/store/nqml7hkiv7zplnpzfpdb4wfqrpx8rghh-nixos-system-other-test, action: test)1062machine # Checking switch inhibitors... done1063machine # [ 21.283480] nixos[999]: switching to system configuration /nix/store/nqml7hkiv7zplnpzfpdb4wfqrpx8rghh-nixos-system-other-test1064machine # [ 21.287366] systemd[1]: Stopped target Remote File Systems.1065machine # [ 21.290368] systemd[1]: Stopped target Local File Systems.1066machine # activating the configuration...1067machine # removing user ‘usertest’1068machine # setting up /etc...1069machine # [ 21.776584] systemd[1]: Reload requested from client PID 999 ('.switch-to-conf') (unit backdoor.service)...1070machine # [ 21.779329] systemd[1]: Reloading...1071machine # [ 22.403396] systemd[1]: Reloading finished in 621 ms.1072machine # reloading user units for usertest...1073machine # [ 22.561328] systemd[616]: Reexecution requested from client PID 1071 ('switch-to-confi')...1074machine # [ 22.565522] systemd[616]: Reexecuting.1075machine # [ 22.591464] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1076machine # [ 22.769053] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1077machine # [ 22.778684] systemd[616]: Reload requested from client PID 1071 ('switch-to-confi')...1078machine # [ 22.781190] systemd[616]: Reloading...1079machine # [ 22.946197] systemd[616]: Reloading finished in 161 ms.1080machine # [ 22.950047] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1081machine # restarting the following user units: nixos-activation.service1082machine # [ 22.962434] systemd[616]: Stopped Run user-specific NixOS activation.1083machine # [ 22.965243] systemd[616]: Stopping Run user-specific NixOS activation...1084machine # [ 22.969312] systemd[616]: Starting Run user-specific NixOS activation...1085machine # [ 23.010290] systemd[616]: Finished Run user-specific NixOS activation.1086machine # restarting sysinit-reactivation.target1087machine # [ 23.016123] systemd[1]: Stopped target Reactivate sysinit units.1088machine # [ 23.018297] systemd[1]: Stopping Reactivate sysinit units...1089machine # reloading the following units: dbus-broker.service1090machine # [ 23.021994] systemd[1]: Reached target Reactivate sysinit units.1091machine # [ 23.025322] dbus-broker-launch[488]: Caught SIGHUP, trigger reload.1092machine # [ 23.028382] systemd[1]: Reloading D-Bus System Message Bus...1093machine # [ 23.050684] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...1094machine # [ 23.054181] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'1095machine # [ 23.056109] dbus-broker-launch[488]: Invalid user-name in /nix/store/f26s767ff0463vx0aawl75y7l18cmp69-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1096machine # [ 23.077790] systemd[1]: Reloaded D-Bus System Message Bus.1097machine # restarting the following units: network-addresses-eth1.service1098machine # [ 23.086178] systemd[1]: Stopping Address configuration of eth1...1099machine # [ 23.125644] network-addresses-eth1-pre-stop[1083]: deleting address 192.168.1.1/24... done1100machine # [ 23.136308] network-addresses-eth1-pre-stop[1083]: deleting address 2001:db8:1::1/64... done1101machine # [ 23.145186] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1102machine # [ 23.147990] systemd[1]: Stopped Address configuration of eth1.1103machine # [ 23.153432] systemd[1]: Starting Address configuration of eth1...1104machine # [ 23.212299] network-addresses-eth1-start[1088]: adding address 192.168.1.2/24... done1105machine # [ 23.223947] network-addresses-eth1-start[1088]: adding address 2001:db8:1::2/64... done1106machine # [ 23.237620] systemd[1]: Finished Address configuration of eth1.1107machine # [ 23.243767] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1108machine # [ 23.246110] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1109machine # [ 23.254299] systemd[1]: Reached target Remote File Systems.1110machine # [ 23.259356] systemd[1]: Starting linger-users.service...1111machine # [ 23.269233] systemd[1]: Reached target Local File Systems.1112machine # [ 23.272344] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1113machine # [ 23.278572] systemd[1]: Starting Create SUID/SGID Wrappers...1114machine # [ 23.281663] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1115machine # [ 23.286209] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671116machine # [ 23.290669] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1117machine # [ 23.295937] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1118machine # [ 23.299345] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1119machine # [ 23.338824] linger-users-start[1102]: id: ‘usertest’: no such user1120machine # [ 23.341700] linger-users-start[1099]: Removing linger for missing user usertest1121machine # [ 23.352528] systemd[1]: linger-users.service: Deactivated successfully.1122machine # [ 23.355411] systemd[1]: Finished linger-users.service.1123machine # [ 23.695260] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1124machine # [ 23.698147] systemd[1]: Finished Create SUID/SGID Wrappers.1125machine # [ 23.954724] nixos[999]: finished switching to system configuration /nix/store/nqml7hkiv7zplnpzfpdb4wfqrpx8rghh-nixos-system-other-test1126machine: (finished: must succeed: /nix/store/w1psanxc52jqfm0knzrj6gw0pz8my68n-stderr-runner /nix/store/nqml7hkiv7zplnpzfpdb4wfqrpx8rghh-nixos-system-other-test/bin/switch-to-configuration test, in 3.33 seconds)1127machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&11128machine # [ 24.563464] nixos[1167]: switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test1129machine # [ 24.567608] systemd[1]: Stopped target Local File Systems.1130machine # [ 24.569738] systemd[1]: Stopped target Remote File Systems.1131machine # [ 24.964287] systemd[1]: Reload requested from client PID 1167 ('.switch-to-conf') (unit backdoor.service)...1132machine # [ 24.967178] systemd[1]: Reloading...1133machine # [ 25.545405] systemd[1]: Reloading finished in 576 ms.1134machine # [ 25.688850] systemd[616]: Reexecution requested from client PID 1236 ('switch-to-confi')...1135machine # [ 25.693150] systemd[616]: Reexecuting.1136machine # [ 25.719908] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1137machine # [ 25.889382] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1138machine # [ 25.897857] systemd[616]: Reload requested from client PID 1236 ('switch-to-confi')...1139machine # [ 25.900199] systemd[616]: Reloading...1140machine # [ 26.059636] systemd[616]: Reloading finished in 156 ms.1141machine # [ 26.064041] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1142machine # [ 26.075636] systemd[616]: Stopped Run user-specific NixOS activation.1143machine # [ 26.077317] systemd[616]: Stopping Run user-specific NixOS activation...1144machine # [ 26.081054] systemd[616]: Starting Run user-specific NixOS activation...1145machine # [ 26.115540] systemd[616]: Finished Run user-specific NixOS activation.1146machine # [ 26.120409] systemd[1]: Stopped target Reactivate sysinit units.1147machine # [ 26.122968] systemd[1]: Stopping Reactivate sysinit units...1148machine # [ 26.124866] systemd[1]: Reached target Reactivate sysinit units.1149machine # [ 26.127430] dbus-broker-launch[488]: Caught SIGHUP, trigger reload.1150machine # [ 26.130731] systemd[1]: Reloading D-Bus System Message Bus...1151machine # [ 26.151141] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...1152machine # [ 26.154543] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'1153machine # [ 26.156449] dbus-broker-launch[488]: Invalid user-name in /nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"1154machine # [ 26.182068] systemd[1]: Reloaded D-Bus System Message Bus.1155machine # [ 26.189104] systemd[1]: Stopping Address configuration of eth1...1156machine # [ 26.234395] network-addresses-eth1-pre-stop[1248]: deleting address 192.168.1.2/24... done1157machine # [ 26.247294] network-addresses-eth1-pre-stop[1248]: deleting address 2001:db8:1::2/64... done1158machine # [ 26.257363] systemd[1]: network-addresses-eth1.service: Deactivated successfully.1159machine # [ 26.260237] systemd[1]: Stopped Address configuration of eth1.1160machine # [ 26.266163] systemd[1]: Starting Address configuration of eth1...1161machine # [ 26.335388] network-addresses-eth1-start[1253]: adding address 192.168.1.1/24... done1162machine # [ 26.348510] network-addresses-eth1-start[1253]: adding address 2001:db8:1::1/64... done1163machine # [ 26.370589] systemd[1]: Finished Address configuration of eth1.1164machine # [ 26.375753] systemd[1]: Reached target Local File Systems.1165machine # [ 26.379444] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1166machine # [ 26.382285] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1167machine # [ 26.387933] systemd[1]: Reached target Remote File Systems.1168machine # [ 26.391227] systemd[1]: Starting linger-users.service...1169machine # [ 26.396930] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1170machine # [ 26.405270] systemd[1]: Starting Create SUID/SGID Wrappers...1171machine # [ 26.408730] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1172machine # [ 26.412602] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671173machine # [ 26.418184] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1174machine # [ 26.421341] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1175machine # [ 26.425499] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1176machine # [ 26.476573] systemd[1]: linger-users.service: Deactivated successfully.1177machine # [ 26.479373] systemd[1]: Finished linger-users.service.1178machine # [ 26.757613] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1179machine # [ 26.760528] systemd[1]: Finished Create SUID/SGID Wrappers.1180machine # [ 27.021811] nixos[1167]: finished switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test1181machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 3.07 seconds)1182subtest: socket-activated services1183machine: must fail: [ -S /run/test.sock ]1184machine: (finished: must fail: [ -S /run/test.sock ], in 0.04 seconds)1185machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11186machine # [ 27.706287] nixos[1325]: switching to system configuration /nix/store/0m17ywdxp65p465vg72msixbl4yvwp9s-nixos-system-machine-test1187machine # [ 27.710482] systemd[1]: Stopped target Local File Systems.1188machine # [ 27.713171] systemd[1]: Stopped target Remote File Systems.1189machine # [ 28.217604] systemd[1]: Reload requested from client PID 1325 ('.switch-to-conf') (unit backdoor.service)...1190machine # [ 28.220548] systemd[1]: Reloading...1191machine # [ 28.870397] systemd[1]: Reloading finished in 647 ms.1192machine # [ 29.018934] systemd[616]: Reexecution requested from client PID 1395 ('switch-to-confi')...1193machine # [ 29.023198] systemd[616]: Reexecuting.1194machine # [ 29.052620] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1195machine # [ 29.217252] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1196machine # [ 29.225617] systemd[616]: Reload requested from client PID 1395 ('switch-to-confi')...1197machine # [ 29.227885] systemd[616]: Reloading...1198machine # [ 29.377204] systemd[616]: Reloading finished in 146 ms.1199machine # [ 29.382986] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1200machine # [ 29.393964] systemd[616]: Stopped Run user-specific NixOS activation.1201machine # [ 29.395812] systemd[616]: Stopping Run user-specific NixOS activation...1202machine # [ 29.400565] systemd[616]: Starting Run user-specific NixOS activation...1203machine # [ 29.440768] systemd[616]: Finished Run user-specific NixOS activation.1204machine # [ 29.445470] systemd[1]: Stopped target Reactivate sysinit units.1205machine # [ 29.448124] systemd[1]: Stopping Reactivate sysinit units...1206machine # [ 29.450073] systemd[1]: Reached target Reactivate sysinit units.1207machine # [ 29.453433] systemd[1]: Reached target Remote File Systems.1208machine # [ 29.461607] systemd[1]: Reached target Local File Systems.1209machine # [ 29.466095] systemd[1]: Listening on socket-activated.socket.1210machine # [ 29.468657] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1211machine # [ 29.472217] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1212machine # [ 29.477690] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1213machine # [ 29.485275] systemd[1]: Starting Create SUID/SGID Wrappers...1214machine # [ 29.487874] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1215machine # [ 29.491245] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671216machine # [ 29.495503] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1217machine # [ 29.499503] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1218machine # [ 29.503186] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1219machine # [ 29.840776] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1220machine # [ 29.843604] systemd[1]: Finished Create SUID/SGID Wrappers.1221machine # [ 29.849933] systemd[1]: Starting linger-users.service...1222machine # [ 29.906238] systemd[1]: linger-users.service: Deactivated successfully.1223machine # [ 29.908160] systemd[1]: Finished linger-users.service.1224machine # [ 30.168671] nixos[1325]: finished switching to system configuration /nix/store/0m17ywdxp65p465vg72msixbl4yvwp9s-nixos-system-machine-test1225machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 3.11 seconds)1226machine: must succeed: [ -S /run/test.sock ]1227machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1228machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&11229machine # [ 30.920868] nixos[1469]: switching to system configuration /nix/store/2sdcn7skd0y17nnikgpabv7ivg55s0j2-nixos-system-machine-test1230machine # [ 30.926344] systemd[1]: Stopped target Remote File Systems.1231machine # [ 30.928205] systemd[1]: Stopped target Local File Systems.1232machine # [ 31.411860] systemd[1]: Reload requested from client PID 1469 ('.switch-to-conf') (unit backdoor.service)...1233machine # [ 31.414809] systemd[1]: Reloading...1234machine # [ 32.027209] systemd[1]: Reloading finished in 609 ms.1235machine # [ 32.175572] systemd[616]: Reexecution requested from client PID 1539 ('switch-to-confi')...1236machine # [ 32.179771] systemd[616]: Reexecuting.1237machine # [ 32.205254] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1238machine # [ 32.373549] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1239machine # [ 32.381925] systemd[616]: Reload requested from client PID 1539 ('switch-to-confi')...1240machine # [ 32.385079] systemd[616]: Reloading...1241machine # [ 32.556374] systemd[616]: Reloading finished in 169 ms.1242machine # [ 32.563042] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1243machine # [ 32.573616] systemd[616]: Stopped Run user-specific NixOS activation.1244machine # [ 32.576899] systemd[616]: Stopping Run user-specific NixOS activation...1245machine # [ 32.580765] systemd[616]: Starting Run user-specific NixOS activation...1246machine # [ 32.624627] systemd[616]: Finished Run user-specific NixOS activation.1247machine # [ 32.629560] systemd[1]: Stopped target Reactivate sysinit units.1248machine # [ 32.631804] systemd[1]: Stopping Reactivate sysinit units...1249machine # [ 32.633569] systemd[1]: Reached target Reactivate sysinit units.1250machine # [ 32.640432] systemd[1]: Reached target Remote File Systems.1251machine # [ 32.645257] systemd[1]: Starting linger-users.service...1252machine # [ 32.653195] systemd[1]: Reached target Local File Systems.1253machine # [ 32.657672] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1254machine # [ 32.661355] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1255machine # [ 32.667345] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1256machine # [ 32.674580] systemd[1]: Starting Create SUID/SGID Wrappers...1257machine # [ 32.677894] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1258machine # [ 32.680435] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671259machine # [ 32.686705] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1260machine # [ 32.691201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1261machine # [ 32.694184] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1262machine # [ 32.751424] systemd[1]: linger-users.service: Deactivated successfully.1263machine # [ 32.754184] systemd[1]: Finished linger-users.service.1264machine # [ 33.030782] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1265machine # [ 33.033660] systemd[1]: Finished Create SUID/SGID Wrappers.1266machine # [ 33.293463] nixos[1469]: finished switching to system configuration /nix/store/2sdcn7skd0y17nnikgpabv7ivg55s0j2-nixos-system-machine-test1267machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-service-modified/bin/switch-to-configuration test 2>&1, in 3.10 seconds)1268machine: must succeed: [ -S /run/test.sock ]1269machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1270machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1271machine # [ 33.385205] systemd[1]: Started A socket-activated service.1272machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.78 seconds)1273machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&11274machine # [ 34.724652] nixos[1618]: switching to system configuration /nix/store/0m17ywdxp65p465vg72msixbl4yvwp9s-nixos-system-machine-test1275machine # [ 34.728867] systemd[1]: Stopped target Remote File Systems.1276machine # [ 34.731866] systemd[1]: Stopped target Local File Systems.1277machine # [ 35.164765] systemd[1]: Reload requested from client PID 1618 ('.switch-to-conf') (unit backdoor.service)...1278machine # [ 35.167699] systemd[1]: Reloading...1279machine # [ 35.750694] systemd[1]: Reloading finished in 580 ms.1280machine # [ 35.907375] systemd[616]: Reexecution requested from client PID 1688 ('switch-to-confi')...1281machine # [ 35.911144] systemd[616]: Reexecuting.1282machine # [ 35.941616] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1283machine # [ 36.107977] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1284machine # [ 36.117767] systemd[616]: Reload requested from client PID 1688 ('switch-to-confi')...1285machine # [ 36.120182] systemd[616]: Reloading...1286machine # [ 36.288863] systemd[616]: Reloading finished in 166 ms.1287machine # [ 36.295091] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1288machine # [ 36.306692] systemd[616]: Stopped Run user-specific NixOS activation.1289machine # [ 36.308396] systemd[616]: Stopping Run user-specific NixOS activation...1290machine # [ 36.312474] systemd[616]: Starting Run user-specific NixOS activation...1291machine # [ 36.354870] systemd[616]: Finished Run user-specific NixOS activation.1292machine # [ 36.359723] systemd[1]: Stopped target Reactivate sysinit units.1293machine # [ 36.361576] systemd[1]: Stopping Reactivate sysinit units...1294machine # [ 36.363818] systemd[1]: Reached target Reactivate sysinit units.1295machine # [ 36.368958] systemd[1]: Stopping A socket-activated service...1296machine # [ 36.372769] systemd[1]: socket-activated.service: Deactivated successfully.1297machine # [ 36.374911] systemd[1]: Stopped A socket-activated service.1298machine # [ 36.381759] systemd[1]: Started A socket-activated service.1299machine # [ 36.391649] systemd[1]: Reached target Local File Systems.1300machine # [ 36.396282] systemd[1]: Reached target Remote File Systems.1301machine # [ 36.400158] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1302machine # [ 36.407339] systemd[1]: Starting Create SUID/SGID Wrappers...1303machine # [ 36.410353] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1304machine # [ 36.412704] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671305machine # [ 36.420280] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1306machine # [ 36.426062] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1307machine # [ 36.428353] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1308machine # [ 36.432930] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1309machine # [ 36.436255] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1310machine # [ 36.821392] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1311machine # [ 36.825712] systemd[1]: Finished Create SUID/SGID Wrappers.1312machine # [ 36.831752] systemd[1]: Starting linger-users.service...1313machine # [ 36.893992] systemd[1]: linger-users.service: Deactivated successfully.1314machine # [ 36.896164] systemd[1]: Finished linger-users.service.1315machine # [ 37.152314] nixos[1618]: finished switching to system configuration /nix/store/0m17ywdxp65p465vg72msixbl4yvwp9s-nixos-system-machine-test1316machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket/bin/switch-to-configuration test 2>&1, in 3.04 seconds)1317machine: must succeed: [ -S /run/test.sock ]1318machine: (finished: must succeed: [ -S /run/test.sock ], in 0.01 seconds)1319machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1320machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.52 seconds)1321machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11322machine # [ 38.330643] nixos[1768]: switching to system configuration /nix/store/75azpcxv17qcgmq5qbby017djmz8iysc-nixos-system-machine-test1323machine # [ 38.334675] systemd[1]: Stopped target Remote File Systems.1324machine # [ 38.338853] systemd[1]: Stopping A socket-activated service...1325machine # [ 38.342319] systemd[1]: Stopped target Local File Systems.1326machine # [ 38.346102] systemd[1]: socket-activated.service: Deactivated successfully.1327machine # [ 38.348871] systemd[1]: Stopped A socket-activated service.1328machine # [ 38.351949] systemd[1]: socket-activated.socket: Deactivated successfully.1329machine # [ 38.354664] systemd[1]: Closed socket-activated.socket.1330machine # [ 38.851451] systemd[1]: Reload requested from client PID 1768 ('.switch-to-conf') (unit backdoor.service)...1331machine # [ 38.854377] systemd[1]: Reloading...1332machine # [ 39.496657] systemd[1]: Reloading finished in 639 ms.1333machine # [ 39.643368] systemd[616]: Reexecution requested from client PID 1838 ('switch-to-confi')...1334machine # [ 39.646749] systemd[616]: Reexecuting.1335machine # [ 39.678654] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1336machine # [ 39.841991] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1337machine # [ 39.851593] systemd[616]: Reload requested from client PID 1838 ('switch-to-confi')...1338machine # [ 39.856172] systemd[616]: Reloading...1339machine # [ 40.051643] systemd[616]: Reloading finished in 195 ms.1340machine # [ 40.056084] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1341machine # [ 40.067613] systemd[616]: Stopped Run user-specific NixOS activation.1342machine # [ 40.069302] systemd[616]: Stopping Run user-specific NixOS activation...1343machine # [ 40.073304] systemd[616]: Starting Run user-specific NixOS activation...1344machine # [ 40.113580] systemd[616]: Finished Run user-specific NixOS activation.1345machine # [ 40.118532] systemd[1]: Stopped target Reactivate sysinit units.1346machine # [ 40.120665] systemd[1]: Stopping Reactivate sysinit units...1347machine # [ 40.122534] systemd[1]: Reached target Reactivate sysinit units.1348machine # [ 40.127098] systemd[1]: Reached target Remote File Systems.1349machine # [ 40.131662] systemd[1]: Starting linger-users.service...1350machine # [ 40.138863] systemd[1]: Reached target Local File Systems.1351machine # [ 40.142608] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1352machine # [ 40.149479] systemd[1]: Starting Create SUID/SGID Wrappers...1353machine # [ 40.151247] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1354machine # [ 40.153482] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671355machine # [ 40.159522] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1356machine # [ 40.163823] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1357machine # [ 40.166107] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1358machine # [ 40.178325] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1359machine # [ 40.181393] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1360machine # [ 40.239976] systemd[1]: linger-users.service: Deactivated successfully.1361machine # [ 40.242288] systemd[1]: Finished linger-users.service.1362machine # [ 40.529961] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1363machine # [ 40.534318] systemd[1]: Finished Create SUID/SGID Wrappers.1364machine # [ 40.537954] systemd[1]: Listening on socket-activated.socket.1365machine # [ 40.793528] nixos[1768]: finished switching to system configuration /nix/store/75azpcxv17qcgmq5qbby017djmz8iysc-nixos-system-machine-test1366machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 3.10 seconds)1367machine: must succeed: [ -S /run/test.sock ]1368machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1369machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1370machine # [ 40.851327] systemd[1]: Started A socket-activated service.1371machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.65 seconds)1372machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&11373machine # [ 42.163701] nixos[1917]: switching to system configuration /nix/store/8k351l2l3x216wfcssmhr9yjik27cqp9-nixos-system-machine-test1374machine # [ 42.167722] systemd[1]: Stopped target Local File Systems.1375machine # [ 42.170613] systemd[1]: Stopped target Remote File Systems.1376machine # [ 42.675476] systemd[1]: Reload requested from client PID 1917 ('.switch-to-conf') (unit backdoor.service)...1377machine # [ 42.678281] systemd[1]: Reloading...1378machine # [ 43.305393] systemd[1]: Reloading finished in 624 ms.1379machine # [ 43.476506] systemd[616]: Reexecution requested from client PID 1987 ('switch-to-confi')...1380machine # [ 43.480495] systemd[616]: Reexecuting.1381machine # [ 43.510457] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1382machine # [ 43.677121] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1383machine # [ 43.684389] systemd[616]: Reload requested from client PID 1987 ('switch-to-confi')...1384machine # [ 43.687169] systemd[616]: Reloading...1385machine # [ 43.840880] systemd[616]: Reloading finished in 151 ms.1386machine # [ 43.846084] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1387machine # [ 43.856626] systemd[616]: Stopped Run user-specific NixOS activation.1388machine # [ 43.858444] systemd[616]: Stopping Run user-specific NixOS activation...1389machine # [ 43.863524] systemd[616]: Starting Run user-specific NixOS activation...1390machine # [ 43.904241] systemd[616]: Finished Run user-specific NixOS activation.1391machine # [ 43.908757] systemd[1]: Stopped target Reactivate sysinit units.1392machine # [ 43.911546] systemd[1]: Stopping Reactivate sysinit units...1393machine # [ 43.913370] systemd[1]: Reached target Reactivate sysinit units.1394machine # [ 43.917474] systemd[1]: Reloading A socket-activated service...1395machine # [ 43.946561] systemd[1]: Reloaded A socket-activated service.1396machine # [ 43.952384] systemd[1]: Reached target Local File Systems.1397machine # [ 43.959668] systemd[1]: Reached target Remote File Systems.1398machine # [ 43.966758] systemd[1]: Starting linger-users.service...1399machine # [ 43.977369] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1400machine # [ 43.984240] systemd[1]: Starting Create SUID/SGID Wrappers...1401machine # [ 43.987889] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1402machine # [ 43.992375] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671403machine # [ 43.998181] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1404machine # [ 44.002190] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1405machine # [ 44.006606] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1406machine # [ 44.009238] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1407machine # [ 44.011971] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1408machine # [ 44.067075] systemd[1]: linger-users.service: Deactivated successfully.1409machine # [ 44.069773] systemd[1]: Finished linger-users.service.1410machine # [ 44.358882] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1411machine # [ 44.361903] systemd[1]: Finished Create SUID/SGID Wrappers.1412machine # [ 44.617420] nixos[1917]: finished switching to system configuration /nix/store/8k351l2l3x216wfcssmhr9yjik27cqp9-nixos-system-machine-test1413machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed-and-reloadtrigger/bin/switch-to-configuration test 2>&1, in 3.15 seconds)1414machine: must succeed: [ -S /run/test.sock ]1415machine: (finished: must succeed: [ -S /run/test.sock ], in 0.02 seconds)1416machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1417machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.53 seconds)1418machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&11419machine # [ 45.794606] nixos[2067]: switching to system configuration /nix/store/q52lrv3frvrdy7ij99vq9vca2anji0fn-nixos-system-machine-test1420machine # [ 45.798693] systemd[1]: Stopped target Remote File Systems.1421machine # [ 45.802404] systemd[1]: Stopped target Local File Systems.1422machine # [ 45.804555] systemd[1]: socket-activated.socket: Deactivated successfully.1423machine # [ 45.806955] systemd[1]: Closed socket-activated.socket.1424machine # [ 46.268874] systemd[1]: Reload requested from client PID 2067 ('.switch-to-conf') (unit backdoor.service)...1425machine # [ 46.271811] systemd[1]: Reloading...1426machine # [ 46.937487] systemd[1]: Reloading finished in 662 ms.1427machine # [ 47.084294] systemd[616]: Reexecution requested from client PID 2137 ('switch-to-confi')...1428machine # [ 47.088456] systemd[616]: Reexecuting.1429machine # [ 47.119428] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1430machine # [ 47.284183] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1431machine # [ 47.293265] systemd[616]: Reload requested from client PID 2137 ('switch-to-confi')...1432machine # [ 47.296167] systemd[616]: Reloading...1433machine # [ 47.460811] systemd[616]: Reloading finished in 162 ms.1434machine # [ 47.466114] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1435machine # [ 47.476506] systemd[616]: Stopped Run user-specific NixOS activation.1436machine # [ 47.479243] systemd[616]: Stopping Run user-specific NixOS activation...1437machine # [ 47.483453] systemd[616]: Starting Run user-specific NixOS activation...1438machine # [ 47.523762] systemd[616]: Finished Run user-specific NixOS activation.1439machine # [ 47.528357] systemd[1]: Stopped target Reactivate sysinit units.1440machine # [ 47.531129] systemd[1]: Stopping Reactivate sysinit units...1441machine # [ 47.533091] systemd[1]: Reached target Reactivate sysinit units.1442machine # [ 47.537443] systemd[1]: Stopping A socket-activated service...1443machine # [ 47.541486] systemd[1]: socket-activated.service: Deactivated successfully.1444machine # [ 47.543480] systemd[1]: Stopped A socket-activated service.1445machine # [ 47.549474] systemd[1]: Started A socket-activated service.1446machine # [ 47.560923] systemd[1]: Reached target Remote File Systems.1447machine # [ 47.563390] systemd[1]: Reached target Local File Systems.1448machine # [ 47.570100] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1449machine # [ 47.574313] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1450machine # [ 47.583119] systemd[1]: Starting linger-users.service...1451machine # [ 47.587335] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1452machine # [ 47.596211] systemd[1]: Starting Create SUID/SGID Wrappers...1453machine # [ 47.598933] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1454machine # [ 47.601352] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671455machine # [ 47.608473] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1456machine # [ 47.613128] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1457machine # [ 47.615563] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1458machine # [ 47.688851] systemd[1]: linger-users.service: Deactivated successfully.1459machine # [ 47.692241] systemd[1]: Finished linger-users.service.1460machine # [ 47.975537] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1461machine # [ 47.979453] systemd[1]: Finished Create SUID/SGID Wrappers.1462machine # [ 48.237924] nixos[2067]: finished switching to system configuration /nix/store/q52lrv3frvrdy7ij99vq9vca2anji0fn-nixos-system-machine-test1463machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/socket-activated-without-socket/bin/switch-to-configuration test 2>&1, in 3.08 seconds)1464machine: must succeed: systemctl is-active socket-activated.service1465machine: (finished: must succeed: systemctl is-active socket-activated.service, in 0.04 seconds)1466machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&11467machine # [ 48.791544] nixos[2213]: switching to system configuration /nix/store/75azpcxv17qcgmq5qbby017djmz8iysc-nixos-system-machine-test1468machine # [ 48.795722] systemd[1]: Stopped target Remote File Systems.1469machine # [ 48.798779] systemd[1]: Stopped target Local File Systems.1470machine # [ 48.802976] systemd[1]: Stopping A socket-activated service...1471machine # [ 48.806909] systemd[1]: socket-activated.service: Deactivated successfully.1472machine # [ 48.808770] systemd[1]: Stopped A socket-activated service.1473machine # [ 49.194798] systemd[1]: Reload requested from client PID 2213 ('.switch-to-conf') (unit backdoor.service)...1474machine # [ 49.197662] systemd[1]: Reloading...1475machine # [ 49.759865] systemd[1]: Reloading finished in 559 ms.1476machine # [ 49.903759] systemd[616]: Reexecution requested from client PID 2283 ('switch-to-confi')...1477machine # [ 49.906328] systemd[616]: Reexecuting.1478machine # [ 49.935515] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1479machine # [ 50.102683] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1480machine # [ 50.111394] systemd[616]: Reload requested from client PID 2283 ('switch-to-confi')...1481machine # [ 50.113872] systemd[616]: Reloading...1482machine # [ 50.273618] systemd[616]: Reloading finished in 157 ms.1483machine # [ 50.280050] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1484machine # [ 50.290826] systemd[616]: Stopped Run user-specific NixOS activation.1485machine # [ 50.292630] systemd[616]: Stopping Run user-specific NixOS activation...1486machine # [ 50.297615] systemd[616]: Starting Run user-specific NixOS activation...1487machine # [ 50.340366] systemd[616]: Finished Run user-specific NixOS activation.1488machine # [ 50.345622] systemd[1]: Stopped target Reactivate sysinit units.1489machine # [ 50.348269] systemd[1]: Stopping Reactivate sysinit units...1490machine # [ 50.350744] systemd[1]: Reached target Reactivate sysinit units.1491machine # [ 50.355046] systemd[1]: Reached target Local File Systems.1492machine # [ 50.359092] systemd[1]: Reached target Remote File Systems.1493machine # [ 50.365753] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1494machine # [ 50.372338] systemd[1]: Starting Create SUID/SGID Wrappers...1495machine # [ 50.373885] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1496machine # [ 50.377636] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671497machine # [ 50.383308] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1498machine # [ 50.389094] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1499machine # [ 50.391362] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1500machine # [ 50.394371] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1501machine # [ 50.396687] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1502machine # [ 50.724952] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1503machine # [ 50.727947] systemd[1]: Finished Create SUID/SGID Wrappers.1504machine # [ 50.732321] systemd[1]: Listening on socket-activated.socket.1505machine # [ 50.737409] systemd[1]: Starting linger-users.service...1506machine # [ 50.794219] systemd[1]: linger-users.service: Deactivated successfully.1507machine # [ 50.796185] systemd[1]: Finished linger-users.service.1508machine # [ 51.056319] nixos[2213]: finished switching to system configuration /nix/store/75azpcxv17qcgmq5qbby017djmz8iysc-nixos-system-machine-test1509machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/simple-socket-stop-if-changed/bin/switch-to-configuration test 2>&1, in 2.78 seconds)1510machine: must succeed: [ -S /run/test.sock ]1511machine: (finished: must succeed: [ -S /run/test.sock ], in 0.03 seconds)1512machine: must succeed: socat - UNIX-CONNECT:/run/test.sock1513machine # [ 51.139125] systemd[1]: Started A socket-activated service.1514machine: (finished: must succeed: socat - UNIX-CONNECT:/run/test.sock, in 0.66 seconds)1515(finished: subtest: socket-activated services, in 24.72 seconds)1516subtest: socket-activated services with Accept=yes1517machine: must fail: [ -S /run/accept-test.sock ]1518machine: (finished: must fail: [ -S /run/accept-test.sock ], in 0.03 seconds)1519machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&11520machine # [ 52.373472] nixos[2364]: switching to system configuration /nix/store/0yygg7964mxwin7q7sy70z1as6jjg65d-nixos-system-machine-test1521machine # [ 52.377545] systemd[1]: socket-activated.socket: Deactivated successfully.1522machine # [ 52.381219] systemd[1]: Closed socket-activated.socket.1523machine # [ 52.383221] systemd[1]: Stopped target Local File Systems.1524machine # [ 52.386817] systemd[1]: Stopping A socket-activated service...1525machine # [ 52.390740] systemd[1]: Stopped target Remote File Systems.1526machine # [ 52.394249] systemd[1]: socket-activated.service: Deactivated successfully.1527machine # [ 52.397453] systemd[1]: Stopped A socket-activated service.1528machine # [ 52.890849] systemd[1]: Reload requested from client PID 2364 ('.switch-to-conf') (unit backdoor.service)...1529machine # [ 52.893752] systemd[1]: Reloading...1530machine # [ 53.510692] systemd[1]: Reloading finished in 613 ms.1531machine # [ 53.651381] systemd[616]: Reexecution requested from client PID 2434 ('switch-to-confi')...1532machine # [ 53.653733] systemd[616]: Reexecuting.1533machine # [ 53.683369] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1534machine # [ 53.847693] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1535machine # [ 53.855741] systemd[616]: Reload requested from client PID 2434 ('switch-to-confi')...1536machine # [ 53.858660] systemd[616]: Reloading...1537machine # [ 54.020746] systemd[616]: Reloading finished in 159 ms.1538machine # [ 54.026071] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1539machine # [ 54.036703] systemd[616]: Stopped Run user-specific NixOS activation.1540machine # [ 54.039349] systemd[616]: Stopping Run user-specific NixOS activation...1541machine # [ 54.043365] systemd[616]: Starting Run user-specific NixOS activation...1542machine # [ 54.086685] systemd[616]: Finished Run user-specific NixOS activation.1543machine # [ 54.091573] systemd[1]: Stopped target Reactivate sysinit units.1544machine # [ 54.093831] systemd[1]: Stopping Reactivate sysinit units...1545machine # [ 54.095567] systemd[1]: Reached target Reactivate sysinit units.1546machine # [ 54.106550] systemd[1]: Reached target Local File Systems.1547machine # [ 54.109884] systemd[1]: Reached target Remote File Systems.1548machine # [ 54.111907] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1549machine # [ 54.117081] systemd[1]: Starting Create SUID/SGID Wrappers...1550machine # [ 54.118712] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1551machine # [ 54.122646] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671552machine # [ 54.127090] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1553machine # [ 54.132654] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1554machine # [ 54.135183] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1555machine # [ 54.138960] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1556machine # [ 54.141301] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1557machine # [ 54.474319] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1558machine # [ 54.477202] systemd[1]: Finished Create SUID/SGID Wrappers.1559machine # [ 54.484310] systemd[1]: Listening on accept-socket.socket.1560machine # [ 54.488637] systemd[1]: Starting linger-users.service...1561machine # [ 54.545822] systemd[1]: linger-users.service: Deactivated successfully.1562machine # [ 54.548283] systemd[1]: Finished linger-users.service.1563machine # [ 54.804903] nixos[2364]: finished switching to system configuration /nix/store/0yygg7964mxwin7q7sy70z1as6jjg65d-nixos-system-machine-test1564machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/accept-socket/bin/switch-to-configuration test 2>&1, in 3.02 seconds)1565machine: must succeed: [ -S /run/accept-test.sock ]1566machine: (finished: must succeed: [ -S /run/accept-test.sock ], in 0.02 seconds)1567machine: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &1568machine: (finished: must succeed: socat EXEC:'sleep infinity' UNIX-CONNECT:/run/accept-test.sock >&2 &, in 0.01 seconds)1569machine: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service'1570machine # [ 54.858818] systemd[1]: Created slice Slice /system/accept-socket.1571machine # [ 54.864526] systemd[1]: Started A per-connection socket-activated service (PID 2508/UID 0).1572machine: (finished: waiting for success: systemctl list-units --no-legend --state=running 'accept-socket@*.service' | grep -m1 -o 'accept-socket@[^ ]*\.service', in 0.05 seconds)1573machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&11574machine # [ 55.551524] nixos[2520]: switching to system configuration /nix/store/5zigy2lnnzz9s6gmdr2wssl73wh9kvn6-nixos-system-machine-test1575machine # [ 55.556924] systemd[1]: Stopping A per-connection socket-activated service (PID 2508/UID 0)...1576machine # [ 55.561376] systemd[1]: accept-socket@0-1-2508_2509-0.service: Deactivated successfully.1577machine # [ 55.564294] systemd[1]: Stopped A per-connection socket-activated service (PID 2508/UID 0).1578machine # [ 55.571216] systemd[1]: Stopped target Remote File Systems.1579machine # [ 55.573888] systemd[1]: accept-socket.socket: Deactivated successfully.1580machine # [ 55.576815] systemd[1]: Closed accept-socket.socket.1581machine # [ 55.578619] systemd[1]: Stopped target Local File Systems.1582machine # [ 56.072336] systemd[1]: Reload requested from client PID 2520 ('.switch-to-conf') (unit backdoor.service)...1583machine # [ 56.075439] systemd[1]: Reloading...1584machine # [ 56.724225] systemd[1]: Reloading finished in 645 ms.1585machine # [ 56.873892] systemd[616]: Reexecution requested from client PID 2591 ('switch-to-confi')...1586machine # [ 56.878189] systemd[616]: Reexecuting.1587machine # [ 56.905709] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1588machine # [ 57.048382] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1589machine # [ 57.057108] systemd[616]: Reload requested from client PID 2591 ('switch-to-confi')...1590machine # [ 57.059985] systemd[616]: Reloading...1591machine # [ 57.219063] systemd[616]: Reloading finished in 157 ms.1592machine # [ 57.225091] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1593machine # [ 57.235948] systemd[616]: Stopped Run user-specific NixOS activation.1594machine # [ 57.237787] systemd[616]: Stopping Run user-specific NixOS activation...1595machine # [ 57.242937] systemd[616]: Starting Run user-specific NixOS activation...1596machine # [ 57.286926] systemd[616]: Finished Run user-specific NixOS activation.1597machine # [ 57.291614] systemd[1]: Stopped target Reactivate sysinit units.1598machine # [ 57.293880] systemd[1]: Stopping Reactivate sysinit units...1599machine # [ 57.295919] systemd[1]: Reached target Reactivate sysinit units.1600machine # [ 57.298950] systemd[1]: Reached target Remote File Systems.1601machine # [ 57.305937] systemd[1]: Listening on accept-socket.socket.1602machine # [ 57.308750] systemd[1]: Reached target Local File Systems.1603machine # [ 57.312319] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1604machine # [ 57.320109] systemd[1]: Starting Create SUID/SGID Wrappers...1605machine # [ 57.321765] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1606machine # [ 57.325473] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671607machine # [ 57.331231] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1608machine # [ 57.335208] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1609machine # [ 57.337458] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1610machine # [ 57.344756] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1611machine # [ 57.347066] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1612machine # [ 57.673825] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1613machine # [ 57.676651] systemd[1]: Finished Create SUID/SGID Wrappers.1614machine # [ 57.682965] systemd[1]: Starting linger-users.service...1615machine # [ 57.740696] systemd[1]: linger-users.service: Deactivated successfully.1616machine # [ 57.743470] systemd[1]: Finished linger-users.service.1617machine # [ 58.003773] nixos[2520]: finished switching to system configuration /nix/store/5zigy2lnnzz9s6gmdr2wssl73wh9kvn6-nixos-system-machine-test1618machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/accept-socket-service-modified/bin/switch-to-configuration test 2>&1, in 3.12 seconds)1619machine: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null1620machine # [ 58.072136] systemd[1]: Started A per-connection socket-activated service (PID 2662/UID 0).1621machine # [ 58.113083] systemd[1]: accept-socket@1-2-2662_2663-0.service: Deactivated successfully.1622machine: (finished: must succeed: socat - UNIX-CONNECT:/run/accept-test.sock </dev/null, in 0.10 seconds)1623(finished: subtest: socket-activated services with Accept=yes, in 6.36 seconds)1624subtest: mounts1625machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11626machine # [ 58.758594] nixos[2669]: switching to system configuration /nix/store/kpbwjsqdwbqv8qbh3p3jwxxld8v7pgxf-nixos-system-machine-test1627machine # [ 58.762629] systemd[1]: Stopped target Remote File Systems.1628machine # [ 58.766267] systemd[1]: Stopped target Local File Systems.1629machine # [ 58.769109] systemd[1]: accept-socket.socket: Deactivated successfully.1630machine # [ 58.770933] systemd[1]: Closed accept-socket.socket.1631machine # [ 59.270751] systemd[1]: Reload requested from client PID 2669 ('.switch-to-conf') (unit backdoor.service)...1632machine # [ 59.273653] systemd[1]: Reloading...1633machine # [ 59.902933] systemd[1]: Reloading finished in 626 ms.1634machine # [ 60.057139] systemd[616]: Reexecution requested from client PID 2738 ('switch-to-confi')...1635machine # [ 60.060276] systemd[616]: Reexecuting.1636machine # [ 60.088952] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1637machine # [ 60.246782] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1638machine # [ 60.256650] systemd[616]: Reload requested from client PID 2738 ('switch-to-confi')...1639machine # [ 60.259193] systemd[616]: Reloading...1640machine # [ 60.423237] systemd[616]: Reloading finished in 160 ms.1641machine # [ 60.427043] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1642machine # [ 60.438783] systemd[616]: Stopped Run user-specific NixOS activation.1643machine # [ 60.440483] systemd[616]: Stopping Run user-specific NixOS activation...1644machine # [ 60.444705] systemd[616]: Starting Run user-specific NixOS activation...1645machine # [ 60.482116] systemd[616]: Finished Run user-specific NixOS activation.1646machine # [ 60.486972] systemd[1]: Stopped target Reactivate sysinit units.1647machine # [ 60.489252] systemd[1]: Stopping Reactivate sysinit units...1648machine # [ 60.491440] systemd[1]: Reached target Reactivate sysinit units.1649machine # [ 60.496182] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1650machine # [ 60.499267] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1651machine # [ 60.502855] systemd[1]: Reached target Remote File Systems.1652machine # [ 60.514058] systemd[1]: Starting linger-users.service...1653machine # [ 60.525307] systemd[1]: Mounting Testmount...1654machine # [ 60.526883] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1655machine # [ 60.533368] systemd[1]: Starting Create SUID/SGID Wrappers...1656machine # [ 60.535529] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671657machine # [ 60.539322] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1658machine # [ 60.546741] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1659machine # [ 60.551318] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1660machine # [ 60.593361] systemd[1]: Mounted Testmount.1661machine # [ 60.595599] systemd[1]: Reached target Local File Systems.1662machine # [ 60.598427] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1663machine # [ 60.624955] systemd[1]: linger-users.service: Deactivated successfully.1664machine # [ 60.627806] systemd[1]: Finished linger-users.service.1665machine # [ 60.856367] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1666machine # [ 60.859288] systemd[1]: Finished Create SUID/SGID Wrappers.1667machine # [ 61.116486] nixos[2669]: finished switching to system configuration /nix/store/kpbwjsqdwbqv8qbh3p3jwxxld8v7pgxf-nixos-system-machine-test1668machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 3.00 seconds)1669machine: must succeed: mount | grep 'on /testmount'1670machine: (finished: must succeed: mount | grep 'on /testmount', in 0.03 seconds)1671machine: must succeed: systemctl stop testmount.mount1672machine # [ 61.181962] systemd[1]: Unmounting Testmount...1673machine # [ 61.217909] systemd[1]: testmount.mount: Deactivated successfully.1674machine # [ 61.219879] systemd[1]: Unmounted Testmount.1675machine: (finished: must succeed: systemctl stop testmount.mount, in 0.07 seconds)1676machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&11677machine # [ 61.808160] nixos[2822]: switching to system configuration /nix/store/kpbwjsqdwbqv8qbh3p3jwxxld8v7pgxf-nixos-system-machine-test1678machine # [ 61.811945] systemd[1]: Stopped target Remote File Systems.1679machine # [ 61.814751] systemd[1]: Stopped target Local File Systems.1680machine # [ 62.250639] systemd[1]: Reload requested from client PID 2822 ('.switch-to-conf') (unit backdoor.service)...1681machine # [ 62.253381] systemd[1]: Reloading...1682machine # [ 62.816569] systemd[1]: Reloading finished in 560 ms.1683machine # [ 62.964184] systemd[616]: Reexecution requested from client PID 2891 ('switch-to-confi')...1684machine # [ 62.968377] systemd[616]: Reexecuting.1685machine # [ 62.999909] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1686machine # [ 63.166282] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1687machine # [ 63.173908] systemd[616]: Reload requested from client PID 2891 ('switch-to-confi')...1688machine # [ 63.176774] systemd[616]: Reloading...1689machine # [ 63.339673] systemd[616]: Reloading finished in 160 ms.1690machine # [ 63.345086] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1691machine # [ 63.356325] systemd[616]: Stopped Run user-specific NixOS activation.1692machine # [ 63.358090] systemd[616]: Stopping Run user-specific NixOS activation...1693machine # [ 63.362460] systemd[616]: Starting Run user-specific NixOS activation...1694machine # [ 63.403130] systemd[616]: Finished Run user-specific NixOS activation.1695machine # [ 63.408342] systemd[1]: Stopped target Reactivate sysinit units.1696machine # [ 63.411315] systemd[1]: Stopping Reactivate sysinit units...1697machine # [ 63.413885] systemd[1]: Reached target Reactivate sysinit units.1698machine # [ 63.422376] systemd[1]: Mounting Testmount...1699machine # [ 63.423731] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1700machine # [ 63.429522] systemd[1]: Starting Create SUID/SGID Wrappers...1701machine # [ 63.432986] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671702machine # [ 63.436850] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1703machine # [ 63.441787] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1704machine # [ 63.446210] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1705machine # [ 63.451044] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1706machine # [ 63.453847] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1707machine # [ 63.463881] systemd[1]: Reached target Remote File Systems.1708machine # [ 63.500443] systemd[1]: Mounted Testmount.1709machine # [ 63.503167] systemd[1]: Reached target Local File Systems.1710machine # [ 63.505488] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1711machine # [ 63.805534] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1712machine # [ 63.809709] systemd[1]: Finished Create SUID/SGID Wrappers.1713machine # [ 63.814757] systemd[1]: Starting linger-users.service...1714machine # [ 63.872902] systemd[1]: linger-users.service: Deactivated successfully.1715machine # [ 63.876318] systemd[1]: Finished linger-users.service.1716machine # [ 64.135978] nixos[2822]: finished switching to system configuration /nix/store/kpbwjsqdwbqv8qbh3p3jwxxld8v7pgxf-nixos-system-machine-test1717machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mount/bin/switch-to-configuration test 2>&1, in 2.92 seconds)1718machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&11719machine # [ 64.881275] nixos[2964]: switching to system configuration /nix/store/iq57i3fsris6ln6j9fr0j1bfnfwdifgd-nixos-system-machine-test1720machine # [ 64.885615] systemd[1]: Stopped target Remote File Systems.1721machine # [ 64.888074] systemd[1]: Stopped target Local File Systems.1722machine # [ 65.359771] systemd[1]: Reload requested from client PID 2964 ('.switch-to-conf') (unit backdoor.service)...1723machine # [ 65.362708] systemd[1]: Reloading...1724machine # [ 66.019985] systemd[1]: Reloading finished in 654 ms.1725machine # [ 66.171370] systemd[616]: Reexecution requested from client PID 3033 ('switch-to-confi')...1726machine # [ 66.174932] systemd[616]: Reexecuting.1727machine # [ 66.207739] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1728machine # [ 66.385224] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1729machine # [ 66.394555] systemd[616]: Reload requested from client PID 3033 ('switch-to-confi')...1730machine # [ 66.397296] systemd[616]: Reloading...1731machine # [ 66.554792] systemd[616]: Reloading finished in 155 ms.1732machine # [ 66.560070] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1733machine # [ 66.571482] systemd[616]: Stopped Run user-specific NixOS activation.1734machine # [ 66.574140] systemd[616]: Stopping Run user-specific NixOS activation...1735machine # [ 66.578559] systemd[616]: Starting Run user-specific NixOS activation...1736machine # [ 66.614646] systemd[616]: Finished Run user-specific NixOS activation.1737machine # [ 66.619594] systemd[1]: Stopped target Reactivate sysinit units.1738machine # [ 66.621923] systemd[1]: Stopping Reactivate sysinit units...1739machine # [ 66.625124] systemd[1]: Reached target Reactivate sysinit units.1740machine # [ 66.627994] systemd[1]: Reloading Testmount...1741machine # [ 66.658915] systemd[1]: Reloaded Testmount.1742machine # [ 66.662883] systemd[1]: Reached target Remote File Systems.1743machine # [ 66.670111] systemd[1]: Starting linger-users.service...1744machine # [ 66.672459] systemd[1]: Reached target Local File Systems.1745machine # [ 66.679342] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1746machine # [ 66.685506] systemd[1]: Starting Create SUID/SGID Wrappers...1747machine # [ 66.688327] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1748machine # [ 66.690554] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671749machine # [ 66.694314] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1750machine # [ 66.702164] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1751machine # [ 66.705501] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1752machine # [ 66.715530] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1753machine # [ 66.717839] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1754machine # [ 66.777365] systemd[1]: linger-users.service: Deactivated successfully.1755machine # [ 66.780402] systemd[1]: Finished linger-users.service.1756machine # [ 67.070909] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1757machine # [ 67.073897] systemd[1]: Finished Create SUID/SGID Wrappers.1758machine # [ 67.332994] nixos[2964]: finished switching to system configuration /nix/store/iq57i3fsris6ln6j9fr0j1bfnfwdifgd-nixos-system-machine-test1759machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mountOptionsModified/bin/switch-to-configuration test 2>&1, in 3.20 seconds)1760machine: must succeed: mount | grep 'on /testmount'1761machine: (finished: must succeed: mount | grep 'on /testmount', in 0.05 seconds)1762machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&11763machine # [ 68.062665] nixos[3111]: switching to system configuration /nix/store/fqmpsj4zq61f9jlpwi93kswlprjlnx0j-nixos-system-machine-test1764machine # [ 68.066486] systemd[1]: Stopped target Local File Systems.1765machine # [ 68.069453] systemd[1]: Stopped target Remote File Systems.1766machine # [ 68.545676] systemd[1]: Reload requested from client PID 3111 ('.switch-to-conf') (unit backdoor.service)...1767machine # [ 68.548620] systemd[1]: Reloading...1768machine # [ 69.177606] systemd[1]: Reloading finished in 626 ms.1769machine # [ 69.329459] systemd[616]: Reexecution requested from client PID 3180 ('switch-to-confi')...1770machine # [ 69.334108] systemd[616]: Reexecuting.1771machine # [ 69.361618] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1772machine # [ 69.530107] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1773machine # [ 69.539865] systemd[616]: Reload requested from client PID 3180 ('switch-to-confi')...1774machine # [ 69.542670] systemd[616]: Reloading...1775machine # [ 69.713605] systemd[616]: Reloading finished in 168 ms.1776machine # [ 69.719056] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1777machine # [ 69.730491] systemd[616]: Stopped Run user-specific NixOS activation.1778machine # [ 69.732208] systemd[616]: Stopping Run user-specific NixOS activation...1779machine # [ 69.736831] systemd[616]: Starting Run user-specific NixOS activation...1780machine # [ 69.779880] systemd[616]: Finished Run user-specific NixOS activation.1781machine # [ 69.784823] systemd[1]: Stopped target Reactivate sysinit units.1782machine # [ 69.787644] systemd[1]: Stopping Reactivate sysinit units...1783machine # [ 69.790173] systemd[1]: Reached target Reactivate sysinit units.1784machine # [ 69.792834] systemd[1]: Unmounting Testmount...1785machine # [ 69.826157] systemd[1]: testmount.mount: Deactivated successfully.1786machine # [ 69.828807] systemd[1]: Unmounted Testmount.1787machine # [ 69.834482] systemd[1]: Mounting Testmount...1788machine # [ 69.871659] systemd[1]: Mounted Testmount.1789machine # [ 69.876369] systemd[1]: Reached target Remote File Systems.1790machine # [ 69.883236] systemd[1]: Starting linger-users.service...1791machine # [ 69.891820] systemd[1]: Reached target Local File Systems.1792machine # [ 69.893350] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1793machine # [ 69.899282] systemd[1]: Starting Create SUID/SGID Wrappers...1794machine # [ 69.901324] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1795machine # [ 69.905264] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671796machine # [ 69.912551] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1797machine # [ 69.917766] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1798machine # [ 69.922685] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1799machine # [ 69.926198] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1800machine # [ 69.928428] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1801machine # [ 69.982939] systemd[1]: linger-users.service: Deactivated successfully.1802machine # [ 69.985985] systemd[1]: Finished linger-users.service.1803machine # [ 70.225464] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1804machine # [ 70.228389] systemd[1]: Finished Create SUID/SGID Wrappers.1805machine # [ 70.485222] nixos[3111]: finished switching to system configuration /nix/store/fqmpsj4zq61f9jlpwi93kswlprjlnx0j-nixos-system-machine-test1806machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/mountModified/bin/switch-to-configuration test 2>&1, in 3.09 seconds)1807machine: must succeed: mount | grep 'on /testmount'1808machine: (finished: must succeed: mount | grep 'on /testmount', in 0.02 seconds)1809(finished: subtest: mounts, in 12.40 seconds)1810subtest: timers1811machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&11812machine # [ 71.110574] nixos[3260]: switching to system configuration /nix/store/zl6addjxymwmbd1i3psw15xbckxv9qsj-nixos-system-machine-test1813machine # [ 71.116428] systemd[1]: Unmounting Testmount...1814machine # [ 71.119383] systemd[1]: Stopped target Remote File Systems.1815machine # [ 71.121852] systemd[1]: Stopped target Local File Systems.1816machine # [ 71.147768] systemd[1]: testmount.mount: Deactivated successfully.1817machine # [ 71.151259] systemd[1]: Unmounted Testmount.1818machine # [ 71.585708] systemd[1]: Reload requested from client PID 3260 ('.switch-to-conf') (unit backdoor.service)...1819machine # [ 71.588630] systemd[1]: Reloading...1820machine # [ 72.201249] systemd[1]: Reloading finished in 610 ms.1821machine # [ 72.326148] systemd[616]: Reexecution requested from client PID 3331 ('switch-to-confi')...1822machine # [ 72.329852] systemd[616]: Reexecuting.1823machine # [ 72.360431] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1824machine # [ 72.504682] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1825machine # [ 72.512806] systemd[616]: Reload requested from client PID 3331 ('switch-to-confi')...1826machine # [ 72.515616] systemd[616]: Reloading...1827machine # [ 72.682167] systemd[616]: Reloading finished in 164 ms.1828machine # [ 72.689058] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1829machine # [ 72.699786] systemd[616]: Stopped Run user-specific NixOS activation.1830machine # [ 72.701569] systemd[616]: Stopping Run user-specific NixOS activation...1831machine # [ 72.706703] systemd[616]: Starting Run user-specific NixOS activation...1832machine # [ 72.747309] systemd[616]: Finished Run user-specific NixOS activation.1833machine # [ 72.751807] systemd[1]: Stopped target Reactivate sysinit units.1834machine # [ 72.753962] systemd[1]: Stopping Reactivate sysinit units...1835machine # [ 72.755857] systemd[1]: Reached target Reactivate sysinit units.1836machine # [ 72.763285] systemd[1]: Reached target Remote File Systems.1837machine # [ 72.772401] systemd[1]: Starting linger-users.service...1838machine # [ 72.775841] systemd[1]: Started test-timer.timer.1839machine # [ 72.778723] systemd[1]: Reached target Local File Systems.1840machine # [ 72.782988] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1841machine # [ 72.786633] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1842machine # [ 72.791212] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1843machine # [ 72.798560] systemd[1]: Starting Create SUID/SGID Wrappers...1844machine # [ 72.800306] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1845machine # [ 72.803958] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671846machine # [ 72.808804] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1847machine # [ 72.811687] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1848machine # [ 72.815207] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1849machine # [ 72.869426] systemd[1]: linger-users.service: Deactivated successfully.1850machine # [ 72.872520] systemd[1]: Finished linger-users.service.1851machine # [ 73.124689] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1852machine # [ 73.128329] systemd[1]: Finished Create SUID/SGID Wrappers.1853machine # [ 73.388621] nixos[3260]: finished switching to system configuration /nix/store/zl6addjxymwmbd1i3psw15xbckxv9qsj-nixos-system-machine-test1854machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timer/bin/switch-to-configuration test 2>&1, in 2.88 seconds)1855machine: must succeed: systemctl show test-timer.timer1856machine: (finished: must succeed: systemctl show test-timer.timer, in 0.09 seconds)1857machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&11858machine # [ 74.127408] nixos[3405]: switching to system configuration /nix/store/v1aw53h0n41l09rqbqk7jqkrs46khqlh-nixos-system-machine-test1859machine # [ 74.131723] systemd[1]: Stopped target Remote File Systems.1860machine # [ 74.134507] systemd[1]: Stopped target Local File Systems.1861machine # [ 74.619575] systemd[1]: Reload requested from client PID 3405 ('.switch-to-conf') (unit backdoor.service)...1862machine # [ 74.622498] systemd[1]: Reloading...1863machine # [ 75.244550] systemd[1]: Reloading finished in 619 ms.1864machine # [ 75.392423] systemd[616]: Reexecution requested from client PID 3474 ('switch-to-confi')...1865machine # [ 75.396806] systemd[616]: Reexecuting.1866machine # [ 75.426558] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1867machine # [ 75.584117] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1868machine # [ 75.593498] systemd[616]: Reload requested from client PID 3474 ('switch-to-confi')...1869machine # [ 75.596048] systemd[616]: Reloading...1870machine # [ 75.756885] systemd[616]: Reloading finished in 158 ms.1871machine # [ 75.763042] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1872machine # [ 75.773863] systemd[616]: Stopped Run user-specific NixOS activation.1873machine # [ 75.777100] systemd[616]: Stopping Run user-specific NixOS activation...1874machine # [ 75.780442] systemd[616]: Starting Run user-specific NixOS activation...1875machine # [ 75.820247] systemd[616]: Finished Run user-specific NixOS activation.1876machine # [ 75.825532] systemd[1]: Stopped target Reactivate sysinit units.1877machine # [ 75.827564] systemd[1]: Stopping Reactivate sysinit units...1878machine # [ 75.829229] systemd[1]: Reached target Reactivate sysinit units.1879machine # [ 75.831943] systemd[1]: test-timer.timer: Deactivated successfully.1880machine # [ 75.834481] systemd[1]: Stopped test-timer.timer.1881machine # [ 75.836842] systemd[1]: Stopping test-timer.timer...1882machine # [ 75.841334] systemd[1]: Started test-timer.timer.1883machine # [ 75.842708] systemd[1]: Reached target Local File Systems.1884machine # [ 75.844221] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1885machine # [ 75.850085] systemd[1]: Starting Create SUID/SGID Wrappers...1886machine # [ 75.851761] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1887machine # [ 75.855457] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671888machine # [ 75.860771] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1889machine # [ 75.866241] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1890machine # [ 75.869423] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1891machine # [ 75.871801] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1892machine # [ 75.876779] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1893machine # [ 75.881187] systemd[1]: Reached target Remote File Systems.1894machine # [ 76.221079] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1895machine # [ 76.223281] systemd[1]: Finished Create SUID/SGID Wrappers.1896machine # [ 76.229933] systemd[1]: Starting linger-users.service...1897machine # [ 76.287325] systemd[1]: linger-users.service: Deactivated successfully.1898machine # [ 76.289363] systemd[1]: Finished linger-users.service.1899machine # [ 76.549668] nixos[3405]: finished switching to system configuration /nix/store/v1aw53h0n41l09rqbqk7jqkrs46khqlh-nixos-system-machine-test1900machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 3.08 seconds)1901machine: must succeed: systemctl show test-timer.timer1902machine: (finished: must succeed: systemctl show test-timer.timer, in 0.08 seconds)1903(finished: subtest: timers, in 6.12 seconds)1904subtest: targets1905machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&11906machine # [ 77.279920] nixos[3548]: switching to system configuration /nix/store/np8csg4bihbmbx1ykd21pwa69b3ipk1b-nixos-system-machine-test1907machine # [ 77.283942] systemd[1]: test-timer.timer: Deactivated successfully.1908machine # [ 77.287497] systemd[1]: Stopped test-timer.timer.1909machine # [ 77.288837] systemd[1]: Stopped target Local File Systems.1910machine # [ 77.291837] systemd[1]: Stopped target Remote File Systems.1911machine # [ 77.766337] systemd[1]: Reload requested from client PID 3548 ('.switch-to-conf') (unit backdoor.service)...1912machine # [ 77.769221] systemd[1]: Reloading...1913machine # [ 78.397462] systemd[1]: Reloading finished in 625 ms.1914machine # [ 78.546975] systemd[616]: Reexecution requested from client PID 3617 ('switch-to-confi')...1915machine # [ 78.551433] systemd[616]: Reexecuting.1916machine # [ 78.582627] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1917machine # [ 78.750191] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1918machine # [ 78.758724] systemd[616]: Reload requested from client PID 3617 ('switch-to-confi')...1919machine # [ 78.761289] systemd[616]: Reloading...1920machine # [ 78.925092] systemd[616]: Reloading finished in 160 ms.1921machine # [ 78.931059] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1922machine # [ 78.941482] systemd[616]: Stopped Run user-specific NixOS activation.1923machine # [ 78.944152] systemd[616]: Stopping Run user-specific NixOS activation...1924machine # [ 78.948569] systemd[616]: Starting Run user-specific NixOS activation...1925machine # [ 78.988137] systemd[616]: Finished Run user-specific NixOS activation.1926machine # [ 78.992649] systemd[1]: Stopped target Reactivate sysinit units.1927machine # [ 78.994998] systemd[1]: Stopping Reactivate sysinit units...1928machine # [ 78.997191] systemd[1]: Reached target Reactivate sysinit units.1929machine # [ 79.003623] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1930machine # [ 79.006737] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1931machine # [ 79.010396] systemd[1]: Reached target Local File Systems.1932machine # [ 79.016577] systemd[1]: Reached target Remote File Systems.1933machine # [ 79.020097] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1934machine # [ 79.028269] systemd[1]: Starting Create SUID/SGID Wrappers...1935machine # [ 79.029941] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1936machine # [ 79.033878] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671937machine # [ 79.041141] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1938machine # [ 79.044063] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1939machine # [ 79.047259] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1940machine # [ 79.385634] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1941machine # [ 79.388631] systemd[1]: Finished Create SUID/SGID Wrappers.1942machine # [ 79.394737] systemd[1]: Starting linger-users.service...1943machine # [ 79.452194] systemd[1]: linger-users.service: Deactivated successfully.1944machine # [ 79.454254] systemd[1]: Finished linger-users.service.1945machine # [ 79.711123] nixos[3548]: finished switching to system configuration /nix/store/np8csg4bihbmbx1ykd21pwa69b3ipk1b-nixos-system-machine-test1946machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/hybridSleepModified/bin/switch-to-configuration test 2>&1, in 3.08 seconds)1947machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&11948machine # [ 80.246894] nixos[3688]: switching to system configuration /nix/store/yqxzzdv6psjkwlzl4k70csvwz1w9nwgf-nixos-system-machine-test1949machine # [ 80.250666] systemd[1]: Stopped target Local File Systems.1950machine # [ 80.253559] systemd[1]: Stopped target Remote File Systems.1951machine # [ 80.745705] systemd[1]: Reload requested from client PID 3688 ('.switch-to-conf') (unit backdoor.service)...1952machine # [ 80.748633] systemd[1]: Reloading...1953machine # [ 81.332170] systemd[1]: Reloading finished in 581 ms.1954machine # [ 81.479776] systemd[616]: Reexecution requested from client PID 3757 ('switch-to-confi')...1955machine # [ 81.483705] systemd[616]: Reexecuting.1956machine # [ 81.514590] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1957machine # [ 81.680315] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1958machine # [ 81.688671] systemd[616]: Reload requested from client PID 3757 ('switch-to-confi')...1959machine # [ 81.691945] systemd[616]: Reloading...1960machine # [ 81.858992] systemd[616]: Reloading finished in 165 ms.1961machine # [ 81.864041] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1962machine # [ 81.875530] systemd[616]: Stopped Run user-specific NixOS activation.1963machine # [ 81.877226] systemd[616]: Stopping Run user-specific NixOS activation...1964machine # [ 81.881459] systemd[616]: Starting Run user-specific NixOS activation...1965machine # [ 81.923904] systemd[616]: Finished Run user-specific NixOS activation.1966machine # [ 81.928829] systemd[1]: Stopped target Reactivate sysinit units.1967machine # [ 81.932700] systemd[1]: Stopping Reactivate sysinit units...1968machine # [ 81.934491] systemd[1]: Reached target Reactivate sysinit units.1969machine # [ 81.937207] systemd[1]: Reached target Remote File Systems.1970machine # [ 81.939647] systemd[1]: Reached target Local File Systems.1971machine # [ 81.949603] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os1972machine # [ 81.952793] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki1973machine # [ 81.957115] systemd[1]: Reached target test-target.target.1974machine # [ 81.962524] systemd[1]: Starting linger-users.service...1975machine # [ 81.970961] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore1976machine # [ 81.977809] systemd[1]: Starting Create SUID/SGID Wrappers...1977machine # [ 81.979344] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.1978machine # [ 81.981678] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc671979machine # [ 81.986439] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore1980machine # [ 81.989906] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1981machine # [ 81.993307] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os1982machine # [ 82.058197] systemd[1]: linger-users.service: Deactivated successfully.1983machine # [ 82.060985] systemd[1]: Finished linger-users.service.1984machine # [ 82.331282] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.1985machine # [ 82.334396] systemd[1]: Finished Create SUID/SGID Wrappers.1986machine # [ 82.594745] nixos[3688]: finished switching to system configuration /nix/store/yqxzzdv6psjkwlzl4k70csvwz1w9nwgf-nixos-system-machine-test1987machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/target/bin/switch-to-configuration test 2>&1, in 2.89 seconds)1988machine # [ 82.662593] systemd[1]: Started test-service.service.1989machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&11990machine # [ 83.331915] nixos[3834]: switching to system configuration /nix/store/ikzmaa658nj7q3wxrva4lq2adn5zcn3c-nixos-system-machine-test1991machine # [ 83.335946] systemd[1]: Stopped target Local File Systems.1992machine # [ 83.338701] systemd[1]: Stopped target Remote File Systems.1993machine # [ 83.825483] systemd[1]: Reload requested from client PID 3834 ('.switch-to-conf') (unit backdoor.service)...1994machine # [ 83.828322] systemd[1]: Reloading...1995machine # [ 84.437974] systemd[1]: Reloading finished in 606 ms.1996machine # [ 84.587789] systemd[616]: Reexecution requested from client PID 3904 ('switch-to-confi')...1997machine # [ 84.592138] systemd[616]: Reexecuting.1998machine # [ 84.621790] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy1999machine # [ 84.776710] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2000machine # [ 84.785319] systemd[616]: Reload requested from client PID 3904 ('switch-to-confi')...2001machine # [ 84.787586] systemd[616]: Reloading...2002machine # [ 84.945933] systemd[616]: Reloading finished in 155 ms.2003machine # [ 84.954068] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2004machine # [ 84.965177] systemd[616]: Stopped Run user-specific NixOS activation.2005machine # [ 84.968210] systemd[616]: Stopping Run user-specific NixOS activation...2006machine # [ 84.971590] systemd[616]: Starting Run user-specific NixOS activation...2007machine # [ 85.008451] systemd[616]: Finished Run user-specific NixOS activation.2008machine # [ 85.013174] systemd[1]: Stopped target Reactivate sysinit units.2009machine # [ 85.015658] systemd[1]: Stopping Reactivate sysinit units...2010machine # [ 85.017967] systemd[1]: Reached target Reactivate sysinit units.2011machine # [ 85.022719] systemd[1]: Reached target Local File Systems.2012machine # [ 85.025692] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2013machine # [ 85.031396] systemd[1]: Starting Create SUID/SGID Wrappers...2014machine # [ 85.034109] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2015machine # [ 85.036338] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672016machine # [ 85.043472] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2017machine # [ 85.047192] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2018machine # [ 85.049404] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2019machine # [ 85.053792] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2020machine # [ 85.056508] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2021machine # [ 85.063380] systemd[1]: Reached target Remote File Systems.2022machine # [ 85.385960] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2023machine # [ 85.388856] systemd[1]: Finished Create SUID/SGID Wrappers.2024machine # [ 85.395971] systemd[1]: Starting linger-users.service...2025machine # [ 85.459232] systemd[1]: linger-users.service: Deactivated successfully.2026machine # [ 85.462443] systemd[1]: Finished linger-users.service.2027machine # [ 85.721372] nixos[3834]: finished switching to system configuration /nix/store/ikzmaa658nj7q3wxrva4lq2adn5zcn3c-nixos-system-machine-test2028machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/targetModified/bin/switch-to-configuration test 2>&1, in 3.06 seconds)2029machine: must succeed: systemctl is-active test-service.service2030machine: (finished: must succeed: systemctl is-active test-service.service, in 0.04 seconds)2031machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&12032machine # [ 86.315425] nixos[3978]: switching to system configuration /nix/store/1a1s26zp6qk368ckcv3304g0p20w8q0d-nixos-system-machine-test2033machine # [ 86.319578] systemd[1]: Stopped target Local File Systems.2034machine # [ 86.321935] systemd[1]: Stopped target test-target.target.2035machine # [ 86.325705] systemd[1]: Stopping test-service.service...2036machine # [ 86.330326] systemd[1]: Stopped target Remote File Systems.2037machine # [ 86.331881] systemd[1]: test-service.service: Deactivated successfully.2038machine # [ 86.333958] systemd[1]: Stopped test-service.service.2039machine # [ 86.786693] systemd[1]: Reload requested from client PID 3978 ('.switch-to-conf') (unit backdoor.service)...2040machine # [ 86.789598] systemd[1]: Reloading...2041machine # [ 87.443773] systemd[1]: Reloading finished in 651 ms.2042machine # [ 87.592484] systemd[616]: Reexecution requested from client PID 4048 ('switch-to-confi')...2043machine # [ 87.596708] systemd[616]: Reexecuting.2044machine # [ 87.623471] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2045machine # [ 87.788620] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2046machine # [ 87.796779] systemd[616]: Reload requested from client PID 4048 ('switch-to-confi')...2047machine # [ 87.799542] systemd[616]: Reloading...2048machine # [ 87.959513] systemd[616]: Reloading finished in 158 ms.2049machine # [ 87.966091] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2050machine # [ 87.977789] systemd[616]: Stopped Run user-specific NixOS activation.2051machine # [ 87.979460] systemd[616]: Stopping Run user-specific NixOS activation...2052machine # [ 87.983683] systemd[616]: Starting Run user-specific NixOS activation...2053machine # [ 88.026937] systemd[616]: Finished Run user-specific NixOS activation.2054machine # [ 88.031721] systemd[1]: Stopped target Reactivate sysinit units.2055machine # [ 88.034604] systemd[1]: Stopping Reactivate sysinit units...2056machine # [ 88.036223] systemd[1]: Reached target Reactivate sysinit units.2057machine # [ 88.041482] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2058machine # [ 88.044320] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2059machine # [ 88.050725] systemd[1]: Reached target Remote File Systems.2060machine # [ 88.054381] systemd[1]: Reached target test-target.target.2061machine # [ 88.059623] systemd[1]: Starting linger-users.service...2062machine # [ 88.067105] systemd[1]: Reached target Local File Systems.2063machine # [ 88.068555] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2064machine # [ 88.079174] systemd[1]: Starting Create SUID/SGID Wrappers...2065machine # [ 88.080695] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2066machine # [ 88.082873] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672067machine # [ 88.090398] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2068machine # [ 88.096194] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2069machine # [ 88.099135] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2070machine # [ 88.154098] systemd[1]: linger-users.service: Deactivated successfully.2071machine # [ 88.156824] systemd[1]: Finished linger-users.service.2072machine # [ 88.437752] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2073machine # [ 88.441282] systemd[1]: Finished Create SUID/SGID Wrappers.2074machine # [ 88.700865] nixos[3978]: finished switching to system configuration /nix/store/1a1s26zp6qk368ckcv3304g0p20w8q0d-nixos-system-machine-test2075machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/targetModifiedStopOnReconfig/bin/switch-to-configuration test 2>&1, in 2.94 seconds)2076machine: must fail: systemctl is-active test-service.servce2077machine: (finished: must fail: systemctl is-active test-service.servce, in 0.05 seconds)2078machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&12079machine # [ 89.354252] nixos[4122]: switching to system configuration /nix/store/v1aw53h0n41l09rqbqk7jqkrs46khqlh-nixos-system-machine-test2080machine # [ 89.358422] systemd[1]: Stopped target Local File Systems.2081machine # [ 89.361679] systemd[1]: Stopped target Remote File Systems.2082machine # [ 89.364205] systemd[1]: Stopped target test-target.target.2083machine # [ 89.776662] systemd[1]: Reload requested from client PID 4122 ('.switch-to-conf') (unit backdoor.service)...2084machine # [ 89.779627] systemd[1]: Reloading...2085machine # [ 90.331075] systemd[1]: Reloading finished in 549 ms.2086machine # [ 90.479158] systemd[616]: Reexecution requested from client PID 4191 ('switch-to-confi')...2087machine # [ 90.482945] systemd[616]: Reexecuting.2088machine # [ 90.509650] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2089machine # [ 90.668455] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2090machine # [ 90.677645] systemd[616]: Reload requested from client PID 4191 ('switch-to-confi')...2091machine # [ 90.680809] systemd[616]: Reloading...2092machine # [ 90.841873] systemd[616]: Reloading finished in 159 ms.2093machine # [ 90.847052] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2094machine # [ 90.857696] systemd[616]: Stopped Run user-specific NixOS activation.2095machine # [ 90.859478] systemd[616]: Stopping Run user-specific NixOS activation...2096machine # [ 90.864533] systemd[616]: Starting Run user-specific NixOS activation...2097machine # [ 90.903565] systemd[616]: Finished Run user-specific NixOS activation.2098machine # [ 90.908571] systemd[1]: Stopped target Reactivate sysinit units.2099machine # [ 90.910632] systemd[1]: Stopping Reactivate sysinit units...2100machine # [ 90.912845] systemd[1]: Reached target Reactivate sysinit units.2101machine # [ 90.915616] systemd[1]: Started test-timer.timer.2102machine # [ 90.919086] systemd[1]: Reached target Local File Systems.2103machine # [ 90.922730] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2104machine # [ 90.928542] systemd[1]: Starting Create SUID/SGID Wrappers...2105machine # [ 90.932077] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2106machine # [ 90.934336] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672107machine # [ 90.938940] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2108machine # [ 90.942775] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2109machine # [ 90.947189] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2110machine # [ 90.955526] systemd[1]: Reached target Remote File Systems.2111machine # [ 90.963108] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2112machine # [ 90.965972] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2113machine # [ 91.283751] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2114machine # [ 91.286706] systemd[1]: Finished Create SUID/SGID Wrappers.2115machine # [ 91.293321] systemd[1]: Starting linger-users.service...2116machine # [ 91.350410] systemd[1]: linger-users.service: Deactivated successfully.2117machine # [ 91.352342] systemd[1]: Finished linger-users.service.2118machine # [ 91.612697] nixos[4122]: finished switching to system configuration /nix/store/v1aw53h0n41l09rqbqk7jqkrs46khqlh-nixos-system-machine-test2119machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/timerModified/bin/switch-to-configuration test 2>&1, in 2.86 seconds)2120(finished: subtest: targets, in 14.99 seconds)2121subtest: paths2122machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&12123machine # [ 92.260495] nixos[4262]: switching to system configuration /nix/store/bldscfrpy4m0yhnzm95s6x048jisvyrg-nixos-system-machine-test2124machine # [ 92.264687] systemd[1]: Stopped target Remote File Systems.2125machine # [ 92.267875] systemd[1]: Stopped target Local File Systems.2126machine # [ 92.270377] systemd[1]: test-timer.timer: Deactivated successfully.2127machine # [ 92.272917] systemd[1]: Stopped test-timer.timer.2128machine # [ 92.801355] systemd[1]: Reload requested from client PID 4262 ('.switch-to-conf') (unit backdoor.service)...2129machine # [ 92.804178] systemd[1]: Reloading...2130machine # [ 93.480358] systemd[1]: Reloading finished in 673 ms.2131machine # [ 93.631376] systemd[616]: Reexecution requested from client PID 4331 ('switch-to-confi')...2132machine # [ 93.634887] systemd[616]: Reexecuting.2133machine # [ 93.664511] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2134machine # [ 93.828150] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2135machine # [ 93.837571] systemd[616]: Reload requested from client PID 4331 ('switch-to-confi')...2136machine # [ 93.840694] systemd[616]: Reloading...2137machine # [ 94.010118] systemd[616]: Reloading finished in 167 ms.2138machine # [ 94.016098] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2139machine # [ 94.027430] systemd[616]: Stopped Run user-specific NixOS activation.2140machine # [ 94.029160] systemd[616]: Stopping Run user-specific NixOS activation...2141machine # [ 94.033563] systemd[616]: Starting Run user-specific NixOS activation...2142machine # [ 94.077250] systemd[616]: Finished Run user-specific NixOS activation.2143machine # [ 94.081809] systemd[1]: Stopped target Reactivate sysinit units.2144machine # [ 94.084721] systemd[1]: Stopping Reactivate sysinit units...2145machine # [ 94.086292] systemd[1]: Reached target Reactivate sysinit units.2146machine # [ 94.088662] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2147machine # [ 94.091616] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2148machine # [ 94.096950] systemd[1]: Started test-watch.path.2149machine # [ 94.101716] systemd[1]: Reached target Local File Systems.2150machine # [ 94.105102] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2151machine # [ 94.111485] systemd[1]: Starting Create SUID/SGID Wrappers...2152machine # [ 94.115069] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2153machine # [ 94.117302] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672154machine # [ 94.122329] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2155machine # [ 94.125948] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2156machine # [ 94.130257] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2157machine # [ 94.139509] systemd[1]: Reached target Remote File Systems.2158machine # [ 94.449589] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2159machine # [ 94.452550] systemd[1]: Finished Create SUID/SGID Wrappers.2160machine # [ 94.458978] systemd[1]: Starting linger-users.service...2161machine # [ 94.514815] systemd[1]: linger-users.service: Deactivated successfully.2162machine # [ 94.517448] systemd[1]: Finished linger-users.service.2163machine # [ 94.774295] nixos[4262]: finished switching to system configuration /nix/store/bldscfrpy4m0yhnzm95s6x048jisvyrg-nixos-system-machine-test2164machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/path/bin/switch-to-configuration test 2>&1, in 3.15 seconds)2165machine: must fail: test -f /testpath-modified2166machine: (finished: must fail: test -f /testpath-modified, in 0.01 seconds)2167machine: must succeed: touch /testpath2168machine: (finished: must succeed: touch /testpath, in 0.02 seconds)2169machine: waiting for success: test -f /testpath-modified2170machine # [ 94.820502] systemd[1]: Starting test-watch.service...2171machine # [ 94.858137] systemd[1]: Finished test-watch.service.2172machine: (finished: waiting for success: test -f /testpath-modified, in 1.07 seconds)2173machine: must succeed: rm /testpath /testpath-modified2174machine: (finished: must succeed: rm /testpath /testpath-modified, in 0.03 seconds)2175machine # [ 95.945407] systemd[1]: test-watch.service: Deactivated successfully.2176machine # [ 95.947808] systemd[1]: Stopped test-watch.service.2177machine # Stopping 'test-watch.service', but its triggering units are still active:2178machine # test-watch.path2179machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&12180machine # [ 96.535471] nixos[4427]: switching to system configuration /nix/store/x2fbqagjyfjx0j2a5vzbimxyn0dfzizy-nixos-system-machine-test2181machine # [ 96.539678] systemd[1]: Stopped target Local File Systems.2182machine # [ 96.542389] systemd[1]: Stopped target Remote File Systems.2183machine # [ 97.038353] systemd[1]: Reload requested from client PID 4427 ('.switch-to-conf') (unit backdoor.service)...2184machine # [ 97.041247] systemd[1]: Reloading...2185machine # [ 97.655468] systemd[1]: Reloading finished in 611 ms.2186machine # [ 97.802284] systemd[616]: Reexecution requested from client PID 4497 ('switch-to-confi')...2187machine # [ 97.804671] systemd[616]: Reexecuting.2188machine # [ 97.834403] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2189machine # [ 97.993233] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2190machine # [ 98.003577] systemd[616]: Reload requested from client PID 4497 ('switch-to-confi')...2191machine # [ 98.006222] systemd[616]: Reloading...2192machine # [ 98.166826] systemd[616]: Reloading finished in 158 ms.2193machine # [ 98.172994] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2194machine # [ 98.184870] systemd[616]: Stopped Run user-specific NixOS activation.2195machine # [ 98.186559] systemd[616]: Stopping Run user-specific NixOS activation...2196machine # [ 98.190831] systemd[616]: Starting Run user-specific NixOS activation...2197machine # [ 98.229936] systemd[616]: Finished Run user-specific NixOS activation.2198machine # [ 98.234927] systemd[1]: Stopped target Reactivate sysinit units.2199machine # [ 98.237481] systemd[1]: Stopping Reactivate sysinit units...2200machine # [ 98.240336] systemd[1]: Reached target Reactivate sysinit units.2201machine # [ 98.242977] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2202machine # [ 98.246305] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2203machine # [ 98.255660] systemd[1]: Reached target Local File Systems.2204machine # [ 98.262172] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2205machine # [ 98.268326] systemd[1]: Starting Create SUID/SGID Wrappers...2206machine # [ 98.271497] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2207machine # [ 98.273696] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672208machine # [ 98.279306] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2209machine # [ 98.283211] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2210machine # [ 98.285900] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2211machine # [ 98.289246] systemd[1]: Reached target Remote File Systems.2212machine # [ 98.590740] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2213machine # [ 98.596622] systemd[1]: Finished Create SUID/SGID Wrappers.2214machine # [ 98.602893] systemd[1]: Starting linger-users.service...2215machine # [ 98.655909] systemd[1]: linger-users.service: Deactivated successfully.2216machine # [ 98.658605] systemd[1]: Finished linger-users.service.2217machine # [ 98.914884] nixos[4427]: finished switching to system configuration /nix/store/x2fbqagjyfjx0j2a5vzbimxyn0dfzizy-nixos-system-machine-test2218machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/pathModified/bin/switch-to-configuration test 2>&1, in 2.97 seconds)2219machine: must succeed: touch /testpath2220machine: (finished: must succeed: touch /testpath, in 0.02 seconds)2221machine: must fail: test -f /testpath-modified2222machine: (finished: must fail: test -f /testpath-modified, in 0.01 seconds)2223machine: must succeed: touch /testpath22224machine: (finished: must succeed: touch /testpath2, in 0.02 seconds)2225machine: waiting for success: test -f /testpath-modified2226machine # [ 98.986106] systemd[1]: Starting test-watch.service...2227machine # [ 99.020698] systemd[1]: Finished test-watch.service.2228machine: (finished: waiting for success: test -f /testpath-modified, in 1.08 seconds)2229(finished: subtest: paths, in 8.43 seconds)2230subtest: slices2231machine: must succeed: echo 0 > /proc/sys/vm/panic_on_oom2232machine: (finished: must succeed: echo 0 > /proc/sys/vm/panic_on_oom, in 0.03 seconds)2233machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&12234machine # [ 100.728474] nixos[4592]: switching to system configuration /nix/store/w38b9gzi5xsn8gmlq8q0b8jhgk2rfz0g-nixos-system-machine-test2235machine # [ 100.732835] systemd[1]: test-watch.service: Deactivated successfully.2236machine # [ 100.736538] systemd[1]: Stopped test-watch.service.2237machine # [ 100.739720] systemd[1]: Stopped target Local File Systems.2238machine # [ 100.743331] systemd[1]: Starting test-watch.service...2239machine # [ 100.746379] systemd[1]: test-watch.path: Deactivated successfully.2240machine # [ 100.748555] systemd[1]: Stopped test-watch.path.2241machine # [ 100.751263] systemd[1]: Stopped target Remote File Systems.2242machine # [ 100.785251] systemd[1]: Finished test-watch.service.2243machine # [ 101.215726] systemd[1]: Reload requested from client PID 4592 ('.switch-to-conf') (unit backdoor.service)...2244machine # [ 101.218680] systemd[1]: Reloading...2245machine # [ 101.799532] systemd[1]: Reloading finished in 578 ms.2246machine # [ 101.938325] systemd[616]: Reexecution requested from client PID 4663 ('switch-to-confi')...2247machine # [ 101.942096] systemd[616]: Reexecuting.2248machine # [ 101.971318] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2249machine # [ 102.132676] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2250machine # [ 102.141955] systemd[616]: Reload requested from client PID 4663 ('switch-to-confi')...2251machine # [ 102.144623] systemd[616]: Reloading...2252machine # [ 102.307544] systemd[616]: Reloading finished in 161 ms.2253machine # [ 102.313991] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2254machine # [ 102.324964] systemd[616]: Stopped Run user-specific NixOS activation.2255machine # [ 102.326819] systemd[616]: Stopping Run user-specific NixOS activation...2256machine # [ 102.331800] systemd[616]: Starting Run user-specific NixOS activation...2257machine # [ 102.373119] systemd[616]: Finished Run user-specific NixOS activation.2258machine # [ 102.377941] systemd[1]: Stopped target Reactivate sysinit units.2259machine # [ 102.380359] systemd[1]: Stopping Reactivate sysinit units...2260machine # [ 102.382567] systemd[1]: Reached target Reactivate sysinit units.2261machine # [ 102.386993] systemd[1]: Reached target Local File Systems.2262machine # [ 102.389842] systemd[1]: Reached target Remote File Systems.2263machine # [ 102.402289] systemd[1]: Starting linger-users.service...2264machine # [ 102.410634] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2265machine # [ 102.415258] systemd[1]: Starting Create SUID/SGID Wrappers...2266machine # [ 102.417198] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2267machine # [ 102.421322] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672268machine # [ 102.428222] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2269machine # [ 102.431514] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2270machine # [ 102.434256] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2271machine # [ 102.439277] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2272machine # [ 102.441610] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2273machine # [ 102.502121] systemd[1]: linger-users.service: Deactivated successfully.2274machine # [ 102.504644] systemd[1]: Finished linger-users.service.2275machine # [ 102.755320] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2276machine # [ 102.758403] systemd[1]: Finished Create SUID/SGID Wrappers.2277machine # [ 103.017879] nixos[4592]: finished switching to system configuration /nix/store/w38b9gzi5xsn8gmlq8q0b8jhgk2rfz0g-nixos-system-machine-test2278machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/slice/bin/switch-to-configuration test 2>&1, in 2.95 seconds)2279machine: must fail: systemctl start testservice.service2280machine # [ 103.083724] systemd[1]: Created slice Slice /testslice.2281machine # [ 103.344219] systemd invoked oom-killer: gfp_mask=0x400dc0(GFP_KERNEL_ACCOUNT|__GFP_ZERO), order=0, oom_score_adj=02282machine # [ 103.346571] CPU: 0 UID: 0 PID: 1 Comm: systemd Not tainted 6.18.50 #1-NixOS PREEMPT(lazy)2283machine # [ 103.346575] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS rel-1.17.0-0-gb52ca86e094d-prebuilt.qemu.org 04/01/20142284machine # [ 103.346581] Call Trace:2285machine # [ 103.346593] <TASK>2286machine # [ 103.346595] dump_stack_lvl+0x5d/0x802287machine # [ 103.346618] dump_header+0x43/0x1aa2288machine # [ 103.346635] out_of_memory.cold+0x8/0x782289machine # [ 103.346639] mem_cgroup_out_of_memory+0xc2/0xd02290machine # [ 103.346664] try_charge_memcg+0x442/0x6602291machine # [ 103.346675] obj_cgroup_charge_account+0xf8/0x4202292machine # [ 103.346678] __memcg_slab_post_alloc_hook+0x105/0x3702293machine # [ 103.346681] ? mem_cgroup_css_alloc+0x28f/0x8e02294machine # [ 103.346705] __kmalloc_cache_noprof+0x4ba/0x5f02295machine # [ 103.346714] ? srso_alias_return_thunk+0x5/0xfbef52296machine # [ 103.346718] ? __xa_alloc+0xa3/0x1402297machine # [ 103.346732] ? mem_cgroup_css_alloc+0x28f/0x8e02298machine # [ 103.346734] ? srso_alias_return_thunk+0x5/0xfbef52299machine # [ 103.346736] mem_cgroup_css_alloc+0x28f/0x8e02300machine # [ 103.346740] cgroup_apply_control_enable+0x132/0x3302301machine # [ 103.346765] cgroup_mkdir+0x1ef/0x5602302machine # [ 103.346776] kernfs_iop_mkdir+0x76/0xe02303machine # [ 103.346791] vfs_mkdir+0x19a/0x2702304machine # [ 103.346800] do_mkdirat+0x14d/0x1902305machine # [ 103.346804] __x64_sys_mkdir+0x49/0x702306machine # [ 103.346806] ? srso_alias_return_thunk+0x5/0xfbef52307machine # [ 103.346808] do_syscall_64+0xd6/0x7c02308machine # [ 103.346811] ? srso_alias_return_thunk+0x5/0xfbef52309machine # [ 103.346813] ? step_into+0x430/0x8102310machine # [ 103.346815] ? srso_alias_return_thunk+0x5/0xfbef52311machine # [ 103.346816] ? terminate_walk+0xef/0x1002312machine # [ 103.346818] ? srso_alias_return_thunk+0x5/0xfbef52313machine # [ 103.346820] ? path_lookupat+0xc9/0x1802314machine # [ 103.346822] ? srso_alias_return_thunk+0x5/0xfbef52315machine # [ 103.346823] ? filename_lookup+0xf7/0x2002316machine # [ 103.346830] ? srso_alias_return_thunk+0x5/0xfbef52317machine # [ 103.346831] ? inode_set_ctime_to_ts+0x38/0xb02318machine # [ 103.346837] ? srso_alias_return_thunk+0x5/0xfbef52319machine # [ 103.346838] ? generic_fillattr+0x4d/0x1302320machine # [ 103.346844] ? srso_alias_return_thunk+0x5/0xfbef52321machine # [ 103.346846] ? srso_alias_return_thunk+0x5/0xfbef52322machine # [ 103.346847] ? srso_alias_return_thunk+0x5/0xfbef52323machine # [ 103.346849] ? cp_new_stat+0x130/0x1702324machine # [ 103.346854] ? srso_alias_return_thunk+0x5/0xfbef52325machine # [ 103.346855] ? __do_sys_newfstatat+0x4a/0x802326machine # [ 103.346861] ? srso_alias_return_thunk+0x5/0xfbef52327machine # [ 103.346862] ? do_syscall_64+0x110/0x7c02328machine # [ 103.346865] ? __x64_sys_openat+0x61/0xa02329machine # [ 103.346869] ? srso_alias_return_thunk+0x5/0xfbef52330machine # [ 103.346871] ? do_syscall_64+0x110/0x7c02331machine # [ 103.346873] ? srso_alias_return_thunk+0x5/0xfbef52332machine # [ 103.346875] ? do_syscall_64+0x110/0x7c02333machine # [ 103.346877] ? srso_alias_return_thunk+0x5/0xfbef52334machine # [ 103.346879] ? do_user_addr_fault+0x21a/0x6902335machine # [ 103.346892] ? srso_alias_return_thunk+0x5/0xfbef52336machine # [ 103.346898] ? srso_alias_return_thunk+0x5/0xfbef52337machine # [ 103.346900] entry_SYSCALL_64_after_hwframe+0x77/0x7f2338machine # [ 103.346902] RIP: 0033:0x7fd9eed2402b2339machine # [ 103.346913] 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 482340machine # [ 103.346914] RSP: 002b:00007ffcf8eb2ec8 EFLAGS: 00000246 ORIG_RAX: 00000000000000532341machine # [ 103.346920] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fd9eed2402b2342machine # [ 103.346922] RDX: 0000000000000000 RSI: 00000000000001ed RDI: 000055a018a677a02343machine # [ 103.346923] RBP: 00007ffcf8eb2ef0 R08: 0000000000000000 R09: 00000000000000002344machine # [ 103.346923] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000000000002345machine # [ 103.346924] R13: 00000000000001a8 R14: 0000000000000000 R15: 0000000000003f082346machine # [ 103.346928] </TASK>2347machine # [ 103.415087] memory: usage 0kB, limit 0kB, failcnt 182348machine # [ 103.416497] swap: usage 0kB, limit 9007199254740988kB, failcnt 02349machine # [ 103.418154] Memory cgroup stats for /testslice.slice:2350machine # [ 103.418194] anon 02351machine # [ 103.420260] file 02352machine # [ 103.421130] kernel 02353machine # [ 103.421715] kernel_stack 02354machine # [ 103.422848] pagetables 02355machine # [ 103.423526] sec_pagetables 02356machine # [ 103.424667] percpu 02357machine # [ 103.425641] sock 02358machine # [ 103.426528] vmalloc 02359machine # [ 103.427427] shmem 02360machine # [ 103.428307] zswap 02361machine # [ 103.429235] zswapped 02362machine # [ 103.430146] file_mapped 02363machine # [ 103.431096] file_dirty 02364machine # [ 103.431724] file_writeback 02365machine # [ 103.432896] swapcached 02366machine # [ 103.433593] anon_thp 02367machine # [ 103.434623] file_thp 02368machine # [ 103.435560] shmem_thp 02369machine # [ 103.436483] inactive_anon 02370machine # [ 103.437458] active_anon 02371machine # [ 103.438406] inactive_file 02372machine # [ 103.439388] active_file 02373machine # [ 103.440338] unevictable 02374machine # [ 103.441307] slab_reclaimable 02375machine # [ 103.442332] slab_unreclaimable 02376machine # [ 103.443423] slab 02377machine # [ 103.444274] hugetlb 02378machine # [ 103.445166] workingset_refault_anon 02379machine # [ 103.446289] workingset_refault_file 02380machine # [ 103.447411] workingset_activate_anon 02381machine # [ 103.448566] workingset_activate_file 02382machine # [ 103.449729] workingset_restore_anon 02383machine # [ 103.450951] workingset_restore_file 02384machine # [ 103.452114] workingset_nodereclaim 02385machine # [ 103.453257] pgdemote_kswapd 02386machine # [ 103.454263] pgdemote_direct 02387machine # [ 103.455267] pgdemote_khugepaged 02388machine # [ 103.456312] pgdemote_proactive 02389machine # [ 103.457339] pgpromote_success 02390machine # [ 103.458350] pgscan 02391machine # [ 103.459201] pgsteal 02392machine # [ 103.460075] pswpin 02393machine # [ 103.460609] pswpout 02394machine # [ 103.461556] pgscan_kswapd 02395machine # [ 103.462542] pgscan_direct 02396machine # [ 103.463539] pgscan_khugepaged 02397machine # [ 103.464566] pgscan_proactive 02398machine # [ 103.465553] pgsteal_kswapd 02399machine # [ 103.466535] pgsteal_direct 02400machine # [ 103.467488] pgsteal_khugepaged 02401machine # [ 103.468542] pgsteal_proactive 02402machine # [ 103.469582] pgfault 02403machine # [ 103.470431] pgmajfault 02404machine # [ 103.471325] pgrefill 02405machine # [ 103.472190] pgactivate 02406machine # [ 103.473075] pgdeactivate 02407machine # [ 103.473706] pglazyfree 02408machine # [ 103.474716] pglazyfreed 02409machine # [ 103.475656] swpin_zero 02410machine # [ 103.476572] swpout_zero 02411machine # [ 103.477494] zswpin 02412machine # [ 103.478333] zswpout 02413machine # [ 103.479184] zswpwb 02414machine # [ 103.479701] thp_fault_alloc 02415machine # [ 103.480815] thp_collapse_alloc 02416machine # [ 103.481550] thp_swpout 02417machine # [ 103.482552] thp_swpout_fallback 02418machine # [ 103.483626] numa_pages_migrated 02419machine # [ 103.484683] numa_pte_updates 02420machine # [ 103.485688] numa_hint_faults 02421machine # [ 103.486716] Tasks state (memory values in pages):2422machine # [ 103.488038] [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name2423machine # [ 103.490426] Out of memory and no killable processes...2424machine # [ 103.233252] systemd[1]: testservice.service: Failed to create cgroup /testslice.slice/testservice.service: Cannot allocate memory2425machine # [ 103.236424] systemd[1]: testservice.service: Failed to spawn 'start' task: Cannot allocate memory2426machine # [ 103.239189] systemd[1]: testservice.service: Failed with result 'resources'.2427machine # [ 103.243106] systemd[1]: Failed to start testservice.service.2428machine # Job for testservice.service failed because of unavailable resources or another system error.2429machine # See "systemctl status testservice.service" and "journalctl -xeu testservice.service" for details.2430machine: (finished: must fail: systemctl start testservice.service, in 0.23 seconds)2431machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&12432machine # [ 103.898582] nixos[4738]: switching to system configuration /nix/store/ycm9xk4f5bafry36ggzix3jd8zw2i0k4-nixos-system-machine-test2433machine # [ 103.902464] systemd[1]: Stopped target Local File Systems.2434machine # [ 103.905191] systemd[1]: Stopped target Remote File Systems.2435machine # [ 104.386527] systemd[1]: Reload requested from client PID 4738 ('.switch-to-conf') (unit backdoor.service)...2436machine # [ 104.389405] systemd[1]: Reloading...2437machine # [ 105.048891] systemd[1]: Reloading finished in 657 ms.2438machine # [ 105.199763] systemd[616]: Reexecution requested from client PID 4807 ('switch-to-confi')...2439machine # [ 105.202632] systemd[616]: Reexecuting.2440machine # [ 105.229537] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2441machine # [ 105.388666] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2442machine # [ 105.397161] systemd[616]: Reload requested from client PID 4807 ('switch-to-confi')...2443machine # [ 105.399903] systemd[616]: Reloading...2444machine # [ 105.565952] systemd[616]: Reloading finished in 164 ms.2445machine # [ 105.572069] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2446machine # [ 105.582948] systemd[616]: Stopped Run user-specific NixOS activation.2447machine # [ 105.585737] systemd[616]: Stopping Run user-specific NixOS activation...2448machine # [ 105.589742] systemd[616]: Starting Run user-specific NixOS activation...2449machine # [ 105.629567] systemd[616]: Finished Run user-specific NixOS activation.2450machine # [ 105.635327] systemd[1]: Stopped target Reactivate sysinit units.2451machine # [ 105.637472] systemd[1]: Stopping Reactivate sysinit units...2452machine # [ 105.639186] systemd[1]: Reached target Reactivate sysinit units.2453machine # [ 105.643399] systemd[1]: Reached target Remote File Systems.2454machine # [ 105.654102] systemd[1]: Reached target Local File Systems.2455machine # [ 105.655599] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2456machine # [ 105.661270] systemd[1]: Starting Create SUID/SGID Wrappers...2457machine # [ 105.663695] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2458machine # [ 105.665922] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672459machine # [ 105.669701] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2460machine # [ 105.677853] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2461machine # [ 105.680152] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2462machine # [ 105.685232] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2463machine # [ 105.687524] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2464machine # [ 106.018664] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2465machine # [ 106.021889] systemd[1]: Finished Create SUID/SGID Wrappers.2466machine # [ 106.028700] systemd[1]: Starting linger-users.service...2467machine # [ 106.085909] systemd[1]: linger-users.service: Deactivated successfully.2468machine # [ 106.087941] systemd[1]: Finished linger-users.service.2469machine # [ 106.347777] nixos[4738]: finished switching to system configuration /nix/store/ycm9xk4f5bafry36ggzix3jd8zw2i0k4-nixos-system-machine-test2470machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/sliceModified/bin/switch-to-configuration test 2>&1, in 3.10 seconds)2471machine: must succeed: systemctl start testservice.service2472machine # [ 106.411208] systemd[1]: Starting testservice.service...2473machine # [ 106.438443] systemd[1]: Finished testservice.service.2474machine: (finished: must succeed: systemctl start testservice.service, in 0.08 seconds)2475machine: must succeed: echo 1 > /proc/sys/vm/panic_on_oom2476machine: (finished: must succeed: echo 1 > /proc/sys/vm/panic_on_oom, in 0.01 seconds)2477(finished: subtest: slices, in 6.40 seconds)2478subtest: dbus reloads2479machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&12480machine # [ 106.985505] nixos[4887]: switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test2481machine # [ 106.989695] systemd[1]: testservice.service: Deactivated successfully.2482machine # [ 106.993063] systemd[1]: Stopped testservice.service.2483machine # [ 106.996137] systemd[1]: Removed slice Slice /testslice.2484machine # [ 106.998876] systemd[1]: Stopped target Remote File Systems.2485machine # [ 107.004503] systemd[1]: Stopped target Local File Systems.2486machine # [ 107.437601] systemd[1]: Reload requested from client PID 4887 ('.switch-to-conf') (unit backdoor.service)...2487machine # [ 107.440562] systemd[1]: Reloading...2488machine # [ 108.007283] systemd[1]: Reloading finished in 563 ms.2489machine # [ 108.157200] systemd[616]: Reexecution requested from client PID 4956 ('switch-to-confi')...2490machine # [ 108.161987] systemd[616]: Reexecuting.2491machine # [ 108.188828] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2492machine # [ 108.359161] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2493machine # [ 108.368734] systemd[616]: Reload requested from client PID 4956 ('switch-to-confi')...2494machine # [ 108.371292] systemd[616]: Reloading...2495machine # [ 108.539113] systemd[616]: Reloading finished in 165 ms.2496machine # [ 108.546077] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2497machine # [ 108.557681] systemd[616]: Stopped Run user-specific NixOS activation.2498machine # [ 108.559381] systemd[616]: Stopping Run user-specific NixOS activation...2499machine # [ 108.563618] systemd[616]: Starting Run user-specific NixOS activation...2500machine # [ 108.606883] systemd[616]: Finished Run user-specific NixOS activation.2501machine # [ 108.611546] systemd[1]: Stopped target Reactivate sysinit units.2502machine # [ 108.613882] systemd[1]: Stopping Reactivate sysinit units...2503machine # [ 108.616087] systemd[1]: Reached target Reactivate sysinit units.2504machine # [ 108.623709] systemd[1]: Reached target Remote File Systems.2505machine # [ 108.628802] systemd[1]: Starting linger-users.service...2506machine # [ 108.639106] systemd[1]: Reached target Local File Systems.2507machine # [ 108.641596] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2508machine # [ 108.647375] systemd[1]: Starting Create SUID/SGID Wrappers...2509machine # [ 108.649700] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2510machine # [ 108.656192] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672511machine # [ 108.661550] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2512machine # [ 108.665201] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2513machine # [ 108.667589] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2514machine # [ 108.673517] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2515machine # [ 108.675812] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2516machine # [ 108.733798] systemd[1]: linger-users.service: Deactivated successfully.2517machine # [ 108.736970] systemd[1]: Finished linger-users.service.2518machine # [ 109.027649] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2519machine # [ 109.030438] systemd[1]: Finished Create SUID/SGID Wrappers.2520machine # [ 109.291314] nixos[4887]: finished switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test2521machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.85 seconds)2522machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&12523machine # [ 109.977191] nixos[5027]: switching to system configuration /nix/store/p0pylzqpzpvycfpsz5ad42q2q2p4j36k-nixos-system-machine-test2524machine # [ 109.982472] systemd[1]: Stopped target Remote File Systems.2525machine # [ 109.985226] systemd[1]: Stopped target Local File Systems.2526machine # [ 110.490383] systemd[1]: Reload requested from client PID 5027 ('.switch-to-conf') (unit backdoor.service)...2527machine # [ 110.493287] systemd[1]: Reloading...2528machine # [ 111.076507] systemd[1]: Reloading finished in 580 ms.2529machine # [ 111.224372] systemd[616]: Reexecution requested from client PID 5096 ('switch-to-confi')...2530machine # [ 111.228166] systemd[616]: Reexecuting.2531machine # [ 111.259787] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2532machine # [ 111.426558] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2533machine # [ 111.434885] systemd[616]: Reload requested from client PID 5096 ('switch-to-confi')...2534machine # [ 111.437719] systemd[616]: Reloading...2535machine # [ 111.603370] systemd[616]: Reloading finished in 163 ms.2536machine # [ 111.610059] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2537machine # [ 111.620921] systemd[616]: Stopped Run user-specific NixOS activation.2538machine # [ 111.623570] systemd[616]: Stopping Run user-specific NixOS activation...2539machine # [ 111.627763] systemd[616]: Starting Run user-specific NixOS activation...2540machine # [ 111.670931] systemd[616]: Finished Run user-specific NixOS activation.2541machine # [ 111.675967] systemd[1]: Stopped target Reactivate sysinit units.2542machine # [ 111.679123] systemd[1]: Stopping Reactivate sysinit units...2543machine # [ 111.681212] systemd[1]: Reached target Reactivate sysinit units.2544machine # [ 111.685085] dbus-broker-launch[488]: Caught SIGHUP, trigger reload.2545machine # [ 111.686986] systemd[1]: Reloading D-Bus System Message Bus...2546machine # [ 111.703422] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...2547machine # [ 111.706694] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'2548machine # [ 111.708647] dbus-broker-launch[488]: Invalid user-name in /nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2549machine # [ 111.731491] systemd[1]: Reloaded D-Bus System Message Bus.2550machine # [ 111.736448] systemd[1]: Reached target Local File Systems.2551machine # [ 111.745757] systemd[1]: Reached target Remote File Systems.2552machine # [ 111.748690] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2553machine # [ 111.754398] systemd[1]: Starting Create SUID/SGID Wrappers...2554machine # [ 111.755961] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2555machine # [ 111.760479] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672556machine # [ 111.765785] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2557machine # [ 111.771193] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2558machine # [ 111.773463] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2559machine # [ 111.776503] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2560machine # [ 111.778743] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2561machine # [ 112.120230] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2562machine # [ 112.123130] systemd[1]: Finished Create SUID/SGID Wrappers.2563machine # [ 112.129579] systemd[1]: Starting linger-users.service...2564machine # [ 112.185589] systemd[1]: linger-users.service: Deactivated successfully.2565machine # [ 112.188255] systemd[1]: Finished linger-users.service.2566machine # [ 112.449681] nixos[5027]: finished switching to system configuration /nix/store/p0pylzqpzpvycfpsz5ad42q2q2p4j36k-nixos-system-machine-test2567machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/dbusReload/bin/switch-to-configuration test 2>&1, in 3.16 seconds)2568(finished: subtest: dbus reloads, in 6.01 seconds)2569subtest: generators2570machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&12571machine # [ 113.108230] nixos[5167]: switching to system configuration /nix/store/6v3wyvh642rlk08jg862kz5a69ch4c8k-nixos-system-machine-test2572machine # [ 113.112628] systemd[1]: Stopped target Remote File Systems.2573machine # [ 113.115476] systemd[1]: Stopped target Local File Systems.2574machine # [ 113.616987] systemd[1]: Reload requested from client PID 5167 ('.switch-to-conf') (unit backdoor.service)...2575machine # [ 113.620185] systemd[1]: Reloading...2576machine # [ 114.220717] systemd[1]: Reloading finished in 598 ms.2577machine # [ 114.371971] systemd[616]: Reexecution requested from client PID 5238 ('switch-to-confi')...2578machine # [ 114.376551] systemd[616]: Reexecuting.2579machine # [ 114.403596] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2580machine # [ 114.574791] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2581machine # [ 114.584922] systemd[616]: Reload requested from client PID 5238 ('switch-to-confi')...2582machine # [ 114.587617] systemd[616]: Reloading...2583machine # [ 114.753951] systemd[616]: Reloading finished in 164 ms.2584machine # [ 114.759051] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2585machine # [ 114.770103] systemd[616]: Stopped Run user-specific NixOS activation.2586machine # [ 114.771769] systemd[616]: Stopping Run user-specific NixOS activation...2587machine # [ 114.776855] systemd[616]: Starting Run user-specific NixOS activation...2588machine # [ 114.818652] systemd[616]: Finished Run user-specific NixOS activation.2589machine # [ 114.823280] systemd[1]: Stopped target Reactivate sysinit units.2590machine # [ 114.825768] systemd[1]: Stopping Reactivate sysinit units...2591machine # [ 114.827438] systemd[1]: Reached target Reactivate sysinit units.2592machine # [ 114.831536] dbus-broker-launch[488]: Caught SIGHUP, trigger reload.2593machine # [ 114.833871] systemd[1]: Reloading D-Bus System Message Bus...2594machine # [ 114.852757] dbus-broker-launch[488]: Looking up NSS user entry for 'systemd-timesync'...2595machine # [ 114.856158] dbus-broker-launch[488]: NSS returned no entry for 'systemd-timesync'2596machine # [ 114.858176] dbus-broker-launch[488]: Invalid user-name in /nix/store/709939rwv1hbi05siqwd0j0z85hbznn0-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"2597machine # [ 114.878727] systemd[1]: Reloaded D-Bus System Message Bus.2598machine # [ 114.882489] systemd[1]: Reached target Local File Systems.2599machine # [ 114.890275] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2600machine # [ 114.895407] systemd[1]: Starting Create SUID/SGID Wrappers...2601machine # [ 114.898470] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2602machine # [ 114.900718] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672603machine # [ 114.905272] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2604machine # [ 114.910388] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2605machine # [ 114.912649] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2606machine # [ 114.919109] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2607machine # [ 114.922129] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2608machine # [ 114.928749] systemd[1]: Reached target Remote File Systems.2609machine # [ 115.258271] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2610machine # [ 115.261274] systemd[1]: Finished Create SUID/SGID Wrappers.2611machine # [ 115.267657] systemd[1]: Starting linger-users.service...2612machine # [ 115.328077] systemd[1]: linger-users.service: Deactivated successfully.2613machine # [ 115.330731] systemd[1]: Finished linger-users.service.2614machine # [ 115.589943] nixos[5167]: finished switching to system configuration /nix/store/6v3wyvh642rlk08jg862kz5a69ch4c8k-nixos-system-machine-test2615machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/specialisation/generators/bin/switch-to-configuration test 2>&1, in 3.14 seconds)2616machine: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service2617machine # [ 115.656323] systemd[1]: Started simple-generated.service.2618machine: (finished: must succeed: systemctl start simple-generated.service && systemctl is-active simple-generated.service, in 0.07 seconds)2619machine: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&12620machine # [ 116.237564] nixos[5316]: switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test2621machine # [ 116.241454] systemd[1]: Stopped target Remote File Systems.2622machine # [ 116.244587] systemd[1]: Stopped target Local File Systems.2623machine # [ 116.688072] systemd[1]: Reload requested from client PID 5316 ('.switch-to-conf') (unit backdoor.service)...2624machine # [ 116.691151] systemd[1]: Reloading...2625machine # [ 117.275809] systemd[1]: Reloading finished in 582 ms.2626machine # [ 117.423731] systemd[616]: Reexecution requested from client PID 5386 ('switch-to-confi')...2627machine # [ 117.426972] systemd[616]: Reexecuting.2628machine # [ 117.453468] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2629machine # [ 117.627901] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2630machine # [ 117.637621] systemd[616]: Reload requested from client PID 5386 ('switch-to-confi')...2631machine # [ 117.640277] systemd[616]: Reloading...2632machine # [ 117.806533] systemd[616]: Reloading finished in 164 ms.2633machine # [ 117.813081] systemd[616]: Failed to adjust io pressure threshold, ignoring: Device or resource busy2634machine # [ 117.823614] systemd[616]: Stopped Run user-specific NixOS activation.2635machine # [ 117.825469] systemd[616]: Stopping Run user-specific NixOS activation...2636machine # [ 117.830676] systemd[616]: Starting Run user-specific NixOS activation...2637machine # [ 117.872307] systemd[616]: Finished Run user-specific NixOS activation.2638machine # [ 117.877319] systemd[1]: Stopped target Reactivate sysinit units.2639machine # [ 117.880247] systemd[1]: Stopping Reactivate sysinit units...2640machine # [ 117.882113] systemd[1]: Reached target Reactivate sysinit units.2641machine # [ 117.884868] systemd[1]: Reached target Remote File Systems.2642machine # [ 117.889802] systemd[1]: Starting linger-users.service...2643machine # [ 117.895096] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os2644machine # [ 117.897963] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki2645machine # [ 117.914190] systemd[1]: Reached target Local File Systems.2646machine # [ 117.916228] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore2647machine # [ 117.922098] systemd[1]: Starting Create SUID/SGID Wrappers...2648machine # [ 117.923656] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.2649machine # [ 117.925869] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc672650machine # [ 117.932700] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore2651machine # [ 117.936583] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2652machine # [ 117.941158] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os2653machine # [ 117.996563] systemd[1]: linger-users.service: Deactivated successfully.2654machine # [ 117.999308] systemd[1]: Finished linger-users.service.2655machine # [ 118.285947] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.2656machine # [ 118.288858] systemd[1]: Finished Create SUID/SGID Wrappers.2657machine # [ 118.549672] nixos[5316]: finished switching to system configuration /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test2658machine: (finished: must succeed: /nix/store/pmj0ky0hxp7x0lalc4zfnvirgbjjfyz1-nixos-system-machine-test/bin/switch-to-configuration test 2>&1, in 2.89 seconds)2659machine: must succeed: systemctl is-active simple-generated.service2660machine: (finished: must succeed: systemctl is-active simple-generated.service, in 0.05 seconds)2661(finished: subtest: generators, in 6.14 seconds)2662(finished: run the VM test script, in 119.93 seconds)2663test script finished in 119.98s2664cleanup2665kill QemuMachine (pid 45)2666machine # qemu-system-x86_64: terminating on signal 15 from pid 39 (/nix/store/b5bpi6zfajzzrwwpgba2q6li3nnya4bs-python3-3.14.7/bin/python3.14)2667(finished: cleanup, in 0.16 seconds)2668additionally exposed symbols:2669 machine, other,2670 vlan1,2671 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