nixbot

1these 31 derivations will be built:2  /nix/store/05qnr2idpsslhv9fwfslc9q8xfqr3253-X-Restart-Triggers-flakelet-static.drv3  /nix/store/yf823g1nflx38h25gz3bmhxkqrxdfkj7-system-path.drv4  /nix/store/1w88cqhy9yqxbwm8w25492lksy0a26ib-dbus-1.drv5  /nix/store/6494m74xnsn8505wh3hbkyh7gjzyj222-X-Restart-Triggers-dbus-broker.drv6  /nix/store/0mq64vp3fvxshg3jrsy5s7m69f2d3jjk-unit-dbus-broker.service.drv7  /nix/store/0nnmyq7a2yw26cxpb5xqfm2w81dd6lgx-gen.drv8  /nix/store/f40s5lcdpwsnih55hqif1whb0gh02i1c-unit-dbus-broker.service.drv9  /nix/store/cz9vaq8kwkraxv51dpdj2sy8g1v8nmwb-user-units.drv10  /nix/store/38l21qsk1jrm3pv1jhx1pwcxrqspgil4-gen.drv11  /nix/store/naa5i36ckb3klcllsyd4krs1cf0nxfav-flakelet-static-generators.drv12  /nix/store/fd26m4g662lh1fgr0q5fxg105jjl4lnx-flakelet-static.drv13  /nix/store/k555k9xfb92yy0d9h7wi7rx6c8r0y37l-flakelet-config.json.drv14  /nix/store/h7czkz6f4wwwrg33llalfq56zvzavz97-unit-flakelet-boot.service.drv15  /nix/store/rp1fl1k4k9qiwpj028f6d6nbsl5v0waq-unit-flakelet-static.service.drv16  /nix/store/s86dfwxasf6ssdzk4937r2n7hdf69fil-unit-flakelet-web.service.drv17  /nix/store/wj7faqdv24kz25vqmnhsx6iaq25yhxk5-X-Restart-Triggers-flakelet-reconcile.drv18  /nix/store/sycx24gm1b7dvj8b31027yd7ijxp4ma8-unit-flakelet-reconcile.service.drv19  /nix/store/mw70fgb5922xwzilwfqc4b9q0152gxdm-system-units.drv20  /nix/store/83cjk5xf470gcl9ifzx8x19fmzy2dl5h-etc.drv21  /nix/store/hkl6dbxfqnklpszr3vdzwvpmxpswxib8-activate.drv22  /nix/store/wd40mys8ck2k5ghw6kxflzh6hqmxn5wz-nixos-system-machine-test.drv23  /nix/store/if2m55jfxa28qw8slpzkkpdpqrk52p0h-flakelet-cli-generators.drv24  /nix/store/b10wj34nqrjqs823h9lb11nlmc1xdl30-flakelet-cli.drv25  /nix/store/pvxx0w4wpf0bqgxvgn2gf63cmf4vmflw-flakelet-cli2.drv26  /nix/store/x4xiv9xg1b3jsbrv02gcs50r5s58zhr9-closure-info.drv27  /nix/store/2i9gp8k8j5gw44znd88sm1s431q0v1b2-run-nixos-vm.drv28  /nix/store/q22ra3wzhi75rmna1fczb236m7hlq1p2-test-script.drv29  /nix/store/2xkjabrjivnjhhgg1khkmssjw9hklh5r-nixos-vm.drv30  /nix/store/rmyfvscd62xwil610y9hib934wi31x0q-driverConfiguration.json.drv31  /nix/store/2j83ihykac9xi6pxrai763dn1wblwy0k-nixos-test-driver-flakelet.drv32  /nix/store/g76j010hyd06hx9a5kxq7khl5v1bnxb1-vm-test-run-flakelet.drv33building '/nix/store/0nnmyq7a2yw26cxpb5xqfm2w81dd6lgx-gen.drv'34gen> tribuchet: building on eliza35building '/nix/store/s86dfwxasf6ssdzk4937r2n7hdf69fil-unit-flakelet-web.service.drv'36building '/nix/store/05qnr2idpsslhv9fwfslc9q8xfqr3253-X-Restart-Triggers-flakelet-static.drv'37unit-flakelet-web.service> tribuchet: building on eliza38building '/nix/store/38l21qsk1jrm3pv1jhx1pwcxrqspgil4-gen.drv'39X-Restart-Triggers-flakelet-static> tribuchet: building on eliza40gen> tribuchet: building on eliza41unit-flakelet-web.service> structuredAttrs is enabled42building '/nix/store/rp1fl1k4k9qiwpj028f6d6nbsl5v0waq-unit-flakelet-static.service.drv'43building '/nix/store/if2m55jfxa28qw8slpzkkpdpqrk52p0h-flakelet-cli-generators.drv'44building '/nix/store/naa5i36ckb3klcllsyd4krs1cf0nxfav-flakelet-static-generators.drv'45flakelet-cli-generators> tribuchet: building on eliza46unit-flakelet-static.service> tribuchet: building on eliza47flakelet-static-generators> tribuchet: building on eliza48unit-flakelet-static.service> structuredAttrs is enabled49building '/nix/store/b10wj34nqrjqs823h9lb11nlmc1xdl30-flakelet-cli.drv'50building '/nix/store/pvxx0w4wpf0bqgxvgn2gf63cmf4vmflw-flakelet-cli2.drv'51flakelet-cli> tribuchet: building on eliza52building '/nix/store/fd26m4g662lh1fgr0q5fxg105jjl4lnx-flakelet-static.drv'53flakelet-cli2> tribuchet: building on eliza54flakelet-static> tribuchet: building on eliza55building '/nix/store/q22ra3wzhi75rmna1fczb236m7hlq1p2-test-script.drv'56building '/nix/store/k555k9xfb92yy0d9h7wi7rx6c8r0y37l-flakelet-config.json.drv'57test-script> tribuchet: building on eliza58flakelet-config.json> tribuchet: building on eliza59flakelet-config.json> structuredAttrs is enabled60building '/nix/store/wj7faqdv24kz25vqmnhsx6iaq25yhxk5-X-Restart-Triggers-flakelet-reconcile.drv'61X-Restart-Triggers-flakelet-reconcile> tribuchet: building on eliza62building '/nix/store/sycx24gm1b7dvj8b31027yd7ijxp4ma8-unit-flakelet-reconcile.service.drv'63unit-flakelet-reconcile.service> tribuchet: building on eliza64unit-flakelet-reconcile.service> structuredAttrs is enabled65building '/nix/store/mw70fgb5922xwzilwfqc4b9q0152gxdm-system-units.drv'66system-units> tribuchet: building on eliza67building '/nix/store/83cjk5xf470gcl9ifzx8x19fmzy2dl5h-etc.drv'68etc> tribuchet: building on eliza69building '/nix/store/hkl6dbxfqnklpszr3vdzwvpmxpswxib8-activate.drv'70activate> tribuchet: building on eliza71building '/nix/store/wd40mys8ck2k5ghw6kxflzh6hqmxn5wz-nixos-system-machine-test.drv'72nixos-system-machine-test> tribuchet: building on eliza73nixos-system-machine-test> structuredAttrs is enabled74building '/nix/store/x4xiv9xg1b3jsbrv02gcs50r5s58zhr9-closure-info.drv'75closure-info> tribuchet: building on eliza76closure-info> structuredAttrs is enabled77building '/nix/store/2i9gp8k8j5gw44znd88sm1s431q0v1b2-run-nixos-vm.drv'78run-nixos-vm> tribuchet: building on eliza79building '/nix/store/2xkjabrjivnjhhgg1khkmssjw9hklh5r-nixos-vm.drv'80nixos-vm> tribuchet: building on eliza81building '/nix/store/rmyfvscd62xwil610y9hib934wi31x0q-driverConfiguration.json.drv'82driverConfiguration.json> tribuchet: building on eliza83driverConfiguration.json> structuredAttrs is enabled84building '/nix/store/2j83ihykac9xi6pxrai763dn1wblwy0k-nixos-test-driver-flakelet.drv'85nixos-test-driver-flakelet> tribuchet: building on eliza86nixos-test-driver-flakelet> Running type check (enable/disable: config.skipTypeCheck)87nixos-test-driver-flakelet> See https://nixos.org/manual/nixos/stable/#test-opt-skipTypeCheck88nixos-test-driver-flakelet> All checks passed!89nixos-test-driver-flakelet> Linting test script (enable/disable: config.skipLint)90nixos-test-driver-flakelet> See https://nixos.org/manual/nixos/stable/#test-opt-skipLint91nixos-test-driver-flakelet> All checks passed!92Failed to find a machine for remote build!93derivation: g76j010hyd06hx9a5kxq7khl5v1bnxb1-vm-test-run-flakelet.drv94required (system, features): (aarch64-linux, [kvm, nixos-test])951 available machines:96(systems, maxjobs, supportedFeatures, mandatoryFeatures)97([aarch64-darwin, x86_64-darwin], 8, [big-parallel, recursive-nix], [])98building '/nix/store/g76j010hyd06hx9a5kxq7khl5v1bnxb1-vm-test-run-flakelet.drv'99vm-test-run-flakelet> tribuchet: building on eliza100vm-test-run-flakelet> Machine state will be reset. To keep it, pass --keep-machine-state101vm-test-run-flakelet> start all VLans102vm-test-run-flakelet> (finished: start all VLans, in 0.00 seconds)103vm-test-run-flakelet> Test will time out and terminate in 3600 seconds104vm-test-run-flakelet> run the VM test script105vm-test-run-flakelet> machine: waiting for unit multi-user.target106vm-test-run-flakelet> machine: waiting for the VM to finish booting107vm-test-run-flakelet> machine: starting vm108vm-test-run-flakelet> machine # Disk image does not exist, creating the virtualisation disk image...109vm-test-run-flakelet> machine: QEMU running (pid 45)110vm-test-run-flakelet> machine # Formatting '/build/vm-state-machine/tmp.2Msu1Hq37B', fmt=raw size=1073741824111vm-test-run-flakelet> machine # mke2fs 1.47.4 (6-Mar-2025)112vm-test-run-flakelet> machine # Discarding device blocks:      0/262144             done113vm-test-run-flakelet> machine # Creating filesystem with 262144 4k blocks and 65536 inodes114vm-test-run-flakelet> machine # Filesystem UUID: a9b48986-8c35-4cfe-9e0e-eed23f87221d115vm-test-run-flakelet> machine # Superblock backups stored on blocks:116vm-test-run-flakelet> machine # 	32768, 98304, 163840, 229376117vm-test-run-flakelet> machine # 118vm-test-run-flakelet> machine # Allocating group tables: 0/8   done119vm-test-run-flakelet> machine # Writing inode tables: 0/8   done120vm-test-run-flakelet> machine # Creating journal (8192 blocks): done121vm-test-run-flakelet> machine # Writing superblocks and filesystem accounting information: 0/8   done122vm-test-run-flakelet> machine # 123vm-test-run-flakelet> machine # Virtualisation disk image created.124vm-test-run-flakelet> machine # [    0.000000] Booting Linux on physical CPU 0x0000000000 [0xc00fac40]125vm-test-run-flakelet> machine # [    0.000000] Linux version 6.18.40 (nixbld@localhost) (gcc (GCC) 15.3.0, GNU ld (GNU Binutils) 2.46) #1-NixOS SMP PREEMPT Fri Jul 24 14:17:26 UTC 2026126vm-test-run-flakelet> machine # [    0.000000] KASLR enabled127vm-test-run-flakelet> machine # [    0.000000] random: crng init done128vm-test-run-flakelet> machine # [    0.000000] Machine model: linux,dummy-virt129vm-test-run-flakelet> machine # [    0.000000] efi: UEFI not found.130vm-test-run-flakelet> machine # [    0.000000] OF: reserved mem: Reserved memory: No reserved-memory node in the DT131vm-test-run-flakelet> machine # [    0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000013fffffff]132vm-test-run-flakelet> machine # [    0.000000] NODE_DATA(0) allocated [mem 0x13fdeb740-0x13fdeeebf]133vm-test-run-flakelet> machine # [    0.000000] Zone ranges:134vm-test-run-flakelet> machine # [    0.000000]   DMA      [mem 0x0000000040000000-0x00000000ffffffff]135vm-test-run-flakelet> machine # [    0.000000]   DMA32    empty136vm-test-run-flakelet> machine # [    0.000000]   Normal   [mem 0x0000000100000000-0x000000013fffffff]137vm-test-run-flakelet> machine # [    0.000000]   Device   empty138vm-test-run-flakelet> machine # [    0.000000] Movable zone start for each node139vm-test-run-flakelet> machine # [    0.000000] Early memory node ranges140vm-test-run-flakelet> machine # [    0.000000]   node   0: [mem 0x0000000040000000-0x000000013fffffff]141vm-test-run-flakelet> machine # [    0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000013fffffff]142vm-test-run-flakelet> machine # [    0.000000] cma: Reserved 32 MiB at 0x00000000fe000000143vm-test-run-flakelet> machine # [    0.000000] psci: probing for conduit method from DT.144vm-test-run-flakelet> machine # [    0.000000] psci: PSCIv1.3 detected in firmware.145vm-test-run-flakelet> machine # [    0.000000] psci: Using standard PSCI v0.2 function IDs146vm-test-run-flakelet> machine # [    0.000000] psci: Trusted OS migration not required147vm-test-run-flakelet> machine # [    0.000000] psci: SMC Calling Convention v1.1148vm-test-run-flakelet> machine # [    0.000000] smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003)149vm-test-run-flakelet> machine # [    0.000000] percpu: Embedded 76 pages/cpu s186392 r8192 d116712 u311296150vm-test-run-flakelet> machine # [    0.000000] Detected PIPT I-cache on CPU0151vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: Address authentication (architected QARMA5 algorithm)152vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: GICv3 CPU interface153vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: Spectre-v4154vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: Spectre-BHB155vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: AmpereOne erratum AC03_CPU_38156vm-test-run-flakelet> machine # [    0.000000] CPU features: detected: AmpereOne erratum AC04_CPU_23157vm-test-run-flakelet> machine # [    0.000000] alternatives: applying boot alternatives158vm-test-run-flakelet> machine # [    0.000000] Kernel command line: console=ttyAMA0 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/5kql3fz0k4kzd62nx07zvq8pj4idg7rl-nixos-system-machine-test/init regInfo=/nix/store/sinwk1z9ry92ging0yx85mszamwrhahr-closure-info/registration console=ttyAMA0,115200n8 console=tty0159vm-test-run-flakelet> machine # [    0.000000] Unknown kernel command line parameters "regInfo=/nix/store/sinwk1z9ry92ging0yx85mszamwrhahr-closure-info/registration", will be passed to user space.160vm-test-run-flakelet> machine # [    0.000000] printk: log buffer data + meta data: 131072 + 458752 = 589824 bytes161vm-test-run-flakelet> machine # [    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)162vm-test-run-flakelet> machine # [    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)163vm-test-run-flakelet> machine # [    0.000000] software IO TLB: area num 4.164vm-test-run-flakelet> machine # [    0.000000] software IO TLB: mapped [mem 0x00000000fa000000-0x00000000fe000000] (64MB)165vm-test-run-flakelet> machine # [    0.000000] Fallback order for Node 0: 0166vm-test-run-flakelet> machine # [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 1048576167vm-test-run-flakelet> machine # [    0.000000] Policy zone: Normal168vm-test-run-flakelet> machine # [    0.000000] mem auto-init: stack:all(zero), heap alloc:on, heap free:off169vm-test-run-flakelet> machine # [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1170vm-test-run-flakelet> machine # [    0.000000] allocated 8388608 bytes of page_ext171vm-test-run-flakelet> machine # [    0.000000] ftrace: allocating 74754 entries in 294 pages172vm-test-run-flakelet> machine # [    0.000000] ftrace: allocated 294 pages with 4 groups173vm-test-run-flakelet> machine # [    0.000000] rcu: Hierarchical RCU implementation.174vm-test-run-flakelet> machine # [    0.000000] rcu: 	RCU event tracing is enabled.175vm-test-run-flakelet> machine # [    0.000000] rcu: 	RCU restricting CPUs from NR_CPUS=384 to nr_cpu_ids=4.176vm-test-run-flakelet> machine # [    0.000000] 	Trampoline variant of Tasks RCU enabled.177vm-test-run-flakelet> machine # [    0.000000] 	Rude variant of Tasks RCU enabled.178vm-test-run-flakelet> machine # [    0.000000] 	Tracing variant of Tasks RCU enabled.179vm-test-run-flakelet> machine # [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.180vm-test-run-flakelet> machine # [    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4181vm-test-run-flakelet> machine # [    0.000000] RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.182vm-test-run-flakelet> machine # [    0.000000] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.183vm-test-run-flakelet> machine # [    0.000000] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.184vm-test-run-flakelet> machine # [    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0185vm-test-run-flakelet> machine # [    0.000000] GICv3: 256 SPIs implemented186vm-test-run-flakelet> machine # [    0.000000] GICv3: 0 Extended SPIs implemented187vm-test-run-flakelet> machine # [    0.000000] Root IRQ handler: gic_handle_irq188vm-test-run-flakelet> machine # [    0.000000] GICv3: GICv3 features: 16 PPIs, DirectLPI189vm-test-run-flakelet> machine # [    0.000000] GICv3: GICD_CTLR.DS=1, SCR_EL3.FIQ=0190vm-test-run-flakelet> machine # [    0.000000] GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000191vm-test-run-flakelet> machine # [    0.000000] ITS [mem 0x08080000-0x0809ffff]192vm-test-run-flakelet> machine # [    0.000000] ITS@0x0000000008080000: allocated 8192 Devices @100b00000 (indirect, esz 8, psz 64K, shr 1)193vm-test-run-flakelet> machine # [    0.000000] ITS@0x0000000008080000: allocated 8192 Interrupt Collections @100b10000 (flat, esz 8, psz 64K, shr 1)194vm-test-run-flakelet> machine # [    0.000000] GICv3: using LPI property table @0x0000000100b30000195vm-test-run-flakelet> machine # [    0.000000] GICv3: CPU0: using allocated LPI pending table @0x0000000100b40000196vm-test-run-flakelet> machine # [    0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention.197vm-test-run-flakelet> machine # [    0.000000] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns198vm-test-run-flakelet> machine # [    0.000000] arch_timer: cp15 timer running at 1000.00MHz (virt).199vm-test-run-flakelet> machine # [    0.000000] clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns200vm-test-run-flakelet> machine # [    0.000000] sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns201vm-test-run-flakelet> machine # [    0.000031] arm-pv: using stolen time PV202vm-test-run-flakelet> machine # [    0.000400] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)203vm-test-run-flakelet> machine # [    0.000626] Console: colour dummy device 80x25204vm-test-run-flakelet> machine # [    0.000633] printk: legacy console [tty0] enabled205vm-test-run-flakelet> machine # [    0.000815] Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=4000000)206vm-test-run-flakelet> machine # [    0.000821] pid_max: default: 32768 minimum: 301207vm-test-run-flakelet> machine # [    0.000902] LSM: initializing lsm=capability,landlock,yama,bpf,ima208vm-test-run-flakelet> machine # [    0.001049] landlock: Up and running.209vm-test-run-flakelet> machine # [    0.001052] Yama: becoming mindful.210vm-test-run-flakelet> machine # [    0.001525] LSM support for eBPF active211vm-test-run-flakelet> machine # [    0.001668] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)212vm-test-run-flakelet> machine # [    0.001731] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)213vm-test-run-flakelet> machine # [    0.002967] cacheinfo: Unable to detect cache hierarchy for CPU 0214vm-test-run-flakelet> machine # [    0.003669] rcu: Hierarchical SRCU implementation.215vm-test-run-flakelet> machine # [    0.003673] rcu: 	Max phase no-delay instances is 1000.216vm-test-run-flakelet> machine # [    0.003828] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level217vm-test-run-flakelet> machine # [    0.004884] fsl-mc MSI: its@8080000 domain created218vm-test-run-flakelet> machine # [    0.004975] EFI services will not be available.219vm-test-run-flakelet> machine # [    0.005138] smp: Bringing up secondary CPUs ...220vm-test-run-flakelet> machine # [    0.005847] Detected PIPT I-cache on CPU1221vm-test-run-flakelet> machine # [    0.005949] GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000222vm-test-run-flakelet> machine # [    0.006085] GICv3: CPU1: using allocated LPI pending table @0x0000000100b50000223vm-test-run-flakelet> machine # [    0.006217] CPU1: Booted secondary processor 0x0000000001 [0xc00fac40]224vm-test-run-flakelet> machine # [    0.007342] Detected PIPT I-cache on CPU2225vm-test-run-flakelet> machine # [    0.007451] GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000226vm-test-run-flakelet> machine # [    0.007584] GICv3: CPU2: using allocated LPI pending table @0x0000000100b60000227vm-test-run-flakelet> machine # [    0.007715] CPU2: Booted secondary processor 0x0000000002 [0xc00fac40]228vm-test-run-flakelet> machine # [    0.009132] Detected PIPT I-cache on CPU3229vm-test-run-flakelet> machine # [    0.009252] GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000230vm-test-run-flakelet> machine # [    0.009395] GICv3: CPU3: using allocated LPI pending table @0x0000000100b70000231vm-test-run-flakelet> machine # [    0.009529] CPU3: Booted secondary processor 0x0000000003 [0xc00fac40]232vm-test-run-flakelet> machine # [    0.010064] smp: Brought up 1 node, 4 CPUs233vm-test-run-flakelet> machine # [    0.010091] SMP: Total of 4 processors activated.234vm-test-run-flakelet> machine # [    0.010094] CPU: All CPU(s) started at EL1235vm-test-run-flakelet> machine # [    0.010104] CPU features: detected: Branch Target Identification236vm-test-run-flakelet> machine # [    0.010108] CPU features: detected: ARMv8.4 Translation Table Level237vm-test-run-flakelet> machine # [    0.010111] CPU features: detected: Instruction cache invalidation not required for I/D coherence238vm-test-run-flakelet> machine # [    0.010115] CPU features: detected: Data cache clean to the PoU not required for I/D coherence239vm-test-run-flakelet> machine # [    0.010118] CPU features: detected: Common not Private translations240vm-test-run-flakelet> machine # [    0.010121] CPU features: detected: CRC32 instructions241vm-test-run-flakelet> machine # [    0.010124] CPU features: detected: Data cache clean to Point of Deep Persistence242vm-test-run-flakelet> machine # [    0.010127] CPU features: detected: Data cache clean to Point of Persistence243vm-test-run-flakelet> machine # [    0.010130] CPU features: detected: Data independent timing control (DIT)244vm-test-run-flakelet> machine # [    0.010134] CPU features: detected: E0PD245vm-test-run-flakelet> machine # [    0.010136] CPU features: detected: Enhanced Counter Virtualization246vm-test-run-flakelet> machine # [    0.010139] CPU features: detected: Enhanced Counter Virtualization (CNTPOFF)247vm-test-run-flakelet> machine # [    0.010142] CPU features: detected: Enhanced Virtualization Traps248vm-test-run-flakelet> machine # [    0.010145] CPU features: detected: Fine Grained Traps249vm-test-run-flakelet> machine # [    0.010149] CPU features: detected: Generic authentication (architected QARMA5 algorithm)250vm-test-run-flakelet> machine # [    0.010153] CPU features: detected: RCpc load-acquire (LDAPR)251vm-test-run-flakelet> machine # [    0.010156] CPU features: detected: LSE atomic instructions252vm-test-run-flakelet> machine # [    0.010159] CPU features: detected: Privileged Access Never253vm-test-run-flakelet> machine # [    0.010161] CPU features: detected: PMUv3254vm-test-run-flakelet> machine # [    0.010164] CPU features: detected: RAS Extension Support255vm-test-run-flakelet> machine # [    0.010167] CPU features: detected: RASv1p1 Extension Support256vm-test-run-flakelet> machine # [    0.010169] CPU features: detected: Random Number Generator257vm-test-run-flakelet> machine # [    0.010172] CPU features: detected: Speculation barrier (SB)258vm-test-run-flakelet> machine # [    0.010175] CPU features: detected: Stage-2 Force Write-Back259vm-test-run-flakelet> machine # [    0.010177] CPU features: detected: TLB range maintenance instructions260vm-test-run-flakelet> machine # [    0.010181] CPU features: detected: Speculative Store Bypassing Safe (SSBS)261vm-test-run-flakelet> machine # [    0.010313] alternatives: applying system-wide alternatives262vm-test-run-flakelet> machine # [    0.013237] CPU features: detected: BBM Level 2 without TLB conflict abort263vm-test-run-flakelet> machine # [    0.013522] Memory: 3910008K/4194304K available (24384K kernel code, 7086K rwdata, 26316K rodata, 4736K init, 1102K bss, 233732K reserved, 32768K cma-reserved)264vm-test-run-flakelet> machine # [    0.014865] devtmpfs: initialized265vm-test-run-flakelet> machine # [    0.018443] posixtimers hash table entries: 2048 (order: 3, 32768 bytes, linear)266vm-test-run-flakelet> machine # [    0.018517] futex hash table entries: 1024 (65536 bytes on 1 NUMA nodes, total 64 KiB, linear).267vm-test-run-flakelet> machine # [    0.018706] 2G module region forced by RANDOMIZE_MODULE_REGION_FULL268vm-test-run-flakelet> machine # [    0.018711] 0 pages in range for non-PLT usage269vm-test-run-flakelet> machine # [    0.018712] 508320 pages in range for PLT usage270vm-test-run-flakelet> machine # [    0.018837] pinctrl core: initialized pinctrl subsystem271vm-test-run-flakelet> machine # [    0.019631] DMI not present or invalid.272vm-test-run-flakelet> machine # [    0.022886] NET: Registered PF_NETLINK/PF_ROUTE protocol family273vm-test-run-flakelet> machine # [    0.025322] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations274vm-test-run-flakelet> machine # [    0.025526] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations275vm-test-run-flakelet> machine # [    0.025767] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations276vm-test-run-flakelet> machine # [    0.025785] audit: initializing netlink subsys (disabled)277vm-test-run-flakelet> machine # [    0.026118] audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1278vm-test-run-flakelet> machine # [    0.026560] thermal_sys: Registered thermal governor 'fair_share'279vm-test-run-flakelet> machine # [    0.026563] thermal_sys: Registered thermal governor 'bang_bang'280vm-test-run-flakelet> machine # [    0.026568] thermal_sys: Registered thermal governor 'step_wise'281vm-test-run-flakelet> machine # [    0.026570] thermal_sys: Registered thermal governor 'user_space'282vm-test-run-flakelet> machine # [    0.026573] thermal_sys: Registered thermal governor 'power_allocator'283vm-test-run-flakelet> machine # [    0.026633] cpuidle: using governor ladder284vm-test-run-flakelet> machine # [    0.026656] cpuidle: using governor menu285vm-test-run-flakelet> machine # [    0.026842] hw-breakpoint: found 16 breakpoint and 4 watchpoint registers.286vm-test-run-flakelet> machine # [    0.027019] ASID allocator initialised with 65536 entries287vm-test-run-flakelet> machine # [    0.028157] Serial: AMBA PL011 UART driver288vm-test-run-flakelet> machine # [    0.033356] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 13, base_baud = 0) is a PL011 rev1289vm-test-run-flakelet> machine # [    0.033658] printk: console [ttyAMA0] enabled290vm-test-run-flakelet> machine # [    0.043883] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages291vm-test-run-flakelet> machine # [    0.043907] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page292vm-test-run-flakelet> machine # [    0.043917] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages293vm-test-run-flakelet> machine # [    0.043926] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page294vm-test-run-flakelet> machine # [    0.043934] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages295vm-test-run-flakelet> machine # [    0.043942] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page296vm-test-run-flakelet> machine # [    0.043950] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages297vm-test-run-flakelet> machine # [    0.043958] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page298vm-test-run-flakelet> machine # [    0.057426] fbcon: Taking over console299vm-test-run-flakelet> machine # [    0.057438] ACPI: Interpreter disabled.300vm-test-run-flakelet> machine # [    0.058442] iommu: Default domain type: Translated301vm-test-run-flakelet> machine # [    0.058460] iommu: DMA domain TLB invalidation policy: strict mode302vm-test-run-flakelet> machine # [    0.076287] SCSI subsystem initialized303vm-test-run-flakelet> machine # [    0.077199] usbcore: registered new interface driver usbfs304vm-test-run-flakelet> machine # [    0.077268] usbcore: registered new interface driver hub305vm-test-run-flakelet> machine # [    0.077313] usbcore: registered new device driver usb306vm-test-run-flakelet> machine # [    0.078280] pps_core: LinuxPPS API ver. 1 registered307vm-test-run-flakelet> machine # [    0.078291] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>308vm-test-run-flakelet> machine # [    0.078315] PTP clock support registered309vm-test-run-flakelet> machine # [    0.078468] EDAC MC: Ver: 3.0.0310vm-test-run-flakelet> machine # [    0.079272] scmi_core: SCMI protocol bus registered311vm-test-run-flakelet> machine # [    0.080396] FPGA manager framework312vm-test-run-flakelet> machine # [    0.082998] vgaarb: loaded313vm-test-run-flakelet> machine # [    0.083906] clocksource: Switched to clocksource arch_sys_counter314vm-test-run-flakelet> machine # [    0.085309] VFS: Disk quotas dquot_6.6.0315vm-test-run-flakelet> machine # [    0.085392] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)316vm-test-run-flakelet> machine # [    0.086189] netfs: FS-Cache loaded317vm-test-run-flakelet> machine # [    0.086449] pnp: PnP ACPI: disabled318vm-test-run-flakelet> machine # [    0.097048] NET: Registered PF_INET protocol family319vm-test-run-flakelet> machine # [    0.098530] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)320vm-test-run-flakelet> machine # [    0.160149] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)321vm-test-run-flakelet> machine # [    0.160206] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)322vm-test-run-flakelet> machine # [    0.160248] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)323vm-test-run-flakelet> machine # [    0.160461] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear)324vm-test-run-flakelet> machine # [    0.160923] TCP: Hash tables configured (established 32768 bind 32768)325vm-test-run-flakelet> machine # [    0.161067] MPTCP token hash table entries: 4096 (order: 5, 98304 bytes, linear)326vm-test-run-flakelet> machine # [    0.161132] UDP hash table entries: 2048 (order: 5, 131072 bytes, linear)327vm-test-run-flakelet> machine # [    0.161239] UDP-Lite hash table entries: 2048 (order: 5, 131072 bytes, linear)328vm-test-run-flakelet> machine # [    0.161445] NET: Registered PF_UNIX/PF_LOCAL protocol family329vm-test-run-flakelet> machine # [    0.161486] NET: Registered PF_XDP protocol family330vm-test-run-flakelet> machine # [    0.161506] PCI: CLS 0 bytes, default 64331vm-test-run-flakelet> machine # [    0.161842] Trying to unpack rootfs image as initramfs...332vm-test-run-flakelet> machine # [    0.172247] kvm [1]: HYP mode not available333vm-test-run-flakelet> machine # [    0.241224] Initialise system trusted keyrings334vm-test-run-flakelet> machine # [    0.241473] workingset: timestamp_bits=42 max_order=20 bucket_order=0335vm-test-run-flakelet> machine # [    0.242150] squashfs: version 4.0 (2009/01/31) Phillip Lougher336vm-test-run-flakelet> machine # [    0.242290] 9p: Installing v9fs 9p2000 file system support337vm-test-run-flakelet> machine # [    0.256945] Key type asymmetric registered338vm-test-run-flakelet> machine # [    0.256957] Asymmetric key parser 'x509' registered339vm-test-run-flakelet> machine # [    0.257009] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 242)340vm-test-run-flakelet> machine # [    0.257221] io scheduler mq-deadline registered341vm-test-run-flakelet> machine # [    0.257226] io scheduler kyber registered342vm-test-run-flakelet> machine # [    0.284358] pl061_gpio 9030000.pl061: PL061 GPIO chip registered343vm-test-run-flakelet> machine # [    0.287997] ledtrig-cpu: registered to indicate activity on CPUs344vm-test-run-flakelet> machine # [    0.289446] pci-host-generic 4010000000.pcie: host bridge /pcie@10000000 ranges:345vm-test-run-flakelet> machine # [    0.289500] pci-host-generic 4010000000.pcie:       IO 0x003eff0000..0x003effffff -> 0x0000000000346vm-test-run-flakelet> machine # [    0.289547] pci-host-generic 4010000000.pcie:      MEM 0x0010000000..0x003efeffff -> 0x0010000000347vm-test-run-flakelet> machine # [    0.289566] pci-host-generic 4010000000.pcie:      MEM 0x8000000000..0xffffffffff -> 0x8000000000348vm-test-run-flakelet> machine # [    0.289632] pci-host-generic 4010000000.pcie: Memory resource size exceeds max for 32 bits349vm-test-run-flakelet> machine # [    0.289683] pci-host-generic 4010000000.pcie: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff]350vm-test-run-flakelet> machine # [    0.289952] pci-host-generic 4010000000.pcie: PCI host bridge to bus 0000:00351vm-test-run-flakelet> machine # [    0.289973] pci_bus 0000:00: root bus resource [bus 00-ff]352vm-test-run-flakelet> machine # [    0.290007] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]353vm-test-run-flakelet> machine # [    0.290019] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff]354vm-test-run-flakelet> machine # [    0.290028] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff]355vm-test-run-flakelet> machine # [    0.290289] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint356vm-test-run-flakelet> machine # [    0.291837] pci 0000:00:01.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint357vm-test-run-flakelet> machine # [    0.292482] pci 0000:00:01.0: BAR 0 [io  0x0000-0x001f]358vm-test-run-flakelet> machine # [    0.292520] pci 0000:00:01.0: BAR 1 [mem 0x00000000-0x00000fff]359vm-test-run-flakelet> machine # [    0.292601] pci 0000:00:01.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]360vm-test-run-flakelet> machine # [    0.292638] pci 0000:00:01.0: ROM [mem 0x00000000-0x0003ffff pref]361vm-test-run-flakelet> machine # [    0.294481] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint362vm-test-run-flakelet> machine # [    0.295137] pci 0000:00:02.0: BAR 0 [io  0x0000-0x001f]363vm-test-run-flakelet> machine # [    0.295177] pci 0000:00:02.0: BAR 1 [mem 0x00000000-0x00000fff]364vm-test-run-flakelet> machine # [    0.295261] pci 0000:00:02.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]365vm-test-run-flakelet> machine # [    0.296957] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint366vm-test-run-flakelet> machine # [    0.297604] pci 0000:00:03.0: BAR 0 [io  0x0000-0x003f]367vm-test-run-flakelet> machine # [    0.297641] pci 0000:00:03.0: BAR 1 [mem 0x00000000-0x00000fff]368vm-test-run-flakelet> machine # [    0.297722] pci 0000:00:03.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]369vm-test-run-flakelet> machine # [    0.299388] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint370vm-test-run-flakelet> machine # [    0.300104] pci 0000:00:04.0: BAR 0 [io  0x0000-0x001f]371vm-test-run-flakelet> machine # [    0.300141] pci 0000:00:04.0: BAR 1 [mem 0x00000000-0x00000fff]372vm-test-run-flakelet> machine # [    0.300224] pci 0000:00:04.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]373vm-test-run-flakelet> machine # [    0.301806] pci 0000:00:05.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint374vm-test-run-flakelet> machine # [    0.302472] pci 0000:00:05.0: BAR 0 [io  0x0000-0x001f]375vm-test-run-flakelet> machine # [    0.302508] pci 0000:00:05.0: BAR 1 [mem 0x00000000-0x00000fff]376vm-test-run-flakelet> machine # [    0.302601] pci 0000:00:05.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]377vm-test-run-flakelet> machine # [    0.304210] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint378vm-test-run-flakelet> machine # [    0.304816] pci 0000:00:06.0: BAR 0 [io  0x0000-0x007f]379vm-test-run-flakelet> machine # [    0.304851] pci 0000:00:06.0: BAR 1 [mem 0x00000000-0x00000fff]380vm-test-run-flakelet> machine # [    0.304931] pci 0000:00:06.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]381vm-test-run-flakelet> machine # [    0.306547] pci 0000:00:07.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint382vm-test-run-flakelet> machine # [    0.307130] pci 0000:00:07.0: BAR 0 [io  0x0000-0x001f]383vm-test-run-flakelet> machine # [    0.307166] pci 0000:00:07.0: BAR 1 [mem 0x00000000-0x00000fff]384vm-test-run-flakelet> machine # [    0.307261] pci 0000:00:07.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]385vm-test-run-flakelet> machine # [    0.307299] pci 0000:00:07.0: ROM [mem 0x00000000-0x0003ffff pref]386vm-test-run-flakelet> machine # [    0.309062] pci 0000:00:08.0: [1af4:1050] type 00 class 0x038000 conventional PCI endpoint387vm-test-run-flakelet> machine # [    0.309711] pci 0000:00:08.0: BAR 1 [mem 0x00000000-0x00000fff]388vm-test-run-flakelet> machine # [    0.309811] pci 0000:00:08.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]389vm-test-run-flakelet> machine # [    0.311469] pci 0000:00:09.0: [1af4:1052] type 00 class 0x090000 conventional PCI endpoint390vm-test-run-flakelet> machine # [    0.312148] pci 0000:00:09.0: BAR 1 [mem 0x00000000-0x00000fff]391vm-test-run-flakelet> machine # [    0.312229] pci 0000:00:09.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]392vm-test-run-flakelet> machine # [    0.313662] pci 0000:00:0a.0: [8086:24cd] type 00 class 0x0c0320 conventional PCI endpoint393vm-test-run-flakelet> machine # [    0.314250] pci 0000:00:0a.0: BAR 0 [mem 0x00000000-0x00000fff]394vm-test-run-flakelet> machine # [    0.315056] pci 0000:00:0b.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint395vm-test-run-flakelet> machine # [    0.315746] pci 0000:00:0b.0: BAR 0 [io  0x0000-0x003f]396vm-test-run-flakelet> machine # [    0.315782] pci 0000:00:0b.0: BAR 1 [mem 0x00000000-0x00000fff]397vm-test-run-flakelet> machine # [    0.315883] pci 0000:00:0b.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]398vm-test-run-flakelet> machine # [    0.317572] pci 0000:00:0c.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint399vm-test-run-flakelet> machine # [    0.318185] pci 0000:00:0c.0: BAR 0 [io  0x0000-0x001f]400vm-test-run-flakelet> machine # [    0.318221] pci 0000:00:0c.0: BAR 1 [mem 0x00000000-0x00000fff]401vm-test-run-flakelet> machine # [    0.318313] pci 0000:00:0c.0: BAR 4 [mem 0x00000000-0x00003fff 64bit pref]402vm-test-run-flakelet> machine # [    0.320270] pci 0000:00:01.0: ROM [mem 0x10000000-0x1003ffff pref]: assigned403vm-test-run-flakelet> machine # [    0.320288] pci 0000:00:07.0: ROM [mem 0x10040000-0x1007ffff pref]: assigned404vm-test-run-flakelet> machine # [    0.320299] pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned405vm-test-run-flakelet> machine # [    0.320449] pci 0000:00:02.0: BAR 4 [mem 0x8000004000-0x8000007fff 64bit pref]: assigned406vm-test-run-flakelet> machine # [    0.320584] pci 0000:00:03.0: BAR 4 [mem 0x8000008000-0x800000bfff 64bit pref]: assigned407vm-test-run-flakelet> machine # [    0.320717] pci 0000:00:04.0: BAR 4 [mem 0x800000c000-0x800000ffff 64bit pref]: assigned408vm-test-run-flakelet> machine # [    0.320865] pci 0000:00:05.0: BAR 4 [mem 0x8000010000-0x8000013fff 64bit pref]: assigned409vm-test-run-flakelet> machine # [    0.321003] pci 0000:00:06.0: BAR 4 [mem 0x8000014000-0x8000017fff 64bit pref]: assigned410vm-test-run-flakelet> machine # [    0.321149] pci 0000:00:07.0: BAR 4 [mem 0x8000018000-0x800001bfff 64bit pref]: assigned411vm-test-run-flakelet> machine # [    0.321298] pci 0000:00:08.0: BAR 4 [mem 0x800001c000-0x800001ffff 64bit pref]: assigned412vm-test-run-flakelet> machine # [    0.321462] pci 0000:00:09.0: BAR 4 [mem 0x8000020000-0x8000023fff 64bit pref]: assigned413vm-test-run-flakelet> machine # [    0.321599] pci 0000:00:0b.0: BAR 4 [mem 0x8000024000-0x8000027fff 64bit pref]: assigned414vm-test-run-flakelet> machine # [    0.321777] pci 0000:00:0c.0: BAR 4 [mem 0x8000028000-0x800002bfff 64bit pref]: assigned415vm-test-run-flakelet> machine # [    0.321920] pci 0000:00:01.0: BAR 1 [mem 0x10080000-0x10080fff]: assigned416vm-test-run-flakelet> machine # [    0.321975] pci 0000:00:02.0: BAR 1 [mem 0x10081000-0x10081fff]: assigned417vm-test-run-flakelet> machine # [    0.322030] pci 0000:00:03.0: BAR 1 [mem 0x10082000-0x10082fff]: assigned418vm-test-run-flakelet> machine # [    0.322084] pci 0000:00:04.0: BAR 1 [mem 0x10083000-0x10083fff]: assigned419vm-test-run-flakelet> machine # [    0.322140] pci 0000:00:05.0: BAR 1 [mem 0x10084000-0x10084fff]: assigned420vm-test-run-flakelet> machine # [    0.322194] pci 0000:00:06.0: BAR 1 [mem 0x10085000-0x10085fff]: assigned421vm-test-run-flakelet> machine # [    0.322249] pci 0000:00:07.0: BAR 1 [mem 0x10086000-0x10086fff]: assigned422vm-test-run-flakelet> machine # [    0.322304] pci 0000:00:08.0: BAR 1 [mem 0x10087000-0x10087fff]: assigned423vm-test-run-flakelet> machine # [    0.322359] pci 0000:00:09.0: BAR 1 [mem 0x10088000-0x10088fff]: assigned424vm-test-run-flakelet> machine # [    0.322416] pci 0000:00:0a.0: BAR 0 [mem 0x10089000-0x10089fff]: assigned425vm-test-run-flakelet> machine # [    0.322472] pci 0000:00:0b.0: BAR 1 [mem 0x1008a000-0x1008afff]: assigned426vm-test-run-flakelet> machine # [    0.322529] pci 0000:00:0c.0: BAR 1 [mem 0x1008b000-0x1008bfff]: assigned427vm-test-run-flakelet> machine # [    0.322582] pci 0000:00:06.0: BAR 0 [io  0x1000-0x107f]: assigned428vm-test-run-flakelet> machine # [    0.322635] pci 0000:00:03.0: BAR 0 [io  0x1080-0x10bf]: assigned429vm-test-run-flakelet> machine # [    0.322688] pci 0000:00:0b.0: BAR 0 [io  0x10c0-0x10ff]: assigned430vm-test-run-flakelet> machine # [    0.322740] pci 0000:00:01.0: BAR 0 [io  0x1100-0x111f]: assigned431vm-test-run-flakelet> machine # [    0.322794] pci 0000:00:02.0: BAR 0 [io  0x1120-0x113f]: assigned432vm-test-run-flakelet> machine # [    0.322848] pci 0000:00:04.0: BAR 0 [io  0x1140-0x115f]: assigned433vm-test-run-flakelet> machine # [    0.322901] pci 0000:00:05.0: BAR 0 [io  0x1160-0x117f]: assigned434vm-test-run-flakelet> machine # [    0.322954] pci 0000:00:07.0: BAR 0 [io  0x1180-0x119f]: assigned435vm-test-run-flakelet> machine # [    0.323009] pci 0000:00:0c.0: BAR 0 [io  0x11a0-0x11bf]: assigned436vm-test-run-flakelet> machine # [    0.323072] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]437vm-test-run-flakelet> machine # [    0.323087] pci_bus 0000:00: resource 5 [mem 0x10000000-0x3efeffff]438vm-test-run-flakelet> machine # [    0.323096] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff]439vm-test-run-flakelet> machine # [    0.327159] pci 0000:00:0a.0: enabling device (0000 -> 0002)440vm-test-run-flakelet> machine # [    0.359146] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)441vm-test-run-flakelet> machine # [    0.363722] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)442vm-test-run-flakelet> machine # [    0.368258] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003)443vm-test-run-flakelet> machine # [    0.374624] virtio-pci 0000:00:04.0: enabling device (0000 -> 0003)444vm-test-run-flakelet> machine # [    0.378821] virtio-pci 0000:00:05.0: enabling device (0000 -> 0003)445vm-test-run-flakelet> machine # [    0.383127] virtio-pci 0000:00:06.0: enabling device (0000 -> 0003)446vm-test-run-flakelet> machine # [    0.390301] virtio-pci 0000:00:07.0: enabling device (0000 -> 0003)447vm-test-run-flakelet> machine # [    0.395020] virtio-pci 0000:00:08.0: enabling device (0000 -> 0002)448vm-test-run-flakelet> machine # [    0.400446] virtio-pci 0000:00:09.0: enabling device (0000 -> 0002)449vm-test-run-flakelet> machine # [    0.405957] virtio-pci 0000:00:0b.0: enabling device (0000 -> 0003)450vm-test-run-flakelet> machine # [    0.410845] virtio-pci 0000:00:0c.0: enabling device (0000 -> 0003)451vm-test-run-flakelet> machine # [    0.425061] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled452vm-test-run-flakelet> machine # [    0.429197] msm_serial: driver initialized453vm-test-run-flakelet> machine # [    0.429610] SuperH (H)SCI(F) driver initialized454vm-test-run-flakelet> machine # [    0.429711] STM32 USART driver initialized455vm-test-run-flakelet> machine # [    0.477602] loop: module loaded456vm-test-run-flakelet> machine # [    0.478248] virtio_blk virtio5: 4/0/0 default/read/poll queues457vm-test-run-flakelet> machine # [    0.484005] virtio_blk virtio5: [vda] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB)458vm-test-run-flakelet> machine # [    0.489784] megasas: 07.734.00.00-rc1459vm-test-run-flakelet> machine # [    0.492068] physmap-flash 0.flash: physmap platform flash device: [mem 0x00000000-0x03ffffff]460vm-test-run-flakelet> machine # [    0.498090] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000461vm-test-run-flakelet> machine # [    0.498269] Intel/Sharp Extended Query Table at 0x0031462vm-test-run-flakelet> machine # [    0.503715] Using buffer write method463vm-test-run-flakelet> machine # [    0.503804] physmap-flash 0.flash: physmap platform flash device: [mem 0x04000000-0x07ffffff]464vm-test-run-flakelet> machine # [    0.508717] 0.flash: Found 2 x16 devices at 0x0 in 32-bit bank. Manufacturer ID 0x000000 Chip ID 0x000000465vm-test-run-flakelet> machine # [    0.508761] Intel/Sharp Extended Query Table at 0x0031466vm-test-run-flakelet> machine # [    0.513768] Using buffer write method467vm-test-run-flakelet> machine # [    0.513812] Concatenating MTD devices:468vm-test-run-flakelet> machine # [    0.513819] (0): "0.flash"469vm-test-run-flakelet> machine # [    0.513825] (1): "0.flash"470vm-test-run-flakelet> machine # [    0.513831] into device "0.flash"471vm-test-run-flakelet> machine # [    0.657627] Freeing initrd memory: 27052K472vm-test-run-flakelet> machine # [    0.670865] tun: Universal TUN/TAP device driver, 1.6473vm-test-run-flakelet> machine # [    0.675208] thunder_xcv, ver 1.0474vm-test-run-flakelet> machine # [    0.675268] thunder_bgx, ver 1.0475vm-test-run-flakelet> machine # [    0.675298] nicpf, ver 1.0476vm-test-run-flakelet> machine # [    0.675961] e1000: Intel(R) PRO/1000 Network Driver477vm-test-run-flakelet> machine # [    0.675967] e1000: Copyright (c) 1999-2006 Intel Corporation.478vm-test-run-flakelet> machine # [    0.676004] e1000e: Intel(R) PRO/1000 Network Driver479vm-test-run-flakelet> machine # [    0.676010] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.480vm-test-run-flakelet> machine # [    0.676035] igb: Intel(R) Gigabit Ethernet Network Driver481vm-test-run-flakelet> machine # [    0.676038] igb: Copyright (c) 2007-2014 Intel Corporation.482vm-test-run-flakelet> machine # [    0.676060] igbvf: Intel(R) Gigabit Virtual Function Network Driver483vm-test-run-flakelet> machine # [    0.676064] igbvf: Copyright (c) 2009 - 2012 Intel Corporation.484vm-test-run-flakelet> machine # [    0.676212] sky2: driver version 1.30485vm-test-run-flakelet> machine # [    0.677998] usbcore: registered new interface driver usb-storage486vm-test-run-flakelet> machine # [    0.678135] usbcore: registered new interface driver usbserial_generic487vm-test-run-flakelet> machine # [    0.678152] usbserial: USB Serial support registered for generic488vm-test-run-flakelet> machine # [    0.678783] hv_vmbus: registering driver hyperv_keyboard489vm-test-run-flakelet> machine # [    0.679166] ehci-pci 0000:00:0a.0: EHCI Host Controller490vm-test-run-flakelet> machine # [    0.679232] ehci-pci 0000:00:0a.0: new USB bus registered, assigned bus number 1491vm-test-run-flakelet> machine # [    0.679813] ehci-pci 0000:00:0a.0: irq 15, io mem 0x10089000492vm-test-run-flakelet> machine # [    0.679952] rtc-pl031 9010000.pl031: registered as rtc0493vm-test-run-flakelet> machine # [    0.679983] rtc-pl031 9010000.pl031: setting system clock to 2026-08-31T08:42:56 UTC (1788165776)494vm-test-run-flakelet> machine # [    0.680329] i2c_dev: i2c /dev entries driver495vm-test-run-flakelet> machine # [    0.683369] sdhci: Secure Digital Host Controller Interface driver496vm-test-run-flakelet> machine # [    0.683376] sdhci: Copyright(c) Pierre Ossman497vm-test-run-flakelet> machine # [    0.683652] Synopsys Designware Multimedia Card Interface Driver498vm-test-run-flakelet> machine # [    0.684065] sdhci-pltfm: SDHCI platform and OF driver helper499vm-test-run-flakelet> machine # [    0.685968] hid: raw HID events driver (C) Jiri Kosina500vm-test-run-flakelet> machine # [    0.686280] usbcore: registered new interface driver usbhid501vm-test-run-flakelet> machine # [    0.686284] usbhid: USB HID core driver502vm-test-run-flakelet> machine # [    0.690671] hw perfevents: enabled with armv8_pmuv3 PMU driver, 11 (0,800003ff) counters available503vm-test-run-flakelet> machine # [    0.692059] ehci-pci 0000:00:0a.0: USB 2.0 started, EHCI 1.00504vm-test-run-flakelet> machine # [    0.692504] drop_monitor: Initializing network drop monitor service505vm-test-run-flakelet> machine # [    0.692693] NET: Registered PF_INET6 protocol family506vm-test-run-flakelet> machine # [    0.693363] hub 1-0:1.0: USB hub found507vm-test-run-flakelet> machine # [    0.693433] hub 1-0:1.0: 6 ports detected508vm-test-run-flakelet> machine # [    0.694799] Segment Routing with IPv6509vm-test-run-flakelet> machine # [    0.694884] In-situ OAM (IOAM) with IPv6510vm-test-run-flakelet> machine # [    0.695115] NET: Registered PF_PACKET protocol family511vm-test-run-flakelet> machine # [    0.695492] 9pnet: Installing 9P2000 support512vm-test-run-flakelet> machine # [    0.702598] Key type dns_resolver registered513vm-test-run-flakelet> machine # [    0.721916] registered taskstats version 1514vm-test-run-flakelet> machine # [    0.722432] Loading compiled-in X.509 certificates515vm-test-run-flakelet> machine # [    0.750569] Demotion targets for Node 0: null516vm-test-run-flakelet> machine # [    0.750844] Key type .fscrypt registered517vm-test-run-flakelet> machine # [    0.750853] Key type fscrypt-provisioning registered518vm-test-run-flakelet> machine # [    0.751175] ima: No TPM chip found, activating TPM-bypass!519vm-test-run-flakelet> machine # [    0.751202] ima: Allocated hash algorithm: sha1520vm-test-run-flakelet> machine # [    0.751306] ima: No architecture policies found521vm-test-run-flakelet> machine # [    0.752953] input: gpio-keys as /devices/platform/gpio-keys/input/input0522vm-test-run-flakelet> machine # [    0.786357] clk: Disabling unused clocks523vm-test-run-flakelet> machine # [    0.786401] PM: genpd: Disabling unused power domains524vm-test-run-flakelet> machine # [    0.792050] Freeing unused kernel memory: 4736K525vm-test-run-flakelet> machine # [    0.792329] Run /init as init process526vm-test-run-flakelet> machine # [    0.839566] systemd[1]: Successfully made /usr/ read-only.527vm-test-run-flakelet> machine # [    0.939990] usb 1-1: new high-speed USB device number 2 using ehci-pci528vm-test-run-flakelet> machine # [    1.094558] input: QEMU QEMU USB Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1529vm-test-run-flakelet> machine # [    1.174518] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)530vm-test-run-flakelet> machine # [    1.174582] systemd[1]: Detected virtualization qemu.531vm-test-run-flakelet> machine # [    1.174656] systemd[1]: Detected architecture arm64.532vm-test-run-flakelet> machine # [    1.174670] systemd[1]: Running in initrd.533vm-test-run-flakelet> machine # [    1.175548] systemd[1]: Initializing machine ID from random generator.534vm-test-run-flakelet> machine # [    1.175985] systemd[1]: Hostname set to <machine>.535vm-test-run-flakelet> machine # [    1.196401] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:00:0a.0-1/input0536vm-test-run-flakelet> machine # [    1.315999] usb 1-2: new high-speed USB device number 3 using ehci-pci537vm-test-run-flakelet> machine # [    1.468361] input: QEMU QEMU USB Tablet as /devices/platform/4010000000.pcie/pci0000:00/0000:00:0a.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2538vm-test-run-flakelet> machine # [    1.468704] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:00:0a.0-2/input0539vm-test-run-flakelet> machine # [    1.537978] systemd[1]: bpf-restrict-fs: LSM BPF program attached540vm-test-run-flakelet> machine # [    1.637198] systemd[1]: Queued start job for default target Initrd Default Target.541vm-test-run-flakelet> machine # [    1.655741] systemd[1]: Created slice Slice /system/modprobe.542vm-test-run-flakelet> machine # [    1.656203] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.543vm-test-run-flakelet> machine # [    1.656252] systemd[1]: Expecting device /dev/disk/by-label/nixos...544vm-test-run-flakelet> machine # [    1.656283] systemd[1]: Reached target Path Units.545vm-test-run-flakelet> machine # [    1.656305] systemd[1]: Reached target Slice Units.546vm-test-run-flakelet> machine # [    1.656327] systemd[1]: Reached target Swaps.547vm-test-run-flakelet> machine # [    1.656350] systemd[1]: Reached target Timer Units.548vm-test-run-flakelet> machine # [    1.656574] systemd[1]: Listening on D-Bus System Message Bus Socket.549vm-test-run-flakelet> machine # [    1.656790] systemd[1]: Listening on Journal Socket (/dev/log).550vm-test-run-flakelet> machine # [    1.656995] systemd[1]: Listening on Journal Sockets.551vm-test-run-flakelet> machine # [    1.657191] systemd[1]: Listening on udev Control Socket.552vm-test-run-flakelet> machine # [    1.657312] systemd[1]: Listening on udev Kernel Socket.553vm-test-run-flakelet> machine # [    1.657361] systemd[1]: Reached target Socket Units.554vm-test-run-flakelet> machine # [    1.659754] systemd[1]: Starting Create List of Static Device Nodes...555vm-test-run-flakelet> machine # [    1.662187] systemd[1]: Starting Load Kernel Module 9pnet_virtio...556vm-test-run-flakelet> machine # [    1.662279] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs557vm-test-run-flakelet> machine # [    1.664723] systemd[1]: Mounting Kernel Configuration File System...558vm-test-run-flakelet> machine # [    1.690361] systemd[1]: Starting Journal Service...559vm-test-run-flakelet> machine # [    1.693302] systemd[1]: Starting Load Kernel Modules...560vm-test-run-flakelet> machine # [    1.693483] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os561vm-test-run-flakelet> machine # [    1.700166] systemd[1]: Starting Coldplug All udev Devices...562vm-test-run-flakelet> machine # [    1.702488] systemd[1]: Finished Create List of Static Device Nodes.563vm-test-run-flakelet> machine # [    1.703883] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.564vm-test-run-flakelet> machine # [    1.712261] systemd[1]: Finished Load Kernel Module 9pnet_virtio.565vm-test-run-flakelet> machine # [    1.714117] systemd[1]: Mounted Kernel Configuration File System.566vm-test-run-flakelet> machine # [    1.724490] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...567vm-test-run-flakelet> machine # [    1.738603] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.568vm-test-run-flakelet> machine # [    1.739011] device-mapper: ioctl: 4.50.0-ioctl (2025-04-28) initialised: dm-devel@lists.linux.dev569vm-test-run-flakelet> machine # [    1.745614] [drm] pci: virtio-gpu-pci detected at 0000:00:08.0570vm-test-run-flakelet> machine # [    1.745881] [drm] features: -virgl +edid -resource_blob -host_visible571vm-test-run-flakelet> machine # [    1.745887] [drm] features: -context_init572vm-test-run-flakelet> machine # [    1.747119] [drm] number of scanouts: 1573vm-test-run-flakelet> machine # [    1.747138] [drm] number of cap sets: 0574vm-test-run-flakelet> machine # [    1.748114] virtio-pci 0000:00:08.0: [drm] Registered 1 planes with drm panic575vm-test-run-flakelet> machine # [    1.748130] [drm] Initialized virtio_gpu 0.1.0 for 0000:00:08.0 on minor 0576vm-test-run-flakelet> machine # [    1.750588] systemd-journald[94]: Collecting audit messages is disabled.577vm-test-run-flakelet> machine # [    1.760890] Console: switching to colour frame buffer device 160x50578vm-test-run-flakelet> machine # [    1.770886] virtio-pci 0000:00:08.0: [drm] fb0: virtio_gpudrmfb frame buffer device579vm-test-run-flakelet> machine # [    1.774997] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.580vm-test-run-flakelet> machine # [    1.777692] systemd[1]: Starting Create Static Device Nodes in /dev...581vm-test-run-flakelet> machine # [    1.800616] systemd[1]: Finished Load Kernel Modules.582vm-test-run-flakelet> machine # [    1.808347] systemd[1]: Starting Apply Kernel Variables...583vm-test-run-flakelet> machine # [    1.821045] systemd[1]: Finished Create Static Device Nodes in /dev.584vm-test-run-flakelet> machine # [    1.821551] systemd[1]: Reached target Preparation for Local File Systems.585vm-test-run-flakelet> machine # [    1.822078] systemd[1]: Reached target Local File Systems.586vm-test-run-flakelet> machine # [    1.829689] systemd[1]: Starting Rule-based Manager for Device Events and Files...587vm-test-run-flakelet> machine # [    1.841039] systemd[1]: Started Journal Service.588vm-test-run-flakelet> machine # [    1.840176] systemd-modules-load[95]: Using 4 probe threads589vm-test-run-flakelet> machine # [    1.843493] systemd-modules-load[95]: Module 'virtio_balloon' is built in590vm-test-run-flakelet> machine # [    1.848329] systemd-modules-load[95]: Module 'virtio_console' is built in591vm-test-run-flakelet> machine # [    1.852640] systemd-modules-load[95]: Module 'virtio_rng' is built in592vm-test-run-flakelet> machine # [    1.855179] systemd-modules-load[95]: Inserted module 'dm_mod'593vm-test-run-flakelet> machine # [    1.857618] systemd-modules-load[95]: Inserted module 'virtio_gpu'594vm-test-run-flakelet> machine # [    1.859755] systemd[1]: Finished Apply Kernel Variables.595vm-test-run-flakelet> machine # [    1.861976] systemd[1]: Starting Create System Files and Directories...596vm-test-run-flakelet> machine # [    1.864185] systemd[1]: Finished Create System Files and Directories.597vm-test-run-flakelet> machine # [    1.866984] systemd-udevd[106]: Using default interface naming scheme 'v261'.598vm-test-run-flakelet> machine # [    1.879733] systemd[1]: Started Rule-based Manager for Device Events and Files.599vm-test-run-flakelet> machine # [    1.897900] systemd[1]: Starting Virtual Console Setup...600vm-test-run-flakelet> machine # [    1.944569] systemd-vconsole-setup[128]: Configuration of first virtual console was skipped, ignoring remaining ones.601vm-test-run-flakelet> machine # [    1.947725] systemd[1]: Finished Virtual Console Setup.602vm-test-run-flakelet> machine # [    2.350320] systemd[1]: Finished Coldplug All udev Devices.603vm-test-run-flakelet> machine # [    2.352920] systemd[1]: Reached target System Initialization.604vm-test-run-flakelet> machine # [    2.355373] systemd[1]: Reached target Basic System.605vm-test-run-flakelet> machine # [    2.559749] (udev-worker)[119]: Network interface NamePolicy= disabled on kernel command line.606vm-test-run-flakelet> machine # [    2.569450] (udev-worker)[145]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.607vm-test-run-flakelet> machine # [    2.573033] (udev-worker)[145]: Network interface NamePolicy= disabled on kernel command line.608vm-test-run-flakelet> machine # [    2.589437] systemd[1]: Found device /dev/disk/by-label/nixos.609vm-test-run-flakelet> machine # [    2.601235] systemd[1]: Reached target Initrd Root Device.610vm-test-run-flakelet> machine # [    2.602770] systemd[1]: Starting File System Check on /dev/disk/by-label/nixos...611vm-test-run-flakelet> machine # [    2.647970] systemd-fsck[151]: nixos: clean, 12/65536 files, 13019/262144 blocks612vm-test-run-flakelet> machine # [    2.655704] systemd[1]: Finished File System Check on /dev/disk/by-label/nixos.613vm-test-run-flakelet> machine # [    2.699854] systemd[1]: Mounting /sysroot...614vm-test-run-flakelet> machine # [    2.736968] EXT4-fs (vda): mounted filesystem a9b48986-8c35-4cfe-9e0e-eed23f87221d r/w with ordered data mode. Quota mode: none.615vm-test-run-flakelet> machine # [    2.737739] systemd[1]: Mounted /sysroot.616vm-test-run-flakelet> machine # [    2.739265] systemd[1]: Reached target Initrd Root File System.617vm-test-run-flakelet> machine # [    2.743529] systemd[1]: Mounting /sysroot/nix/.ro-store...618vm-test-run-flakelet> machine # [    2.745841] systemd[1]: Mounting /sysroot/run...619vm-test-run-flakelet> machine # [    2.760670] systemd[1]: Mounting /sysroot/tmp/shared...620vm-test-run-flakelet> machine # [    2.764301] systemd[1]: Mounting /sysroot/tmp/xchg...621vm-test-run-flakelet> machine # [    2.770450] systemd[1]: Starting Mountpoints Configured in the Real Root...622vm-test-run-flakelet> machine # [    2.776177] systemd[1]: Starting rw-sysroot-nix-store.service...623vm-test-run-flakelet> machine # [    2.791682] systemd[1]: Mounted /sysroot/run.624vm-test-run-flakelet> machine # [    2.805005] systemd-sysroot-fstab-check[163]: /sysroot should be mounted in the initrd, will request daemon-reload.625vm-test-run-flakelet> machine # [    2.811973] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.626vm-test-run-flakelet> machine # [    2.818150] systemd[1]: Finished rw-sysroot-nix-store.service.627vm-test-run-flakelet> machine # [    2.822866] systemd[1]: Reload requested from client PID 163 ('systemd-sysroot') (unit initrd-parse-etc.service)...628vm-test-run-flakelet> machine # [    2.825207] systemd[1]: Reloading...629vm-test-run-flakelet> machine # [    2.960672] systemd[1]: Reloading finished in 136 ms.630vm-test-run-flakelet> machine # [    3.006466] systemd-sysroot-fstab-check[163]: Requesting initrd-fs.target/start/replace...631vm-test-run-flakelet> machine # [    3.007959] systemd[1]: Mounted /sysroot/nix/.ro-store.632vm-test-run-flakelet> machine # [    3.008810] systemd[1]: Mounted /sysroot/tmp/shared.633vm-test-run-flakelet> machine # [    3.009583] systemd[1]: Mounted /sysroot/tmp/xchg.634vm-test-run-flakelet> machine # [    3.011665] systemd-sysroot-fstab-check[163]: Requesting swap.target/start/replace...635vm-test-run-flakelet> machine # [    3.017711] systemd[1]: Starting rw-sysroot-nix-store.service...636vm-test-run-flakelet> machine # [    3.018719] systemd[1]: initrd-parse-etc.service: Deactivated successfully.637vm-test-run-flakelet> machine # [    3.019789] systemd[1]: Finished Mountpoints Configured in the Real Root.638vm-test-run-flakelet> machine # [    3.020986] systemd[1]: initrd-parse-etc.service: Triggering OnSuccess= dependencies.639vm-test-run-flakelet> machine # [    3.039079] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.640vm-test-run-flakelet> machine # [    3.040733] systemd[1]: Finished rw-sysroot-nix-store.service.641vm-test-run-flakelet> machine # [    3.143682] (udev-worker)[145]: mtd0ro: Failed to find and pin callout binary "/nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/mtd_probe": No such file or directory642vm-test-run-flakelet> machine # [    3.147552] (udev-worker)[145]: mtd0ro: /nix/store/z2nxjmk4666nbka3hgyxlg13prrnvx38-systemd-261/lib/udev/rules.d/75-probe_mtd.rules:5 IMPORT{program}="mtd_probe $devnode": Failed to execute "mtd_probe /dev/mtd0ro", ignoring: No such file or directory643vm-test-run-flakelet> machine # [    3.155400] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.644vm-test-run-flakelet> machine # [    3.156479] systemd[1]: Stopped Virtual Console Setup.645vm-test-run-flakelet> machine # [    3.157208] systemd[1]: Stopping Virtual Console Setup...646vm-test-run-flakelet> machine # [    3.157960] systemd[1]: Starting Virtual Console Setup...647vm-test-run-flakelet> machine # [    3.198637] systemd-vconsole-setup[194]: Configuration of first virtual console was skipped, ignoring remaining ones.648vm-test-run-flakelet> machine # [    3.200681] systemd[1]: Finished Virtual Console Setup.649vm-test-run-flakelet> machine # [    3.703909] systemd[1]: Mounting /sysroot/nix/store...650vm-test-run-flakelet> machine # [    3.780063] systemd[1]: Mounted /sysroot/nix/store.651vm-test-run-flakelet> machine # [    3.782522] systemd[1]: Reached target Initrd File Systems.652vm-test-run-flakelet> machine # [    3.784968] systemd[1]: Starting Find NixOS closure...653vm-test-run-flakelet> machine # [    3.787697] systemd[1]: Starting Create Volatile Files and Directories in the Real Root...654vm-test-run-flakelet> machine # [    3.837099] systemd[1]: Finished Create Volatile Files and Directories in the Real Root.655vm-test-run-flakelet> machine # [    3.840654] systemd[1]: sysroot-run-credentials-systemd\x2dtmpfiles\x2dsetup\x2dsysroot.service.mount: Deactivated successfully.656vm-test-run-flakelet> machine # [    3.853551] systemd[1]: Finished Find NixOS closure.657vm-test-run-flakelet> machine # [    3.855754] systemd[1]: Reached target Initrd Default Target.658vm-test-run-flakelet> machine # [    3.858297] systemd[1]: Starting Cleaning Up and Shutting Down Daemons...659vm-test-run-flakelet> machine # [    3.909026] systemd[1]: Stopped target Initrd Default Target.660vm-test-run-flakelet> machine # [    3.911569] systemd[1]: Stopped target Basic System.661vm-test-run-flakelet> machine # [    3.913836] systemd[1]: Stopped target Initrd Root Device.662vm-test-run-flakelet> machine # [    3.916199] systemd[1]: Stopped target Path Units.663vm-test-run-flakelet> machine # [    3.918322] systemd[1]: systemd-ask-password-console.path: Deactivated successfully.664vm-test-run-flakelet> machine # [    3.921560] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.665vm-test-run-flakelet> machine # [    3.924780] systemd[1]: Stopped target Slice Units.666vm-test-run-flakelet> machine # [    3.926886] systemd[1]: Stopped target Socket Units.667vm-test-run-flakelet> machine # [    3.929106] systemd[1]: Stopped target System Initialization.668vm-test-run-flakelet> machine # [    3.931533] systemd[1]: Stopped target Swaps.669vm-test-run-flakelet> machine # [    3.933562] systemd[1]: Stopped target Timer Units.670vm-test-run-flakelet> machine # [    3.935665] systemd[1]: dbus.socket: Deactivated successfully.671vm-test-run-flakelet> machine # [    3.940293] systemd[1]: Closed D-Bus System Message Bus Socket.672vm-test-run-flakelet> machine # [    3.942964] systemd[1]: initrd-find-nixos-closure.service: Deactivated successfully.673vm-test-run-flakelet> machine # [    3.947576] systemd[1]: Stopped Find NixOS closure.674vm-test-run-flakelet> machine # [    3.949917] systemd[1]: Starting Load Kernel Module 9pnet_virtio...675vm-test-run-flakelet> machine # [    3.952140] systemd[1]: Starting rw-sysroot-nix-store.service...676vm-test-run-flakelet> machine # [    3.954297] systemd[1]: systemd-sysctl.service: Deactivated successfully.677vm-test-run-flakelet> machine # [    3.956749] systemd[1]: Stopped Apply Kernel Variables.678vm-test-run-flakelet> machine # [    3.958576] systemd[1]: systemd-modules-load.service: Deactivated successfully.679vm-test-run-flakelet> machine # [    3.961232] systemd[1]: Stopped Load Kernel Modules.680vm-test-run-flakelet> machine # [    3.962797] systemd[1]: systemd-tmpfiles-setup-sysroot.service: Deactivated successfully.681vm-test-run-flakelet> machine # [    3.965243] systemd[1]: Stopped Create Volatile Files and Directories in the Real Root.682vm-test-run-flakelet> machine # [    3.967553] systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.683vm-test-run-flakelet> machine # [    3.969846] systemd[1]: Stopped Create System Files and Directories.684vm-test-run-flakelet> machine # [    3.971738] systemd[1]: Stopped target Local File Systems.685vm-test-run-flakelet> machine # [    3.973498] systemd[1]: Stopped target Preparation for Local File Systems.686vm-test-run-flakelet> machine # [    3.975587] systemd[1]: systemd-udev-trigger.service: Deactivated successfully.687vm-test-run-flakelet> machine # [    3.977905] systemd[1]: Stopped Coldplug All udev Devices.688vm-test-run-flakelet> machine # [    3.979659] systemd[1]: Stopping Rule-based Manager for Device Events and Files...689vm-test-run-flakelet> machine # [    3.982001] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.690vm-test-run-flakelet> machine # [    3.984214] systemd[1]: Stopped Virtual Console Setup.691vm-test-run-flakelet> machine # [    3.985802] systemd[1]: initrd-cleanup.service: Deactivated successfully.692vm-test-run-flakelet> machine # [    3.987809] systemd[1]: Finished Cleaning Up and Shutting Down Daemons.693vm-test-run-flakelet> machine # [    3.989874] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.694vm-test-run-flakelet> machine # [    3.991899] systemd[1]: Finished Load Kernel Module 9pnet_virtio.695vm-test-run-flakelet> machine # [    3.993684] systemd[1]: rw-sysroot-nix-store.service: Deactivated successfully.696vm-test-run-flakelet> machine # [    3.995552] systemd[1]: Finished rw-sysroot-nix-store.service.697vm-test-run-flakelet> machine # [    3.997156] systemd[1]: systemd-udevd.service: Deactivated successfully.698vm-test-run-flakelet> machine # [    3.998820] systemd[1]: Stopped Rule-based Manager for Device Events and Files.699vm-test-run-flakelet> machine # [    4.000641] systemd[1]: systemd-udevd.service: Consumed 2.241s CPU time over 2.152s wall clock time, 31.2M memory peak.700vm-test-run-flakelet> machine # [    4.003067] systemd[1]: systemd-udevd-control.socket: Deactivated successfully.701vm-test-run-flakelet> machine # [    4.004810] systemd[1]: Closed udev Control Socket.702vm-test-run-flakelet> machine # [    4.005982] systemd[1]: Starting Cleanup udev Database...703vm-test-run-flakelet> machine # [    4.007201] systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.704vm-test-run-flakelet> machine # [    4.010008] systemd[1]: Stopped Create Static Device Nodes in /dev.705vm-test-run-flakelet> machine # [    4.011469] systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.706vm-test-run-flakelet> machine # [    4.013536] systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.707vm-test-run-flakelet> machine # [    4.015303] systemd[1]: kmod-static-nodes.service: Deactivated successfully.708vm-test-run-flakelet> machine # [    4.017080] systemd[1]: Stopped Create List of Static Device Nodes.709vm-test-run-flakelet> machine # [    4.086691] systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.710vm-test-run-flakelet> machine # [    4.090040] systemd[1]: Finished Cleanup udev Database.711vm-test-run-flakelet> machine # [    4.092521] systemd[1]: Reached target Switch Root.712vm-test-run-flakelet> machine # [    4.094654] systemd[1]: Starting NixOS Activation...713vm-test-run-flakelet> machine # [    4.386091] initrd-nixos-activation-start[223]: booting system configuration /nix/store/5kql3fz0k4kzd62nx07zvq8pj4idg7rl-nixos-system-machine-test714vm-test-run-flakelet> machine # [    4.495407] initrd-nixos-activation-start[223]: running activation script...715vm-test-run-flakelet> machine # [    5.049499] initrd-nixos-activation-start[246]: setting up /etc...716vm-test-run-flakelet> machine # [    5.350433] systemd[1]: initrd-nixos-activation.service: Deactivated successfully.717vm-test-run-flakelet> machine # [    5.353926] systemd[1]: Finished NixOS Activation.718vm-test-run-flakelet> machine # [    5.356136] systemd[1]: Starting Switch Root...719vm-test-run-flakelet> machine # [    5.399143] systemd[1]: Switching root.720vm-test-run-flakelet> machine # [    5.530487] systemd-journald[94]: Received SIGTERM from PID 1 (systemd).721vm-test-run-flakelet> machine # [    6.251057] systemd[1]: systemd 261 running in system mode (+PAM +AUDIT -SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -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)722vm-test-run-flakelet> machine # [    6.251204] systemd[1]: Detected virtualization qemu.723vm-test-run-flakelet> machine # [    6.251299] systemd[1]: Detected architecture arm64.724vm-test-run-flakelet> machine # [    6.251474] systemd[1]: Detected first boot.725vm-test-run-flakelet> machine # [    6.275672] systemd[1]: Initializing machine ID from random generator.726vm-test-run-flakelet> machine # [    6.512761] systemd[1]: bpf-restrict-fs: LSM BPF program attached727vm-test-run-flakelet> machine # [    6.666810] systemd[1]: Applying preset policy.728vm-test-run-flakelet> machine # [    7.226274] systemd[1]: Populated /etc with preset unit settings.729vm-test-run-flakelet> machine # [    7.776569] systemd[1]: initrd-switch-root.service: Deactivated successfully.730vm-test-run-flakelet> machine # [    7.777046] systemd[1]: Stopped initrd-switch-root.service.731vm-test-run-flakelet> machine # [    7.779406] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.732vm-test-run-flakelet> machine # [    7.788326] systemd[1]: Created slice Slice /system/getty.733vm-test-run-flakelet> machine # [    7.790820] systemd[1]: Created slice User and Session Slice.734vm-test-run-flakelet> machine # [    7.791324] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.735vm-test-run-flakelet> machine # [    7.791748] systemd[1]: Started Forward Password Requests to Wall Directory Watch.736vm-test-run-flakelet> machine # [    7.792285] systemd[1]: Expecting device /dev/hvc0...737vm-test-run-flakelet> machine # [    7.793165] systemd[1]: Expecting device /dev/ttyAMA0...738vm-test-run-flakelet> machine # [    7.794111] systemd[1]: Reached target Local Encrypted Volumes.739vm-test-run-flakelet> machine # [    7.795034] systemd[1]: Reached target flakelet contract providers ready.740vm-test-run-flakelet> machine # [    7.795976] systemd[1]: Stopped target initrd-fs.target.741vm-test-run-flakelet> machine # [    7.796869] systemd[1]: Stopped target initrd-root-fs.target.742vm-test-run-flakelet> machine # [    7.797809] systemd[1]: Stopped target initrd-switch-root.target.743vm-test-run-flakelet> machine # [    7.798739] systemd[1]: Reached target Virtual Machines and Containers.744vm-test-run-flakelet> machine # [    7.799641] systemd[1]: Reached target Path Units.745vm-test-run-flakelet> machine # [    7.800535] systemd[1]: Reached target Remote File Systems.746vm-test-run-flakelet> machine # [    7.801428] systemd[1]: Reached target Slice Units.747vm-test-run-flakelet> machine # [    7.802339] systemd[1]: Reached target Swaps.748vm-test-run-flakelet> machine # [    7.806907] systemd[1]: Listening on Query the User Interactively for a Password.749vm-test-run-flakelet> machine # [    7.811354] systemd[1]: Listening on Process Core Dump Socket.750vm-test-run-flakelet> machine # [    7.815095] systemd[1]: Listening on Credential Encryption/Decryption.751vm-test-run-flakelet> machine # [    7.818694] systemd[1]: Listening on Factory Reset Management.752vm-test-run-flakelet> machine # [    7.819377] systemd[1]: Listening on Hostname Service Socket.753vm-test-run-flakelet> machine # [    7.828386] systemd[1]: Starting Journal Log Access Socket...754vm-test-run-flakelet> machine # [    7.829627] systemd[1]: Listening on Journal Audit Socket.755vm-test-run-flakelet> machine # [    7.833724] systemd[1]: Listening on Console Output Muting Service Socket.756vm-test-run-flakelet> machine # [    7.834647] systemd[1]: Listening on Userspace Out-Of-Memory (OOM) Killer Socket.757vm-test-run-flakelet> machine # [    7.835230] systemd[1]: TPM PCR Measurements skipped, unmet condition check ConditionSecurity=measured-os758vm-test-run-flakelet> machine # [    7.836051] systemd[1]: Make TPM PCR Policy skipped, unmet condition check ConditionSecurity=measured-uki759vm-test-run-flakelet> machine # [    7.846804] systemd[1]: Listening on Disk Repartitioning Service Socket.760vm-test-run-flakelet> machine # [    7.847554] systemd[1]: Listening on udev Control Socket.761vm-test-run-flakelet> machine # [    7.848401] systemd[1]: Listening on udev Varlink Socket.762vm-test-run-flakelet> machine # [    7.897035] systemd[1]: Mounting Huge Pages File System...763vm-test-run-flakelet> machine # [    7.904019] systemd[1]: Mounting POSIX Message Queue File System...764vm-test-run-flakelet> machine # [    7.911196] systemd[1]: Mounting Kernel Debug File System...765vm-test-run-flakelet> machine # [    7.917743] systemd[1]: Mounting Kernel Trace File System...766vm-test-run-flakelet> machine # [    7.940894] systemd[1]: Starting Create List of Static Device Nodes...767vm-test-run-flakelet> machine # [    7.953799] systemd[1]: Starting Load Kernel Module 9pnet_virtio...768vm-test-run-flakelet> machine # [    7.955143] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs769vm-test-run-flakelet> machine # [    7.963846] systemd[1]: Mounting Kernel Configuration File System...770vm-test-run-flakelet> machine # [    7.965262] systemd[1]: Load Kernel Module drm skipped, unmet condition check ConditionKernelModuleLoaded=!drm771vm-test-run-flakelet> machine # [    7.966319] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore772vm-test-run-flakelet> machine # [    7.977059] systemd[1]: Starting Load Kernel Module fuse...773vm-test-run-flakelet> machine # [    7.978082] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67774vm-test-run-flakelet> machine # [    7.991875] systemd[1]: Starting Journal Service...775vm-test-run-flakelet> machine # [    8.020867] systemd[1]: Starting Load Kernel Modules...776vm-test-run-flakelet> machine # [    8.051006] systemd[1]: Starting Userspace Out-Of-Memory (OOM) Killer...777vm-test-run-flakelet> machine # [    8.064050] systemd[1]: Starting Remount Root and Kernel File Systems...778vm-test-run-flakelet> machine # [    8.065550] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os779vm-test-run-flakelet> machine # [    8.076211] systemd[1]: Starting Coldplug All udev Devices...780vm-test-run-flakelet> machine # [    8.095279] systemd[1]: Listening on Journal Log Access Socket.781vm-test-run-flakelet> machine # [    8.098235] systemd[1]: Mounted Huge Pages File System.782vm-test-run-flakelet> machine # [    8.102457] systemd[1]: Mounted Kernel Debug File System.783vm-test-run-flakelet> machine # [    8.104173] systemd[1]: Mounted POSIX Message Queue File System.784vm-test-run-flakelet> machine # [    8.105047] systemd[1]: Mounted Kernel Trace File System.785vm-test-run-flakelet> machine # [    8.106380] systemd[1]: Finished Create List of Static Device Nodes.786vm-test-run-flakelet> machine # [    8.107595] systemd[1]: modprobe@9pnet_virtio.service: Deactivated successfully.787vm-test-run-flakelet> machine # [    8.108681] systemd[1]: Finished Load Kernel Module 9pnet_virtio.788vm-test-run-flakelet> machine # [    8.122461] systemd[1]: Starting Create Static Device Nodes in /dev gracefully...789vm-test-run-flakelet> machine # [    8.132350] fuse: init (API version 7.45)790vm-test-run-flakelet> machine # [    8.141900] systemd[1]: Mounted Kernel Configuration File System.791vm-test-run-flakelet> machine # [    8.143630] systemd[1]: modprobe@fuse.service: Deactivated successfully.792vm-test-run-flakelet> machine # [    8.145210] systemd[1]: Finished Load Kernel Module fuse.793vm-test-run-flakelet> machine # [    8.158924] systemd[1]: Mounting FUSE Control File System...794vm-test-run-flakelet> machine # [    8.179307] systemd[1]: Finished Load Kernel Modules.795vm-test-run-flakelet> machine # [    8.180312] systemd-journald[317]: Collecting audit messages is enabled.796vm-test-run-flakelet> machine # [    8.187675] systemd[1]: Starting Firewall...797vm-test-run-flakelet> machine # [    8.195795] systemd[1]: Starting Apply Kernel Variables...798vm-test-run-flakelet> machine # [    8.195864] systemd[1]: Queued start job for default target Multi-User System.799vm-test-run-flakelet> machine # [    8.200618] systemd[1]: systemd-journald.service: Deactivated successfully.800vm-test-run-flakelet> machine # [    8.204129] EXT4-fs (vda): re-mounted a9b48986-8c35-4cfe-9e0e-eed23f87221d.801vm-test-run-flakelet> machine # [    8.204144] systemd[1]: Started Journal Service.802vm-test-run-flakelet> machine # [    8.207004] systemd-modules-load[318]: Using 2 probe threads803vm-test-run-flakelet> machine # [    8.209674] systemd-modules-load[318]: Module 'atkbd' is built in804vm-test-run-flakelet> machine # [    8.212523] systemd-modules-load[318]: Module 'loop' is built in805vm-test-run-flakelet> machine # [    8.215063] systemd-oomd[319]: No swap; memory pressure usage will be degraded806vm-test-run-flakelet> machine # [    8.217903] systemd[1]: Finished Remount Root and Kernel File Systems.807vm-test-run-flakelet> machine # [    8.223099] systemd[1]: Started Userspace Out-Of-Memory (OOM) Killer.808vm-test-run-flakelet> machine # [    8.230180] systemd[1]: Listening on Disk Image Download Service Socket.809vm-test-run-flakelet> machine # [    8.237429] systemd[1]: Starting Flush Journal to Persistent Storage...810vm-test-run-flakelet> machine # [    8.240798] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore811vm-test-run-flakelet> machine # [    8.247042] systemd[1]: Starting Load/Save OS Random Seed...812vm-test-run-flakelet> machine # [    8.251098] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os813vm-test-run-flakelet> machine # [    8.255511] systemd[1]: Mounted FUSE Control File System.814vm-test-run-flakelet> machine # [    8.289244] systemd-journald[317]: Received client request to flush runtime journal.815vm-test-run-flakelet> machine # [    8.314026] systemd[1]: Finished Create Static Device Nodes in /dev gracefully.816vm-test-run-flakelet> machine # [    8.316951] systemd[1]: Starting Create Static Device Nodes in /dev...817vm-test-run-flakelet> machine # [    8.321061] systemd[1]: Finished Apply Kernel Variables.818vm-test-run-flakelet> machine # [    8.322779] systemd[1]: Finished Load/Save OS Random Seed.819vm-test-run-flakelet> machine # [    8.324436] systemd[1]: Reached target First Boot Complete.820vm-test-run-flakelet> machine # [    8.326183] systemd[1]: Finished Flush Journal to Persistent Storage.821vm-test-run-flakelet> machine # [    8.362546] systemd[1]: Finished Create Static Device Nodes in /dev.822vm-test-run-flakelet> machine # [    8.364694] systemd[1]: Reached target Preparation for Local File Systems.823vm-test-run-flakelet> machine # [    8.369049] systemd[1]: Starting Rule-based Manager for Device Events and Files...824vm-test-run-flakelet> machine # [    8.452619] systemd-udevd[355]: Using default interface naming scheme 'v261'.825vm-test-run-flakelet> machine # [    8.562425] systemd[1]: Started Rule-based Manager for Device Events and Files.826vm-test-run-flakelet> machine # [    8.780800] systemd[1]: Mounting /run/wrappers...827vm-test-run-flakelet> machine # [    8.824993] systemd[1]: Finished Coldplug All udev Devices.828vm-test-run-flakelet> machine # [    8.843155] systemd[1]: Mounted /run/wrappers.829vm-test-run-flakelet> machine # [    8.845120] systemd[1]: Reached target Local File Systems.830vm-test-run-flakelet> machine # [    8.849771] systemd[1]: Listening on Boot Loader Control Service Socket.831vm-test-run-flakelet> machine # [    8.857940] systemd[1]: Starting register-nix-paths.service...832vm-test-run-flakelet> machine # [    8.864637] systemd[1]: Starting Create SUID/SGID Wrappers...833vm-test-run-flakelet> machine # [    8.869168] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.834vm-test-run-flakelet> machine # [    8.883898] systemd[1]: Starting Save Transient machine-id to Disk...835vm-test-run-flakelet> machine # [    8.892171] systemd[1]: Starting Create System Files and Directories...836vm-test-run-flakelet> machine # [    8.901129] systemd[1]: Load Kernel Module configfs skipped, unmet condition check ConditionKernelModuleLoaded=!configfs837vm-test-run-flakelet> machine # [    8.940508] systemd[1]: etc-machine\x2did.mount: Deactivated successfully.838vm-test-run-flakelet> machine # [    8.942895] systemd[1]: Finished Save Transient machine-id to Disk.839vm-test-run-flakelet> machine # [    8.969475] systemd[1]: Condition check resulted in /dev/hvc0 being skipped.840vm-test-run-flakelet> machine # [    9.008811] systemd[1]: Finished Create System Files and Directories.841vm-test-run-flakelet> machine # [    9.023982] systemd[1]: Condition check resulted in /dev/ttyAMA0 being skipped.842vm-test-run-flakelet> machine # [    9.028035] systemd[1]: Starting Rebuild Journal Catalog...843vm-test-run-flakelet> machine # [    9.031523] systemd[1]: Starting Record System Boot/Shutdown in UTMP...844vm-test-run-flakelet> machine # [    9.094033] systemd[1]: Finished Record System Boot/Shutdown in UTMP.845vm-test-run-flakelet> machine # [    9.128672] systemd[1]: Finished Rebuild Journal Catalog.846vm-test-run-flakelet> machine # [    9.138508] systemd[1]: Starting Update is Completed...847vm-test-run-flakelet> machine # [    9.155253] (udev-worker)[386]: eth1: Config file /etc/systemd/network/40-eth1.link is applied to device based on potentially unpredictable interface name.848vm-test-run-flakelet> machine # [    9.162368] (udev-worker)[390]: Network interface NamePolicy= disabled on kernel command line.849vm-test-run-flakelet> machine # [    9.167317] (udev-worker)[386]: Network interface NamePolicy= disabled on kernel command line.850vm-test-run-flakelet> machine # [    9.203757] systemd[1]: Finished Update is Completed.851vm-test-run-flakelet> machine # [    9.212625] mousedev: PS/2 mouse device common for all mice852vm-test-run-flakelet> machine # [    9.213727] systemd[1]: Condition check resulted in Virtio network device being skipped.853vm-test-run-flakelet> machine # [    9.218289] systemd[1]: Load Kernel Module efi_pstore skipped, unmet condition check ConditionKernelModuleLoaded=!efi_pstore854vm-test-run-flakelet> machine # [    9.221885] systemd[1]: Update Boot Loader Random Seed skipped, no trigger condition checks were met.855vm-test-run-flakelet> machine # [    9.225625] systemd[1]: Clear Stale Hibernate Storage Info skipped, unmet condition check ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67856vm-test-run-flakelet> machine # [    9.229510] systemd[1]: Platform Persistent Storage Archival skipped, unmet condition check ConditionDirectoryNotEmpty=/sys/fs/pstore857vm-test-run-flakelet> machine # [    9.232367] systemd[1]: Early TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os858vm-test-run-flakelet> machine # [    9.235646] systemd[1]: TPM SRK Setup skipped, unmet condition check ConditionSecurity=measured-os859vm-test-run-flakelet> machine # [    9.436219] systemd[1]: suid-sgid-wrappers.service: Deactivated successfully.860vm-test-run-flakelet> machine # [    9.439233] systemd[1]: Finished Create SUID/SGID Wrappers.861vm-test-run-flakelet> machine # [    9.499405] systemd[1]: Finished Firewall.862vm-test-run-flakelet> machine # [    9.653305] input: QEMU Virtio Keyboard as /devices/platform/4010000000.pcie/pci0000:00/0000:00:09.0/virtio8/input/input3863vm-test-run-flakelet> machine # [    9.672097] systemd[1]: Finished register-nix-paths.service.864vm-test-run-flakelet> machine # [    9.673595] systemd[1]: Reached target System Initialization.865vm-test-run-flakelet> machine # [    9.675359] systemd[1]: Started Discard unused filesystem blocks once a week.866vm-test-run-flakelet> machine # [    9.676474] systemd[1]: Started Daily Cleanup of Temporary Directories.867vm-test-run-flakelet> machine # [    9.677596] systemd[1]: Reached target Timer Units.868vm-test-run-flakelet> machine # [    9.680281] systemd[1]: Listening on D-Bus System Message Bus Socket.869vm-test-run-flakelet> machine # [    9.681305] systemd[1]: Listening on Nix Daemon Socket.870vm-test-run-flakelet> machine # [    9.682484] systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.871vm-test-run-flakelet> machine # [    9.684905] systemd[1]: Reached target Socket Units.872vm-test-run-flakelet> machine # [    9.686017] systemd[1]: Reached target Basic System.873vm-test-run-flakelet> machine # [    9.686840] systemd[1]: Started backdoor.service.874vm-test-run-flakelet> machine # [    9.696760] systemd[1]: Starting Re-link flakelet services at boot...875vm-test-run-flakelet> machine # [    9.701652] systemd[1]: Starting Import lastlog data into lastlog2 database...876vm-test-run-flakelet> machine # [    9.708514] systemd[1]: Starting Name Service Cache Daemon (nsncd)...877vm-test-run-flakelet> machine # [    9.716568] systemd[1]: Starting Post-Boot Actions...878vm-test-run-flakelet> machine # [    9.720327] systemd[1]: Started Reset console on configuration changes.879vm-test-run-flakelet> machine # [    9.726393] systemd[1]: Starting resolvconf update...880vm-test-run-flakelet> machine # [    9.736866] systemd[1]: Starting D-Bus System Message Bus...881vm-test-run-flakelet> machine # [    9.779854] systemd[1]: Finished Re-link flakelet services at boot.882vm-test-run-flakelet> machine # connecting to host...883vm-test-run-flakelet> machine # [    9.784973] systemd[1]: Finished Post-Boot Actions.884vm-test-run-flakelet> machine # [    9.806655] systemd[1]: Starting Reconcile flakelet services with the host configuration...885vm-test-run-flakelet> machine # [    9.810737] nsncd[562]: Aug 31 08:43:05.631 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"886vm-test-run-flakelet> machine # [    9.816566] systemd[1]: Starting Virtual Console Setup...887vm-test-run-flakelet> machine # [    9.817940] systemd[1]: Started Name Service Cache Daemon (nsncd).888vm-test-run-flakelet> machine: Guest shell says: b'Spawning backdoor root shell...\n'889vm-test-run-flakelet> machine: connected to guest root shell890vm-test-run-flakelet> machine # [    9.832579] systemd[1]: Finished Import lastlog data into lastlog2 database.891vm-test-run-flakelet> machine: (connecting took 10.24 seconds)892vm-test-run-flakelet> machine: (finished: waiting for the VM to finish booting, in 10.70 seconds)893vm-test-run-flakelet> machine # [    9.835189] systemd[1]: Reached target Host and Network Name Lookups.894vm-test-run-flakelet> machine # [    9.839377] systemd[1]: Reached target User and Group Name Lookups.895vm-test-run-flakelet> machine # [    9.847283] systemd[1]: Starting User Login Management...896vm-test-run-flakelet> machine # [    9.852339] systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.897vm-test-run-flakelet> machine # [    9.854421] systemd[1]: Stopped Virtual Console Setup.898vm-test-run-flakelet> machine # [    9.860442] systemd[1]: Starting Virtual Console Setup...899vm-test-run-flakelet> machine # [    9.874758] systemd[1]: Finished Reconcile flakelet services with the host configuration.900vm-test-run-flakelet> machine # [    9.890465] dbus-broker-launch[569]: Looking up NSS user entry for 'systemd-timesync'...901vm-test-run-flakelet> machine # [    9.925707] systemd-logind[608]: New seat seat0.902vm-test-run-flakelet> machine # [    9.927865] systemd-logind[608]: Watching system buttons on /dev/input/event3 (QEMU Virtio Keyboard)903vm-test-run-flakelet> machine # [    9.932577] systemd-logind[608]: Watching system buttons on /dev/input/event1 (QEMU QEMU USB Keyboard)904vm-test-run-flakelet> machine # [    9.936724] systemd-logind[608]: Watching system buttons on /dev/input/event0 (gpio-keys)905vm-test-run-flakelet> machine # [    9.954975] systemd[1]: Started User Login Management.906vm-test-run-flakelet> machine # [    9.959530] dbus-broker-launch[569]: NSS returned no entry for 'systemd-timesync'907vm-test-run-flakelet> machine # [    9.965818] dbus-broker-launch[569]: Invalid user-name in /nix/store/bf92frfbn0nv5cw1b1iqmz5f8plg6zzx-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"908vm-test-run-flakelet> machine # [    9.971629] systemd[1]: Starting linger-users.service...909vm-test-run-flakelet> machine # [    9.974882] systemd[1]: Stopped target Host and Network Name Lookups.910vm-test-run-flakelet> machine # [    9.979908] systemd[1]: Stopping Host and Network Name Lookups...911vm-test-run-flakelet> machine # [    9.985703] systemd[1]: Stopped target User and Group Name Lookups.912vm-test-run-flakelet> machine # [    9.989703] systemd[1]: Stopping User and Group Name Lookups...913vm-test-run-flakelet> machine # [    9.991625] systemd[1]: Stopping Name Service Cache Daemon (nsncd)...914vm-test-run-flakelet> machine # [    9.996073] systemd[1]: nscd.service: Deactivated successfully.915vm-test-run-flakelet> machine # [    9.998852] systemd[1]: Stopped Name Service Cache Daemon (nsncd).916vm-test-run-flakelet> machine # [   10.001710] systemd[1]: Starting Name Service Cache Daemon (nsncd)...917vm-test-run-flakelet> machine # [   10.002892] systemd[1]: Started D-Bus System Message Bus.918vm-test-run-flakelet> machine # [   10.023319] dbus-broker-launch[569]: Ready919vm-test-run-flakelet> machine # [   10.024082] systemd[1]: linger-users.service: Deactivated successfully.920vm-test-run-flakelet> machine # [   10.025765] systemd[1]: Finished linger-users.service.921vm-test-run-flakelet> machine # [   10.037947] nsncd[658]: Aug 31 08:43:05.861 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"922vm-test-run-flakelet> machine # [   10.043599] systemd[1]: Started Name Service Cache Daemon (nsncd).923vm-test-run-flakelet> machine # [   10.046559] systemd[1]: Reached target Host and Network Name Lookups.924vm-test-run-flakelet> machine # [   10.048571] systemd[1]: Reached target User and Group Name Lookups.925vm-test-run-flakelet> machine # [   10.053155] systemd[1]: Finished resolvconf update.926vm-test-run-flakelet> machine # [   10.054592] systemd[1]: Reached target Preparation for Network.927vm-test-run-flakelet> machine # [   10.058284] systemd[1]: Starting DHCP Client...928vm-test-run-flakelet> machine # [   10.061544] systemd[1]: Starting Address configuration of eth1...929vm-test-run-flakelet> machine # [   10.066241] systemd[1]: Starting Extra networking commands....930vm-test-run-flakelet> machine # [   10.163377] network-addresses-eth1-start[682]: adding address 192.168.1.1/24... done931vm-test-run-flakelet> machine # [   10.174037] network-addresses-eth1-start[682]: adding address 2001:db8:1::1/64... done932vm-test-run-flakelet> machine # [   10.188946] systemd[1]: Finished Address configuration of eth1.933vm-test-run-flakelet> machine # [   10.206290] dhcpcd[689]: dhcpcd-10.3.2 starting934vm-test-run-flakelet> machine # [   10.214453] dhcpcd[739]: dev: loaded udev935vm-test-run-flakelet> machine # [   10.231335] systemd[1]: Finished Extra networking commands..936vm-test-run-flakelet> machine # [   10.232469] systemd[1]: Reached target Network.937vm-test-run-flakelet> machine # [   10.234953] systemd[1]: Starting Permit User Sessions...938vm-test-run-flakelet> machine # [   10.239495] systemd[1]: Starting Update flakelet service static...939vm-test-run-flakelet> machine # [   10.248668] 8021q: 802.1Q VLAN Support v1.8940vm-test-run-flakelet> machine # [   10.249088] 8021q: adding VLAN 0 to HW filter on device eth1941vm-test-run-flakelet> machine # [   10.257605] systemd[1]: Starting Update flakelet service web...942vm-test-run-flakelet> machine # [   10.277150] systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.943vm-test-run-flakelet> machine # [   10.281678] systemd[1]: Finished Permit User Sessions.944vm-test-run-flakelet> machine # [   10.286376] systemd[1]: Started Getty on tty1.945vm-test-run-flakelet> machine # [   10.287543] systemd[1]: Reached target Login Prompts.946vm-test-run-flakelet> machine # [   10.294176] flakelet[750]: static: using prebuilt artifact /nix/store/d4wsijyvrlqv20ah5mv211f8zv4gayxk-flakelet-static947vm-test-run-flakelet> machine # [   10.302188] flakelet[751]: web: resolving path:/nix/store/1ndj6dy6hwr8inncmsdbwhm9pd6vbdvp-flake.nix948vm-test-run-flakelet> machine # [   10.311019] cfg80211: Loading compiled-in X.509 certificates for regulatory database949vm-test-run-flakelet> machine # [   10.307256] flakelet[750]: static: activating generation 1950vm-test-run-flakelet> machine # [   10.320277] systemd[1]: Reload requested from client PID 756 ('systemctl') (unit flakelet-static.service)...951vm-test-run-flakelet> machine # [   10.324813] systemd[1]: Reloading...952vm-test-run-flakelet> machine # [   10.326653] systemd-vconsole-setup[619]: Configuration of first virtual console was skipped, ignoring remaining ones.953vm-test-run-flakelet> machine # [   10.337230] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'954vm-test-run-flakelet> machine # [   10.337738] Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600'955vm-test-run-flakelet> machine # [   10.341591] faux_driver regulatory: Direct firmware load for regulatory.db failed with error -2956vm-test-run-flakelet> machine # [   10.341945] cfg80211: failed to load regulatory.db957vm-test-run-flakelet> machine # [   10.414979] 8021q: adding VLAN 0 to HW filter on device eth0958vm-test-run-flakelet> machine # [   10.412343] dhcpcd[739]: eth0: waiting for carrier959vm-test-run-flakelet> machine # [   10.416889] dhcpcd[739]: eth0: carrier acquired960vm-test-run-flakelet> machine # [   10.426484] dhcpcd[739]: DUID 00:01:00:01:32:27:fb:1a:52:54:00:12:34:56961vm-test-run-flakelet> machine # [   10.427595] dhcpcd[739]: eth0: IAID 00:12:34:56962vm-test-run-flakelet> machine # [   10.428574] dhcpcd[739]: eth0: adding address fe80::5054:ff:fe12:3456963vm-test-run-flakelet> machine # [   10.909833] systemd[1]: Reloading finished in 587 ms.964vm-test-run-flakelet> machine # [   10.988567] systemd[1]: Finished Virtual Console Setup.965vm-test-run-flakelet> machine # [   10.999996] systemd[1]: Started Nix Daemon.966vm-test-run-flakelet> machine # [   11.058209] systemd[1]: Reload requested from client PID 815 ('systemctl') (unit flakelet-static.service)...967vm-test-run-flakelet> machine # [   11.062057] systemd[1]: Reloading...968vm-test-run-flakelet> machine # [   11.675642] systemd[1]: Reloading finished in 611 ms.969vm-test-run-flakelet> machine # [   11.838137] nix-daemon[812]: accepted connection from pid 755, user flakelet970vm-test-run-flakelet> machine # [   11.844594] systemd[1]: Created slice Slice /system/static-echo.971vm-test-run-flakelet> machine # [   11.849557] systemd[1]: Listening on static-echo@1.socket.972vm-test-run-flakelet> machine # [   11.854174] systemd[1]: Listening on static-echo@2.socket.973vm-test-run-flakelet> machine # [   11.876177] systemd[1]: Starting prebuilt flakelet service static...974vm-test-run-flakelet> machine # [   11.901728] systemd[1]: Listening on static-echo@3.socket.975vm-test-run-flakelet> machine # [   11.930882] systemd[1]: Started prebuilt flakelet service static.976vm-test-run-flakelet> machine # [   12.153145] flakelet[751]: web: evaluating /nix/store/xzc6sfn40lzk3g23lj2g3xmkh1fnsjx0-flakelet-driver.nix977vm-test-run-flakelet> machine # [   12.261057] nix-daemon[812]: accepted connection from pid 885, user flakelet978vm-test-run-flakelet> machine # [   12.369375] dhcpcd[739]: eth0: soliciting an IPv6 router979vm-test-run-flakelet> machine # [   12.374759] dhcpcd[739]: eth0: Router Advertisement from fe80::2980vm-test-run-flakelet> machine # [   12.380897] dhcpcd[739]: eth0: adding address fec0::5054:ff:fe12:3456/64981vm-test-run-flakelet> machine # [   12.387460] dhcpcd[739]: eth0: adding route to fec0::/64982vm-test-run-flakelet> machine # [   12.391577] dhcpcd[739]: eth0: adding default route via fe80::2983vm-test-run-flakelet> machine # [   12.395242] dhcpcd[739]: eth0: soliciting a DHCP lease984vm-test-run-flakelet> machine # [   12.401054] dhcpcd[739]: eth0: offered 10.0.2.15 from 10.0.2.2985vm-test-run-flakelet> machine # [   12.428509] dhcpcd[739]: eth0: probing address 10.0.2.15/24986vm-test-run-flakelet> machine # [   12.692952] flakelet[750]: static: updated to generation 1987vm-test-run-flakelet> machine # [   12.696407] systemd[1]: Finished Update flakelet service static.988vm-test-run-flakelet> machine # [   17.995458] dhcpcd[739]: eth0: leased 10.0.2.15 for 86400 seconds989vm-test-run-flakelet> machine # [   17.995790] dhcpcd[739]: eth0: adding route to 10.0.2.0/24990vm-test-run-flakelet> machine # [   17.995926] dhcpcd[739]: eth0: adding default route via 10.0.2.2991vm-test-run-flakelet> machine # [   18.238468] systemd[1]: Started DHCP Client.992vm-test-run-flakelet> machine # [   21.031110] flakelet[751]: web: building /nix/store/j25ylrbpc0fh4gm9irhnxqx0mrzicswr-flakelet-web.drv993vm-test-run-flakelet> machine # [   22.518274] nix-daemon[812]: accepted connection from pid 1140, user flakelet994vm-test-run-flakelet> machine # [   22.557076] flakelet[751]: web: activating generation 1995vm-test-run-flakelet> machine # [   22.572304] systemd[1]: Reload requested from client PID 1148 ('systemctl') (unit flakelet-web.service)...996vm-test-run-flakelet> machine # [   22.577493] systemd[1]: Reloading...997vm-test-run-flakelet> machine # [   23.252145] systemd[1]: Reloading finished in 676 ms.998vm-test-run-flakelet> machine # [   23.433366] systemd[1]: Reload requested from client PID 1190 ('systemctl') (unit flakelet-web.service)...999vm-test-run-flakelet> machine # [   23.440739] systemd[1]: Reloading...1000vm-test-run-flakelet> machine # [   24.047421] systemd[1]: Reloading finished in 611 ms.1001vm-test-run-flakelet> machine # [   24.121615] systemd[1]: Starting flakelet test service...1002vm-test-run-flakelet> machine # [   24.164642] systemd[1]: Started flakelet test service.1003vm-test-run-flakelet> machine # [   24.329903] flakelet[751]: web: updated to generation 11004vm-test-run-flakelet> machine # [   24.332636] systemd[1]: Finished Update flakelet service web.1005vm-test-run-flakelet> machine # [   24.335078] systemd[1]: Reached target flakelet managed services.1006vm-test-run-flakelet> machine # [   24.337655] systemd[1]: Reached target Multi-User System.1007vm-test-run-flakelet> machine # [   24.339865] systemd[1]: Startup finished in 799ms (kernel) + 4.877s (initrd) + 18.657s (userspace) = 24.335s.1008vm-test-run-flakelet> machine: (finished: waiting for unit multi-user.target, in 26.14 seconds)1009vm-test-run-flakelet> machine: must succeed: systemctl start flakelet-web.service1010vm-test-run-flakelet> machine: (finished: must succeed: systemctl start flakelet-web.service, in 0.07 seconds)1011vm-test-run-flakelet> machine: must succeed: systemctl is-active web.service1012vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active web.service, in 0.05 seconds)1013vm-test-run-flakelet> machine: must succeed: systemctl show web.service -p Environment | grep -q GREETING=hello1014vm-test-run-flakelet> machine: (finished: must succeed: systemctl show web.service -p Environment | grep -q GREETING=hello, in 0.06 seconds)1015vm-test-run-flakelet> machine: must succeed: test -f /nix/var/nix/gcroots/flakelet/web/gen-1/manifest.json1016vm-test-run-flakelet> machine: (finished: must succeed: test -f /nix/var/nix/gcroots/flakelet/web/gen-1/manifest.json, in 0.03 seconds)1017vm-test-run-flakelet> machine: must succeed: flakelet status | grep -q '^web'1018vm-test-run-flakelet> machine: (finished: must succeed: flakelet status | grep -q '^web', in 0.88 seconds)1019vm-test-run-flakelet> machine: must succeed: grep -q '"port": 8080' /run/flakelet/exports/web.json1020vm-test-run-flakelet> machine: (finished: must succeed: grep -q '"port": 8080' /run/flakelet/exports/web.json, in 0.04 seconds)1021vm-test-run-flakelet> machine: must succeed: systemctl start flakelet-static.service1022vm-test-run-flakelet> machine: (finished: must succeed: systemctl start flakelet-static.service, in 0.06 seconds)1023vm-test-run-flakelet> machine: must succeed: systemctl is-active static.service1024vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active static.service, in 0.04 seconds)1025vm-test-run-flakelet> machine: must succeed: systemctl is-active static-echo@1.socket static-echo@2.socket1026vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active static-echo@1.socket static-echo@2.socket, in 0.04 seconds)1027vm-test-run-flakelet> machine: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/static-echo/2.sock1028vm-test-run-flakelet> machine: (finished: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/static-echo/2.sock, in 0.05 seconds)1029vm-test-run-flakelet> machine: waiting for success: systemctl is-active static-echo@2.service1030vm-test-run-flakelet> machine # [   26.640892] systemd[1]: Starting static-echo@2.service...1031vm-test-run-flakelet> machine # [   26.674791] systemd[1]: Started static-echo@2.service.1032vm-test-run-flakelet> machine: (finished: waiting for success: systemctl is-active static-echo@2.service, in 0.05 seconds)1033vm-test-run-flakelet> machine: must succeed: systemctl is-active static-echo@3.socket1034vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active static-echo@3.socket, in 0.04 seconds)1035vm-test-run-flakelet> machine: must succeed: flakelet activate cli /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli1036vm-test-run-flakelet> machine # cli: using prebuilt artifact /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli1037vm-test-run-flakelet> machine # cli: activating generation 11038vm-test-run-flakelet> machine # [   26.813302] systemd[1]: Reload requested from client PID 1298 ('systemctl') (unit backdoor.service)...1039vm-test-run-flakelet> machine # [   26.815458] systemd[1]: Reloading...1040vm-test-run-flakelet> machine # [   27.530465] systemd[1]: Reloading finished in 712 ms.1041vm-test-run-flakelet> machine # [   27.702643] systemd[1]: Reload requested from client PID 1342 ('systemctl') (unit backdoor.service)...1042vm-test-run-flakelet> machine # [   27.705699] systemd[1]: Reloading...1043vm-test-run-flakelet> machine # [   28.381833] systemd[1]: Reloading finished in 673 ms.1044vm-test-run-flakelet> machine # [   28.455371] systemd[1]: Created slice Slice /system/cli-echo.1045vm-test-run-flakelet> machine # [   28.458293] systemd[1]: Listening on cli-echo@1.socket.1046vm-test-run-flakelet> machine # [   28.464202] systemd[1]: Listening on cli-echo@2.socket.1047vm-test-run-flakelet> machine # [   28.474962] systemd[1]: Starting prebuilt flakelet service cli...1048vm-test-run-flakelet> machine # [   28.479592] systemd[1]: Listening on cli-echo@3.socket.1049vm-test-run-flakelet> machine # [   28.529769] systemd[1]: Started prebuilt flakelet service cli.1050vm-test-run-flakelet> machine: (finished: must succeed: flakelet activate cli /nix/store/85x399n5c1linb3lh03s8jwr8d390mmw-flakelet-cli, in 2.53 seconds)1051vm-test-run-flakelet> machine: must succeed: systemctl is-active cli.service1052vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active cli.service, in 0.03 seconds)1053vm-test-run-flakelet> machine: must succeed: flakelet status --json | grep -q 'prebuilt:cli'1054vm-test-run-flakelet> machine: (finished: must succeed: flakelet status --json | grep -q 'prebuilt:cli', in 0.94 seconds)1055vm-test-run-flakelet> machine: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken1056vm-test-run-flakelet> machine # cli: using prebuilt artifact /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken1057vm-test-run-flakelet> machine # cli: activating generation 21058vm-test-run-flakelet> machine # [   30.442170] systemd[1]: cli-echo@3.socket: Deactivated successfully.1059vm-test-run-flakelet> machine # [   30.445172] systemd[1]: Closed cli-echo@3.socket.1060vm-test-run-flakelet> machine # [   30.452257] systemd[1]: cli-echo@1.socket: Deactivated successfully.1061vm-test-run-flakelet> machine # [   30.454936] systemd[1]: Closed cli-echo@1.socket.1062vm-test-run-flakelet> machine # [   30.462443] systemd[1]: cli-echo@2.socket: Deactivated successfully.1063vm-test-run-flakelet> machine # [   30.465317] systemd[1]: Closed cli-echo@2.socket.1064vm-test-run-flakelet> machine # [   30.473544] systemd[1]: Stopping prebuilt flakelet service cli...1065vm-test-run-flakelet> machine # [   30.478729] systemd[1]: cli.service: Deactivated successfully.1066vm-test-run-flakelet> machine # [   30.481524] systemd[1]: Stopped prebuilt flakelet service cli.1067vm-test-run-flakelet> machine # [   30.669948] systemd[1]: Reload requested from client PID 1418 ('systemctl') (unit backdoor.service)...1068vm-test-run-flakelet> machine # [   30.675060] systemd[1]: Reloading...1069vm-test-run-flakelet> machine # [   31.340140] systemd[1]: Reloading finished in 667 ms.1070vm-test-run-flakelet> machine # [   31.495547] systemd[1]: Reload requested from client PID 1460 ('systemctl') (unit backdoor.service)...1071vm-test-run-flakelet> machine # [   31.500308] systemd[1]: Reloading...1072vm-test-run-flakelet> machine # [   32.209068] systemd[1]: Reloading finished in 705 ms.1073vm-test-run-flakelet> machine # [   32.303675] systemd[1]: Reload requested from client PID 1498 ('systemctl') (unit backdoor.service)...1074vm-test-run-flakelet> machine # [   32.309024] systemd[1]: Reloading...1075vm-test-run-flakelet> machine # [   32.968120] systemd[1]: Reloading finished in 660 ms.1076vm-test-run-flakelet> machine # [   33.060009] systemd[1]: Reload requested from client PID 1537 ('systemctl') (unit backdoor.service)...1077vm-test-run-flakelet> machine # [   33.066043] systemd[1]: Reloading...1078vm-test-run-flakelet> machine # [   33.424830] systemd[1]: multi-user.target: Wants dependency dropin /run/systemd/system/multi-user.target.wants/cli.service target /nix/store/z84ijpkkv9wps6zd00rg19z89z9yvb85-cli.service has different name1079vm-test-run-flakelet> machine # [   33.644267] systemd[1]: Reloading finished in 582 ms.1080vm-test-run-flakelet> machine # [   33.757683] systemd[1]: Starting cli.service...1081vm-test-run-flakelet> machine # [   33.802871] (nonexistent)[1577]: cli.service: Unable to locate executable '/nonexistent': No such file or directory1082vm-test-run-flakelet> machine # [   33.813509] (nonexistent)[1577]: cli.service: Failed at step EXEC spawning /nonexistent: No such file or directory1083vm-test-run-flakelet> machine # [   33.823721] systemd[1]: cli.service: Main process exited, code=exited, status=203/EXEC1084vm-test-run-flakelet> machine # [   33.827519] systemd[1]: cli.service: Failed with result 'exit-code'.1085vm-test-run-flakelet> machine # [   33.831142] systemd[1]: Failed to start cli.service.1086vm-test-run-flakelet> machine # [   33.927824] systemd[1]: Reload requested from client PID 1584 ('systemctl') (unit backdoor.service)...1087vm-test-run-flakelet> machine # [   33.933053] systemd[1]: Reloading...1088vm-test-run-flakelet> machine # [   34.538119] systemd[1]: Reloading finished in 607 ms.1089vm-test-run-flakelet> machine # [   34.621961] systemd[1]: Reload requested from client PID 1623 ('systemctl') (unit backdoor.service)...1090vm-test-run-flakelet> machine # [   34.627054] systemd[1]: Reloading...1091vm-test-run-flakelet> machine # [   35.238628] systemd[1]: Reloading finished in 614 ms.1092vm-test-run-flakelet> machine # [   35.333116] systemd[1]: Reload requested from client PID 1661 ('systemctl') (unit backdoor.service)...1093vm-test-run-flakelet> machine # [   35.338156] systemd[1]: Reloading...1094vm-test-run-flakelet> machine # [   35.972914] systemd[1]: Reloading finished in 636 ms.1095vm-test-run-flakelet> machine # [   36.097693] systemd[1]: Reload requested from client PID 1705 ('systemctl') (unit backdoor.service)...1096vm-test-run-flakelet> machine # [   36.102780] systemd[1]: Reloading...1097vm-test-run-flakelet> machine # [   36.691605] systemd[1]: Reloading finished in 592 ms.1098vm-test-run-flakelet> machine # [   36.763222] systemd[1]: Listening on cli-echo@1.socket.1099vm-test-run-flakelet> machine # [   36.774651] systemd[1]: Listening on cli-echo@2.socket.1100vm-test-run-flakelet> machine # [   36.791362] systemd[1]: Starting prebuilt flakelet service cli...1101vm-test-run-flakelet> machine # [   36.798279] systemd[1]: Listening on cli-echo@3.socket.1102vm-test-run-flakelet> machine # [   36.836454] systemd[1]: Started prebuilt flakelet service cli.1103vm-test-run-flakelet> machine: (finished: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken, in 6.62 seconds)1104vm-test-run-flakelet> machine: must succeed: systemctl is-active cli.service1105vm-test-run-flakelet> machine: (finished: must succeed: systemctl is-active cli.service, in 0.04 seconds)1106vm-test-run-flakelet> machine: must succeed: test "$(ls /nix/var/nix/gcroots/flakelet/cli)" = gen-11107vm-test-run-flakelet> machine: (finished: must succeed: test "$(ls /nix/var/nix/gcroots/flakelet/cli)" = gen-1, in 0.05 seconds)1108vm-test-run-flakelet> machine: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken1109vm-test-run-flakelet> machine # cli: using prebuilt artifact /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken1110vm-test-run-flakelet> machine: (finished: must fail: flakelet activate cli /nix/store/5rlafdbdfvmf0jiv0mp6xdaxf9cpyhs2-flakelet-cli-broken, in 0.05 seconds)1111vm-test-run-flakelet> machine: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/cli-echo/1.sock1112vm-test-run-flakelet> machine: (finished: must succeed: /nix/store/2r7kl4a7jbdbs2nxz9whzp7hh5zc324l-socat-1.8.1.3/bin/socat -u /dev/null UNIX-CONNECT:/run/cli-echo/1.sock, in 0.04 seconds)1113vm-test-run-flakelet> machine: waiting for success: systemctl is-active cli-echo@1.service1114vm-test-run-flakelet> machine # [   37.031064] systemd[1]: Starting cli-echo@1.service...1115vm-test-run-flakelet> machine # [   37.082323] systemd[1]: Started cli-echo@1.service.1116vm-test-run-flakelet> machine: (finished: waiting for success: systemctl is-active cli-echo@1.service, in 0.06 seconds)1117vm-test-run-flakelet> machine: must succeed: systemctl show -P MainPID cli-echo@1.service1118vm-test-run-flakelet> machine: (finished: must succeed: systemctl show -P MainPID cli-echo@1.service, in 0.19 seconds)1119vm-test-run-flakelet> machine: must succeed: systemctl show -P MainPID cli.service1120vm-test-run-flakelet> machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.12 seconds)1121vm-test-run-flakelet> machine: must succeed: flakelet activate cli /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli2 | grep -q 'generation 2'1122vm-test-run-flakelet> machine # cli: using prebuilt artifact /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli21123vm-test-run-flakelet> machine # cli: activating generation 21124vm-test-run-flakelet> machine # [   37.493090] systemd[1]: Stopping prebuilt flakelet service cli...1125vm-test-run-flakelet> machine # [   37.499665] systemd[1]: cli.service: Deactivated successfully.1126vm-test-run-flakelet> machine # [   37.501055] systemd[1]: Stopped prebuilt flakelet service cli.1127vm-test-run-flakelet> machine # [   37.553350] systemd[1]: Reload requested from client PID 1784 ('systemctl') (unit backdoor.service)...1128vm-test-run-flakelet> machine # [   37.557211] systemd[1]: Reloading...1129vm-test-run-flakelet> machine # [   38.238520] systemd[1]: Reloading finished in 676 ms.1130vm-test-run-flakelet> machine # [   38.342357] systemd[1]: Reload requested from client PID 1827 ('systemctl') (unit backdoor.service)...1131vm-test-run-flakelet> machine # [   38.347602] systemd[1]: Reloading...1132vm-test-run-flakelet> machine # [   38.998350] systemd[1]: Reloading finished in 654 ms.1133vm-test-run-flakelet> machine # [   39.133656] systemd[1]: Reload requested from client PID 1866 ('systemctl') (unit backdoor.service)...1134vm-test-run-flakelet> machine # [   39.138374] systemd[1]: Reloading...1135vm-test-run-flakelet> machine # [   39.816982] systemd[1]: Reloading finished in 674 ms.1136vm-test-run-flakelet> machine # [   39.969393] systemd[1]: Reload requested from client PID 1911 ('systemctl') (unit backdoor.service)...1137vm-test-run-flakelet> machine # [   39.974704] systemd[1]: Reloading...1138vm-test-run-flakelet> machine # [   40.617358] systemd[1]: Reloading finished in 646 ms.1139vm-test-run-flakelet> machine # [   40.745529] systemd[1]: Starting prebuilt flakelet service cli...1140vm-test-run-flakelet> machine # [   40.792666] systemd[1]: Started prebuilt flakelet service cli.1141vm-test-run-flakelet> machine: (finished: must succeed: flakelet activate cli /nix/store/p1wi0s5gddvdx0c85gpmnpnk9nk1f0wi-flakelet-cli2 | grep -q 'generation 2', in 4.07 seconds)1142vm-test-run-flakelet> machine: must succeed: systemctl show -P MainPID cli-echo@1.service1143vm-test-run-flakelet> machine: (finished: must succeed: systemctl show -P MainPID cli-echo@1.service, in 0.05 seconds)1144vm-test-run-flakelet> machine: must succeed: systemctl show -P MainPID cli.service1145vm-test-run-flakelet> machine: (finished: must succeed: systemctl show -P MainPID cli.service, in 0.05 seconds)1146vm-test-run-flakelet> machine: must succeed: flakelet rollback cli | grep -q 'generation 1'1147vm-test-run-flakelet> machine # [   41.653707] systemd[1]: Stopping prebuilt flakelet service cli...1148vm-test-run-flakelet> machine # [   41.656311] systemd[1]: cli.service: Deactivated successfully.1149vm-test-run-flakelet> machine # [   41.657444] systemd[1]: Stopped prebuilt flakelet service cli.1150vm-test-run-flakelet> machine # [   41.711514] systemd[1]: Reload requested from client PID 1976 ('systemctl') (unit backdoor.service)...1151vm-test-run-flakelet> machine # [   41.716660] systemd[1]: Reloading...1152vm-test-run-flakelet> machine # [   42.383437] systemd[1]: Reloading finished in 668 ms.1153vm-test-run-flakelet> machine # [   42.478465] systemd[1]: Reload requested from client PID 2019 ('systemctl') (unit backdoor.service)...1154vm-test-run-flakelet> machine # [   42.483705] systemd[1]: Reloading...