Machine state will be reset. To keep it, pass --keep-machine-state start all VLans (finished: start all VLans, in 0.00 seconds) Test will time out and terminate in 3600 seconds run the VM test script additionally exposed symbols: machine1, machine2, vlan1, start_all, test_script, machines, machines_qemu, machines_nspawn, vlans, driver, log, os, create_machine, subtest, run_tests, join_all, retry, serial_stdout_off, serial_stdout_on, polling_condition, BaseMachine, QemuMachine, NspawnMachine, t, debug, dump_machine_ssh start all VMs machine1: systemd-nspawn running (pid 52) machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal... machine2: systemd-nspawn running (pid 53) machine2: Waiting for journal at /build/vm-state-machine2/var/log/journal... (finished: start all VMs, in 0.00 seconds) machine1: must succeed: systemctl status sshd nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE nixos-nspawn(machine2): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. nixos-nspawn(machine1): TAP vde-tap1 not found; container will be isolated from VDE nixos-nspawn(machine1): A common reason for this is that /dev/net is not available in the Nix sandbox. Try adding /dev/net to extra-sandbox-paths. Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. Note: in a future version of systemd-nspawn the default set of permitted socket address families will be restricted to AF_INET, AF_INET6 and AF_UNIX. Use --restrict-address-families= to configure the set of permitted socket address families, or set RestrictAddressFamilies= in a .nspawn file. ░ Spawning container machine2 on /build/vm-state-machine2. ░ Spawning container machine1 on /build/vm-state-machine1. machine1 # No journal boot entry found for the specified boot (+0). machine2 # [4440978.783402] machine2 systemd-journald[57]: Journal started machine2 # [4440978.783499] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/5b1c19242c1c4969ac228d55f3cacb14) is 8M, max 2.5G, 2.4G free. machine2 # [4440978.797272] machine2 systemd[1]: Finished Apply Kernel Variables. machine2 # [4440978.811792] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine2 # [4440978.824505] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... machine2 # [4440978.825228] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... machine2 # [4440978.832403] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/5b1c19242c1c4969ac228d55f3cacb14 is 3.246ms for 6 entries. machine2 # [4440978.832403] machine2 systemd-journald[57]: System Journal (/var/log/journal/5b1c19242c1c4969ac228d55f3cacb14) is 8M, max 4G, 3.9G free. machine2 # [4440978.841938] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. machine2 # [4440978.842576] machine2 systemd[1]: Reached target Preparation for Local File Systems. machine2 # [4440978.842692] machine2 systemd[1]: Reached target Local File Systems. machine2 # [4440978.843451] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. machine2 # [4440978.843491] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine2 # [4440978.844342] machine2 systemd[1]: Starting Save Transient machine-id to Disk... machine2 # [4440978.844376] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine2 # [4440978.864089] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. machine2 # [4440978.865426] machine2 systemd[1]: Starting Create System Files and Directories... machine2 # [4440978.881275] machine2 systemd-tmpfiles[125]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine2 # [4440978.881495] machine2 systemd-tmpfiles[125]: fchmod() of /var/log/journal failed: Operation not permitted machine2 # [4440978.881652] machine2 systemd-tmpfiles[125]: fchmod() of /var/log/journal/5b1c19242c1c4969ac228d55f3cacb14 failed: Operation not permitted machine2 # [4440978.881880] machine2 systemd-tmpfiles[125]: fchmod() of /run/log/journal failed: Operation not permitted machine2 # [4440978.883265] machine2 systemd[1]: Finished Create System Files and Directories. machine2 # [4440978.884314] machine2 systemd[1]: Starting Rebuild Journal Catalog... machine2 # [4440978.885040] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine2 # [4440978.896680] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine2 # [4440978.905947] machine2 systemd[1]: Finished Rebuild Journal Catalog. machine2 # [4440978.907711] machine2 systemd[1]: Starting Update is Completed... machine2 # [4440978.919391] machine2 systemd[1]: Finished Update is Completed. machine2 # [4440978.919578] machine2 systemd[1]: Reached target System Initialization. machine2 # [4440978.919669] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine2 # [4440978.919746] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. machine2 # [4440978.919769] machine2 systemd[1]: Reached target Timer Units. machine2 # [4440978.919882] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. machine2 # [4440978.919996] machine2 systemd[1]: Listening on Nix Daemon Socket. machine2 # [4440978.920967] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine2 # [4440978.921790] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine2 # [4440978.921878] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine2 # [4440978.921897] machine2 systemd[1]: Reached target Socket Units. machine2 # [4440978.921938] machine2 systemd[1]: Reached target Basic System. machine2 # [4440978.922914] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... machine2 # [4440978.923798] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [4440978.924532] machine2 systemd[1]: Starting SSH Host Keys Generation... machine2 # [4440978.925114] machine2 systemd[1]: Finished Save Transient machine-id to Disk. machine2 # [4440978.927357] machine2 systemd[1]: Finished Firewall. machine2 # [4440978.927555] machine2 systemd[1]: Reached target Preparation for Network. machine2 # [4440978.928531] machine2 systemd[1]: Starting Address configuration of eth1... machine2 # [4440978.929308] machine2 systemd[1]: Starting Extra networking commands.... machine2 # [4440978.938968] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database. machine2 # [4440978.945563] machine2 network-addresses-eth1-start[172]: adding address 192.168.1.2/24... done machine2 # [4440978.947836] machine2 network-addresses-eth1-start[172]: adding address 2001:db8:1::2/64... done machine2 # [4440978.951860] machine2 systemd[1]: Finished Address configuration of eth1. machine2 # [4440978.990151] machine2 systemd[1]: nscd.service: Deactivated successfully. machine2 # [4440978.990281] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine2 # [4440978.992378] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [4440978.994505] machine2 systemd[1]: Finished Extra networking commands.. machine2 # [4440978.994665] machine2 systemd[1]: Reached target Network. machine2 # [4440979.128987] machine2 nsncd[246]: Jul 30 09:00:05.182 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine2 # [4440979.129092] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). machine2 # [4440979.129144] machine2 systemd[1]: Reached target Host and Network Name Lookups. machine2 # [4440979.129192] machine2 systemd[1]: Reached target User and Group Name Lookups. machine2 # [4440979.131352] machine2 systemd[1]: Starting User Login Management... machine2 # [4440979.132171] machine2 systemd[1]: Starting Permit User Sessions... machine2 # [4440979.174890] machine2 systemd[1]: Finished Permit User Sessions. machine2 # [4440979.175855] machine2 systemd[1]: Started Console Getty. machine2 # [4440979.175899] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine2 # [4440979.175918] machine2 systemd[1]: Reached target Login Prompts. machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: Generating public/private rsa key pair. machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: The key fingerprint is: machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: SHA256:BI3wwlfR9OXs4CuCS5xJtYxfNYLFPCvYabOKxKnOayI root@machine2 machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: The key's randomart image is: machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: +---[RSA 4096]----+ machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | ...oo*o . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | . ..o.o=. + | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | o oo+..o= o | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | o.=*..+ + | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | . .o.S+. . . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | +o =.. . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: | o .*.o . . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: |E.o .... . . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: |.++. . | machine2 # [4440979.407090] machine2 sshd-keygen-start[183]: +----[SHA256]-----+ machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: Generating public/private ed25519 key pair. machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: The key fingerprint is: machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: SHA256:GhxW1fxK7wMkJZmnQhYGkgrnCx+hECc4cI3JTbRuCX0 root@machine2 machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: The key's randomart image is: machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: +--[ED25519 256]--+ machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: |*ooB+...+o.= | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: |=+*.+o oo + = | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: |.*.oo Eo = . | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: |o +o = .. o o . | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: | o o+ o S. + o | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: | o. o o . | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: | . o | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: | o | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: | . | machine2 # [4440979.422936] machine2 sshd-keygen-start[274]: +----[SHA256]-----+ machine2 # [4440979.424088] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. machine2 # [4440979.424260] machine2 systemd[1]: Finished SSH Host Keys Generation. machine2 # [4440979.425752] machine2 systemd[1]: Starting SSH Daemon... machine2 # [4440979.483379] machine2 sshd[276]: Server listening on 0.0.0.0 port 22. machine2 # [4440979.483410] machine2 sshd[276]: Server listening on :: port 22. machine2 # [4440979.483576] machine2 systemd[1]: Started SSH Daemon. machine1 # [4440978.818899] machine1 systemd-journald[57]: Journal started machine1 # [4440978.818995] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/b82ffbe42231413e8fc57deee638afef) is 8M, max 2.5G, 2.4G free. machine1 # [4440978.826266] machine1 systemd[1]: Finished Apply Kernel Variables. machine1 # [4440978.839393] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine1 # [4440978.853447] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... machine1 # [4440978.854128] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... machine1 # [4440978.864217] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/b82ffbe42231413e8fc57deee638afef is 3.498ms for 6 entries. machine1 # [4440978.864217] machine1 systemd-journald[57]: System Journal (/var/log/journal/b82ffbe42231413e8fc57deee638afef) is 8M, max 4G, 3.9G free. machine1 # [4440978.871736] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. machine1 # [4440978.872405] machine1 systemd[1]: Reached target Preparation for Local File Systems. machine1 # [4440978.872524] machine1 systemd[1]: Reached target Local File Systems. machine1 # [4440978.873322] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. machine1 # [4440978.873366] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine1 # [4440978.874142] machine1 systemd[1]: Starting Save Transient machine-id to Disk... machine1 # [4440978.874175] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine1 # [4440978.876839] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. machine1 # [4440978.877624] machine1 systemd[1]: Starting Create System Files and Directories... machine1 # [4440978.892228] machine1 systemd-tmpfiles[114]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine1 # [4440978.892416] machine1 systemd-tmpfiles[114]: fchmod() of /var/log/journal failed: Operation not permitted machine1 # [4440978.892546] machine1 systemd-tmpfiles[114]: fchmod() of /var/log/journal/b82ffbe42231413e8fc57deee638afef failed: Operation not permitted machine1 # [4440978.892754] machine1 systemd-tmpfiles[114]: fchmod() of /run/log/journal failed: Operation not permitted machine1 # [4440978.894736] machine1 systemd[1]: Finished Create System Files and Directories. machine1 # [4440978.895699] machine1 systemd[1]: Starting Rebuild Journal Catalog... machine1 # [4440978.896380] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine1 # [4440978.908642] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine1 # [4440978.913778] machine1 systemd[1]: Finished Rebuild Journal Catalog. machine1 # [4440978.914802] machine1 systemd[1]: Starting Update is Completed... machine1 # [4440978.920384] machine1 systemd[1]: Finished Save Transient machine-id to Disk. machine1 # [4440978.925594] machine1 systemd[1]: Finished Update is Completed. machine1 # [4440978.925766] machine1 systemd[1]: Reached target System Initialization. machine1 # [4440978.925861] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine1 # [4440978.925896] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. machine1 # [4440978.925916] machine1 systemd[1]: Reached target Timer Units. machine1 # [4440978.926031] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. machine1 # [4440978.926154] machine1 systemd[1]: Listening on Nix Daemon Socket. machine1 # [4440978.927333] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine1 # [4440978.928459] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine1 # [4440978.928581] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine1 # [4440978.928607] machine1 systemd[1]: Reached target Socket Units. machine1 # [4440978.928658] machine1 systemd[1]: Reached target Basic System. machine1 # [4440978.929812] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... machine1 # [4440978.930594] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [4440978.931242] machine1 systemd[1]: Starting SSH Host Keys Generation... machine1 # [4440978.944419] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. machine1 # [4440978.953550] machine1 systemd[1]: Finished Firewall. machine1 # [4440978.954049] machine1 systemd[1]: Reached target Preparation for Network. machine1 # [4440978.954938] machine1 systemd[1]: Starting Address configuration of eth1... machine1 # [4440978.955778] machine1 systemd[1]: Starting Extra networking commands.... machine1 # [4440978.969543] machine1 network-addresses-eth1-start[182]: adding address 192.168.1.1/24... done machine1 # [4440978.971498] machine1 network-addresses-eth1-start[182]: adding address 2001:db8:1::1/64... done machine1 # [4440978.975473] machine1 systemd[1]: Finished Address configuration of eth1. machine1 # [4440979.015387] machine1 systemd[1]: nscd.service: Deactivated successfully. machine1 # [4440979.015497] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine1 # [4440979.017399] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [4440979.018924] machine1 systemd[1]: Finished Extra networking commands.. machine1 # [4440979.019055] machine1 systemd[1]: Reached target Network. machine1 # [4440979.145897] machine1 nsncd[246]: Jul 30 09:00:05.198 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine1 # [4440979.145798] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). machine1 # [4440979.145850] machine1 systemd[1]: Reached target Host and Network Name Lookups. machine1 # [4440979.145897] machine1 systemd[1]: Reached target User and Group Name Lookups. machine1 # [4440979.168433] machine1 systemd[1]: Starting User Login Management... machine1 # [4440979.169279] machine1 systemd[1]: Starting Permit User Sessions... machine1 # [4440979.179075] machine1 systemd[1]: Finished Permit User Sessions. machine1 # [4440979.179943] machine1 systemd[1]: Started Console Getty. machine1 # [4440979.179978] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine1 # [4440979.179994] machine1 systemd[1]: Reached target Login Prompts. machine1 # [4440979.814453] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine2 # [4440979.773669] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine1 # [4440980.069648] machine1 systemd-logind[259]: New seat seat0. machine1 # [4440980.070032] machine1 systemd[1]: Started User Login Management. machine1 # [4440980.071175] machine1 systemd[1]: Starting D-Bus System Message Bus... machine1 # [4440980.072062] machine1 systemd[1]: Starting linger-users.service... machine1 # [4440980.083660] machine1 systemd[1]: linger-users.service: Deactivated successfully. machine1 # [4440980.083952] machine1 systemd[1]: Finished linger-users.service. machine2 # [4440980.114731] machine2 systemd-logind[252]: New seat seat0. machine2 # [4440980.114974] machine2 systemd[1]: Started User Login Management. machine2 # [4440980.115935] machine2 systemd[1]: Starting D-Bus System Message Bus... machine2 # [4440980.116657] machine2 systemd[1]: Starting linger-users.service... machine2 # [4440980.126486] machine2 systemd[1]: linger-users.service: Deactivated successfully. machine2 # [4440980.126650] machine2 systemd[1]: Finished linger-users.service. machine2 # [4440980.126831] machine2 systemd[1]: Reached target Multi-User System. machine1 # [4440980.375532] machine1 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'... machine1 # [4440980.377306] machine1 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync' machine1 # [4440980.377306] machine1 dbus-broker-launch[269]: Invalid user-name in /nix/store/7pvvilh1gh0r3z8nn3xh86hs2f6h6gj2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine1 # [4440980.377731] machine1 systemd[1]: Started D-Bus System Message Bus. machine1 # [4440980.384449] machine1 dbus-broker-launch[269]: Ready machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: Generating public/private rsa key pair. machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: The key fingerprint is: machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: SHA256:ZtQKjC+vl44WmWFLqyDM41xomFztQ4Wg2h+aE+ebG6E root@machine1 machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: The key's randomart image is: machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: +---[RSA 4096]----+ machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: | .. . | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: | . + . . | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: | . o + . . | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: |.. .++ o . | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: |*.=oB*. S | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: |=B X*B o | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: |=.E.+.o. | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: | o...*o | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: | .*+. | machine1 # [4440980.484403] machine1 sshd-keygen-start[176]: +----[SHA256]-----+ machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: Generating public/private ed25519 key pair. machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: The key fingerprint is: machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: SHA256:9mqKTfL3TuUqxLbbVvS6RoPO1Yx9478E3ZZ32esK0Kw root@machine1 machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: The key's randomart image is: machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: +--[ED25519 256]--+ machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | | machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | | machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | | machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | o . . =| machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | .S. =.B +*| machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | .+.+o* *o=| machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | . .o Eo=.o.+.| machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | * =++.+ o. | machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: | . ++o*=..o.o+| machine1 # [4440980.498385] machine1 sshd-keygen-start[279]: +----[SHA256]-----+ machine1 # [4440980.499769] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. machine1 # [4440980.499915] machine1 systemd[1]: Finished SSH Host Keys Generation. machine1 # [4440980.501327] machine1 systemd[1]: Starting SSH Daemon... machine1 # [4440980.513641] machine1 sshd[281]: Server listening on 0.0.0.0 port 22. machine1 # [4440980.513674] machine1 sshd[281]: Server listening on :: port 22. machine1 # [4440980.514124] machine1 systemd[1]: Started SSH Daemon. machine1 # [4440980.514374] machine1 systemd[1]: Reached target Multi-User System. machine1 # [4440980.516102] machine1 systemd[1]: Startup finished in 2.159s. machine2 # [4440980.437371] machine2 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'... machine2 # [4440980.438120] machine2 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync' machine2 # [4440980.438120] machine2 dbus-broker-launch[277]: Invalid user-name in /nix/store/7pvvilh1gh0r3z8nn3xh86hs2f6h6gj2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine2 # [4440980.438913] machine2 systemd[1]: Started D-Bus System Message Bus. machine2 # [4440980.445914] machine2 dbus-broker-launch[277]: Ready machine2 # [4440980.446440] machine2 systemd[1]: Startup finished in 2.089s. machine1: (finished: must succeed: systemctl status sshd, in 3.16 seconds) machine2: must succeed: systemctl status sshd machine2: (finished: must succeed: systemctl status sshd, in 0.02 seconds) machine1: waiting for unit sshd machine1: (finished: waiting for unit sshd, in 0.02 seconds) machine2: waiting for unit sshd machine2: (finished: waiting for unit sshd, in 0.02 seconds) machine1: must succeed: ip addr show eth1 machine1: (finished: must succeed: ip addr show eth1, in 0.01 seconds) machine2: must succeed: ip addr show eth1 machine2: (finished: must succeed: ip addr show eth1, in 0.01 seconds) machine1: must succeed: ping -c 1 machine2 machine1: (finished: must succeed: ping -c 1 machine2, in 0.01 seconds) (finished: run the VM test script, in 3.25 seconds) test script finished in 3.30s cleanup kill NspawnMachine (pid 52) kill NspawnMachine (pid 53) Container machine1 terminated by signal KILL. Container machine2 terminated by signal KILL. (finished: cleanup, in 0.38 seconds)