�[2malpha # [  454.192520] systemd[1]: Starting Load Kernel Module efi_pstore...�[0m
�[2malpha # [  454.197467] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.�[0m
�[2malpha # [  454.201121] systemd[1]: Finished Load Kernel Module efi_pstore.�[0m
�[2malpha # [  454.203317] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).�[0m
�[2malpha # [  454.225783] nixos[1664]: �[0;1;39mfinished switching to system configuration /nix/store/b7ckpjpnkvl007mqzsa4zw2bpxkyhv9v-nixos-system-alpha-23.11pre-git�[0m�[0m
�[2malpha # [  454.230580] sshd[1662]: Received disconnect from 192.168.1.3 port 51804:11: disconnected by user�[0m
�[2malpha # [  454.231142] sshd[1662]: Disconnected from user root 192.168.1.3 port 51804�[0m
�[2mdeployer # alpha | Activation successful�[0m
�[2mdeployer # alpha | Uploading keys�[0m
�[2malpha # [  454.231674] sshd[1662]: pam_unix(sshd:session): session closed for user root�[0m
�[2mdeployer # alpha | Uploading post-activation keys...�[0m
�[2mdeployer # alpha | Uploading key post-activation�[0m
�[2malpha # [  454.234019] systemd-logind[626]: Session 24 logged out. Waiting for processes to exit.�[0m
�[2malpha # [  454.235111] systemd[1]: session-24.scope: Deactivated successfully.�[0m
�[2malpha # [  454.239180] systemd-logind[626]: Removed session 24.�[0m
�[2malpha # [  454.365156] sshd[1841]: Accepted publickey for root from 192.168.1.3 port 51810 ssh2: ECDSA SHA256:+oCBm1A1Mzt3CuOnz392raccJznodBlQkGTcQQa5HUg�[0m
�[2malpha # [  454.367205] sshd[1841]: pam_unix(sshd:session): session opened for user root(uid=0) by (uid=0)�[0m
�[2malpha # [  454.370698] systemd-logind[626]: New session 25 of user root.�[0m
�[2malpha # [  454.372946] systemd[1]: Started Session 25 of User root.�[0m
�[2malpha # [  454.442930] systemd[1]: post-activation-key.service: Deactivated successfully.�[0m
�[2malpha # [  454.443911] sshd[1841]: Received disconnect from 192.168.1.3 port 51810:11: disconnected by user�[0m
�[2malpha # [  454.444449] sshd[1841]: Disconnected from user root 192.168.1.3 port 51810�[0m
�[2mdeployer # alpha | Uploaded keys (post-activation)�[0m
�[2mdeployer #       | Failed: Child process exited with error code: 1�[0m
�[2malpha # [  454.444968] sshd[1841]: pam_unix(sshd:session): session closed for user root�[0m
�[2malpha # [  454.447724] systemd-logind[626]: Session 25 logged out. Waiting for processes to exit.�[0m
�[2malpha # [  454.448789] systemd[1]: session-25.scope: Deactivated successfully.�[0m
�[2malpha # [  454.450956] systemd-logind[626]: Removed session 25.�[0m
�[2malpha # [  454.740377] systemd[1]: post-activation-key.service: Scheduled restart job, restart counter is at 1.�[0m
�[2malpha # [  454.742775] systemd[1]: Starting post-activation-key.service...�[0m
�[2malpha # [  454.746058] post-activation-key-pre-start[1852]: flapped down�[0m
�[2malpha # [  454.746979] systemd[1]: �[0;1;38;5;185mpost-activation-key.service: Found left-over process 1853 (post-activation) in control group while starting unit. Ignoring.�[0m�[0m
�[2malpha # [  454.747752] systemd[1]: �[0;1;38;5;185mpost-activation-key.service: This usually indicates unclean termination of a previous run, or service implementation deficiencies.�[0m�[0m
�[2malpha # [  454.749194] systemd[1]: Started post-activation-key.service.�[0m
�[2mdeployer # [ERROR] Failed to complete requested operation - Last 1 lines of logs:�[0m
�[2mdeployer # [ERROR]  failure) Child process exited with error code: 1�[0m
�[2mdeployer # [ERROR] Failed to push system closure to beta - Last 5 lines of logs:�[0m
�[2mdeployer # [ERROR]  created)�[0m
�[2mdeployer # [ERROR]    state) Running�[0m
�[2mdeployer # [ERROR]   stderr) ssh: connect to host beta port 22: Connection refused�[0m
�[2mdeployer # [ERROR]   stderr) error: failed to start SSH connection to 'root@beta'�[0m
�[2mdeployer # [ERROR]  failure) Child process exited with error code: 1�[0m
�[2mdeployer # [ERROR] -----�[0m
�[2mdeployer # [ERROR] Operation failed with error: Child process exited with error code: 1�[0m
�[2mdeployer # Hint: Backtrace available - Use `RUST_BACKTRACE=1` environment variable to display a backtrace�[0m
(finished: must fail: cd /tmp/bundle &&/nix/store/9gw7im3iapqj9nf75n40ajfrqq0lwl84-colmena-0.5.0-pre/bin/colmena apply --evaluator streaming --eval-node-limit 4 --on @target, in 20.60 seconds)
alpha: �[1m�[32mmust succeed: grep SECOND /etc/deployment�[0m
(finished: must succeed: grep SECOND /etc/deployment, in 0.01 seconds)
beta: �[1m�[32mmust succeed: grep FIRST /etc/deployment�[0m
(finished: must succeed: grep FIRST /etc/deployment, in 0.01 seconds)
gamma: �[1m�[32mmust succeed: grep SECOND /etc/deployment�[0m
(finished: must succeed: grep SECOND /etc/deployment, in 0.01 seconds)
(finished: subtest: Check that we can correctly deploy to remaining nodes despite failures, in 20.66 seconds)
�[1m�[32msubtest: Check that key contents are not in the Nix store�[0m
deployer: �[1m�[32mmust succeed: ls /nix/store�[0m
(finished: must succeed: ls /nix/store, in 0.03 seconds)
deployer: 345 store paths were created
(finished: subtest: Check that key contents are not in the Nix store, in 0.14 seconds)
�[1m�[32msubtest: Check that our Nix store test is actually working�[0m
deployer: �[1m�[32mmust succeed: nix-build -E 'with import <nixpkgs> {}; writeText "forbidden-text.txt" "this must not appear in the nix store"'�[0m
�[2mdeployer # this derivation will be built:�[0m
�[2mdeployer #   /nix/store/4rmgqp8b9b19j130qz3flpr4g4lx11ab-forbidden-text.txt.drv�[0m
�[2mdeployer # building '/nix/store/4rmgqp8b9b19j130qz3flpr4g4lx11ab-forbidden-text.txt.drv'...�[0m
(finished: must succeed: nix-build -E 'with import <nixpkgs> {}; writeText "forbidden-text.txt" "this must not appear in the nix store"', in 0.30 seconds)
deployer: �[1m�[32mmust succeed: ls /nix/store�[0m
(finished: must succeed: ls /nix/store, in 0.02 seconds)
deployer: 347 store paths were created
deployer: �[1m�[32mmust succeed: grep -r 'this must not appear in the nix store' /nix/store/k11r74ncnmwdr436bbz5kmv2zj18kimy-unit-script-post-activation-key-start /nix/store/a3m6r4d67513vzlv7rdyqb05f8cn37sp-unit-dbus.service.drv /nix/store/lxrndr53nb9hbb44pminhivqz2b0z8ki-python3.10-re_assert-1.1.0.drv /nix/store/s78mgql8m7zz82wmw1qjbb8xs1x31cq5-string-hosts /nix/store/aiyj64nmphm5kgllcwmwdyrc887xrdrg-source.drv /nix/store/xbgca2g1c4mvilgrvyz51ji4lh15w6wq-python3.10-pyasn1-0.5.0.drv /nix/store/5r5c33m6jpbabznr28vqzadbfvq3w04m-python3.10-trustme-1.1.0.drv /nix/store/gy1iixz8rp0lrk8akcy6jh4hvk1s8g0n-python3.10-cython-3.0.2.drv /nix/store/mky66…
(finished: must succeed: grep -r 'this must not appear in the nix store' /nix/store/k11r74ncnmwdr436bbz5kmv2zj18kimy-unit-script-post-activation-key-start /nix/store/a3m6r4d67513vzlv7rdyqb05f8cn37sp-unit-dbus.service.drv /nix/store/lxrndr53nb9hbb44pminhivqz2b0z8ki-python3.10-re_assert-1.1.0.drv /nix/store/s78mgql8m7zz82wmw1qjbb8xs1x31cq5-string-hosts /nix/store/aiyj64nmphm5kgllcwmwdyrc887xrdrg-source.drv /nix/store/xbgca2g1c4mvilgrvyz51ji4lh15w6wq-python3.10-pyasn1-0.5.0.drv /nix/store/5r5c33m6jpbabznr28vqzadbfvq3w04m-python3.10-trustme-1.1.0.drv /nix/store/gy1iixz8rp0lrk8akcy6jh4hvk1s8g0n-python3.10-cython-3.0.2.drv /nix/store/mky66z8qnnjv8…
(finished: subtest: Check that our Nix store test is actually working, in 0.43 seconds)
�[1m�[32msubtest: Check that we can build nodes with dots in their names�[0m
deployer: �[1m�[32mmust succeed: cd /tmp/bundle &&/nix/store/9gw7im3iapqj9nf75n40ajfrqq0lwl84-colmena-0.5.0-pre/bin/colmena build --evaluator streaming --eval-node-limit 4 --on gamma.tld�[0m
�[2mdeployer # [INFO ] Using configuration: /tmp/bundle/hive.nix�[0m
�[2mdeployer # [INFO ] Enumerating nodes...�[0m
�[2mdeployer # [INFO ] Selected 1 out of 5 hosts.�[0m
�[2mdeployer # [WARN ] Streaming evaluation is an experimental feature�[0m
�[2mdeployer # gamma.tld | Evaluating gamma.tld�[0m
�[2mdeployer # gamma.tld | warning: unknown setting 'allowed-users'�[0m
�[2mdeployer # gamma.tld | warning: unknown setting 'trusted-users'�[0m
�[2mdeployer # gamma.tld | warning: `--gc-roots-dir' not specified�[0m
�[2mdeployer # gamma.tld | Building gamma.tld�[0m
�[2mdeployer # gamma.tld | Evaluated gamma.tld�[0m
�[2mdeployer # gamma.tld | /nix/store/g61xk1s18spxx4m00hn1p7hlwa49614l-nixos-system-gamma-23.11pre-git�[0m
�[2mdeployer # gamma.tld | Built "/nix/store/g61xk1s18spxx4m00hn1p7hlwa49614l-nixos-system-gamma-23.11pre-git"�[0m
�[2mdeployer #           | All done!�[0m
(finished: must succeed: cd /tmp/bundle &&/nix/store/9gw7im3iapqj9nf75n40ajfrqq0lwl84-colmena-0.5.0-pre/bin/colmena build --evaluator streaming --eval-node-limit 4 --on gamma.tld, in 5.53 seconds)
(finished: subtest: Check that we can build nodes with dots in their names, in 5.53 seconds)
(finished: run the VM test script, in 468.18 seconds)
test script finished in 468.25s
�[1m�[32mcleanup�[0m
kill machine (pid 8)
�[2malpha # qemu-kvm: terminating on signal 15 from pid 6 (/nix/store/pzf6dnxg8gf04xazzjdwarm7s03cbrgz-python3-3.10.12/bin/python3.10)�[0m
kill machine (pid 31)
�[2mbeta # qemu-kvm: terminating on signal 15 from pid 6 (/nix/store/pzf6dnxg8gf04xazzjdwarm7s03cbrgz-python3-3.10.12/bin/python3.10)�[0m
kill machine (pid 53)
�[2mdeployer # qemu-kvm: terminating on signal 15 from pid 6 (/nix/store/pzf6dnxg8gf04xazzjdwarm7s03cbrgz-python3-3.10.12/bin/python3.10)�[0m
kill machine (pid 72)
�[2mgamma # qemu-kvm: terminating on signal 15 from pid 6 (/nix/store/pzf6dnxg8gf04xazzjdwarm7s03cbrgz-python3-3.10.12/bin/python3.10)�[0m
(finished: cleanup, in 0.56 seconds)
kill vlan (pid 7)