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.0 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(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. 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. 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 machine1 on /build/vm-state-machine1. 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. machine1 # No journal boot entry found for the specified boot (+0). machine2 # No journal boot entry found for the specified boot (+0). machine1 # [1582725.081818] machine1 systemd-journald[57]: Journal started machine1 # [1582725.081868] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/31639cc860c640358e485eed6351b280) is 8M, max 2.5G, 2.4G free. machine1 # [1582725.116245] machine1 systemd[1]: Finished Apply Kernel Variables. machine1 # [1582725.135850] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine1 # [1582725.162203] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... machine1 # [1582725.162999] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... machine1 # [1582725.222861] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/31639cc860c640358e485eed6351b280 is 1.601ms for 6 entries. machine1 # [1582725.222861] machine1 systemd-journald[57]: System Journal (/var/log/journal/31639cc860c640358e485eed6351b280) is 8M, max 4G, 3.9G free. machine1 # [1582725.233007] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. machine1 # [1582725.233760] machine1 systemd[1]: Reached target Preparation for Local File Systems. machine1 # [1582725.233888] machine1 systemd[1]: Reached target Local File Systems. machine1 # [1582725.234726] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. machine1 # [1582725.234776] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine1 # [1582725.235638] machine1 systemd[1]: Starting Save Transient machine-id to Disk... machine1 # [1582725.235696] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine1 # [1582725.259634] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. machine1 # [1582725.261397] machine1 systemd[1]: Starting Create System Files and Directories... machine1 # [1582725.290536] machine1 systemd-tmpfiles[124]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine1 # [1582725.290823] machine1 systemd-tmpfiles[124]: fchmod() of /var/log/journal failed: Operation not permitted machine1 # [1582725.291019] machine1 systemd-tmpfiles[124]: fchmod() of /var/log/journal/31639cc860c640358e485eed6351b280 failed: Operation not permitted machine1 # [1582725.291287] machine1 systemd-tmpfiles[124]: fchmod() of /run/log/journal failed: Operation not permitted machine1 # [1582725.296530] machine1 systemd[1]: Finished Create System Files and Directories. machine1 # [1582725.296981] machine1 systemd[1]: Finished Save Transient machine-id to Disk. machine1 # [1582725.298835] machine1 systemd[1]: Starting Rebuild Journal Catalog... machine1 # [1582725.299565] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine1 # [1582725.311598] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine1 # [1582725.318753] machine1 systemd[1]: Finished Rebuild Journal Catalog. machine1 # [1582725.319813] machine1 systemd[1]: Starting Update is Completed... machine1 # [1582725.330479] machine1 systemd[1]: Finished Update is Completed. machine1 # [1582725.330672] machine1 systemd[1]: Reached target System Initialization. machine1 # [1582725.330765] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine1 # [1582725.330803] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. machine1 # [1582725.330824] machine1 systemd[1]: Reached target Timer Units. machine1 # [1582725.330940] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. machine1 # [1582725.331083] machine1 systemd[1]: Listening on Nix Daemon Socket. machine1 # [1582725.332199] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine1 # [1582725.333086] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine1 # [1582725.333192] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine1 # [1582725.333212] machine1 systemd[1]: Reached target Socket Units. machine1 # [1582725.333254] machine1 systemd[1]: Reached target Basic System. machine1 # [1582725.334332] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... machine1 # [1582725.335209] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [1582725.335919] machine1 systemd[1]: Starting SSH Host Keys Generation... machine1 # [1582725.336537] machine1 systemd[1]: Finished Firewall. machine1 # [1582725.337218] machine1 systemd[1]: Reached target Preparation for Network. machine1 # [1582725.338112] machine1 systemd[1]: Starting Address configuration of eth1... machine1 # [1582725.338911] machine1 systemd[1]: Starting Extra networking commands.... machine1 # [1582725.353320] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. machine1 # [1582725.355657] machine1 network-addresses-eth1-start[172]: adding address 192.168.1.1/24... done machine1 # [1582725.358166] machine1 network-addresses-eth1-start[172]: adding address 2001:db8:1::1/64... done machine1 # [1582725.363154] machine1 systemd[1]: Finished Address configuration of eth1. machine1 # [1582725.400762] machine1 systemd[1]: nscd.service: Deactivated successfully. machine1 # [1582725.400873] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine1 # [1582725.403006] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [1582725.452364] machine1 systemd[1]: Finished Extra networking commands.. machine1 # [1582725.452592] machine1 systemd[1]: Reached target Network. machine1 # [1582725.727282] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). machine1 # [1582725.727866] machine1 nsncd[243]: Sep 22 15:05:45.713 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine1 # [1582725.727348] machine1 systemd[1]: Reached target Host and Network Name Lookups. machine1 # [1582725.727401] machine1 systemd[1]: Reached target User and Group Name Lookups. machine1 # [1582725.733202] machine1 systemd[1]: Starting User Login Management... machine1 # [1582725.733987] machine1 systemd[1]: Starting Permit User Sessions... machine1 # [1582725.744552] machine1 systemd[1]: Finished Permit User Sessions. machine1 # [1582725.745574] machine1 systemd[1]: Started Console Getty. machine1 # [1582725.745630] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine1 # [1582725.745650] machine1 systemd[1]: Reached target Login Prompts. machine1 # [1582726.141735] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine2 # [1582725.081242] machine2 systemd-journald[57]: Journal started machine2 # [1582725.081293] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/dd2e7b83c37e43f593e186f0d01debe0) is 8M, max 2.5G, 2.4G free. machine2 # [1582725.120792] machine2 systemd[1]: Finished Apply Kernel Variables. machine2 # [1582725.136481] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine2 # [1582725.162204] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... machine2 # [1582725.162999] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... machine2 # [1582725.222559] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/dd2e7b83c37e43f593e186f0d01debe0 is 1.676ms for 6 entries. machine2 # [1582725.222559] machine2 systemd-journald[57]: System Journal (/var/log/journal/dd2e7b83c37e43f593e186f0d01debe0) is 8M, max 4G, 3.9G free. machine2 # [1582725.232735] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. machine2 # [1582725.233488] machine2 systemd[1]: Reached target Preparation for Local File Systems. machine2 # [1582725.233600] machine2 systemd[1]: Reached target Local File Systems. machine2 # [1582725.234447] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. machine2 # [1582725.234491] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine2 # [1582725.235383] machine2 systemd[1]: Starting Save Transient machine-id to Disk... machine2 # [1582725.235418] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine2 # [1582725.259612] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. machine2 # [1582725.261609] machine2 systemd[1]: Starting Create System Files and Directories... machine2 # [1582725.283012] machine2 systemd-tmpfiles[125]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine2 # [1582725.283216] machine2 systemd-tmpfiles[125]: fchmod() of /var/log/journal failed: Operation not permitted machine2 # [1582725.283757] machine2 systemd-tmpfiles[125]: fchmod() of /var/log/journal/dd2e7b83c37e43f593e186f0d01debe0 failed: Operation not permitted machine2 # [1582725.284641] machine2 systemd-tmpfiles[125]: fchmod() of /run/log/journal failed: Operation not permitted machine2 # [1582725.286472] machine2 systemd[1]: Finished Create System Files and Directories. machine2 # [1582725.288269] machine2 systemd[1]: Starting Rebuild Journal Catalog... machine2 # [1582725.289214] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine2 # [1582725.297791] machine2 systemd[1]: Finished Save Transient machine-id to Disk. machine2 # [1582725.302285] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine2 # [1582725.309914] machine2 systemd[1]: Finished Rebuild Journal Catalog. machine2 # [1582725.311250] machine2 systemd[1]: Starting Update is Completed... machine2 # [1582725.325077] machine2 systemd[1]: Finished Update is Completed. machine2 # [1582725.325259] machine2 systemd[1]: Reached target System Initialization. machine2 # [1582725.325352] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine2 # [1582725.325396] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. machine2 # [1582725.325416] machine2 systemd[1]: Reached target Timer Units. machine2 # [1582725.325532] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. machine2 # [1582725.325659] machine2 systemd[1]: Listening on Nix Daemon Socket. machine2 # [1582725.326708] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine2 # [1582725.327587] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine2 # [1582725.327689] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine2 # [1582725.327709] machine2 systemd[1]: Reached target Socket Units. machine2 # [1582725.327749] machine2 systemd[1]: Reached target Basic System. machine2 # [1582725.328851] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... machine2 # [1582725.329707] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [1582725.330446] machine2 systemd[1]: Starting SSH Host Keys Generation... machine2 # [1582725.331035] machine2 systemd[1]: Finished Firewall. machine2 # [1582725.331685] machine2 systemd[1]: Reached target Preparation for Network. machine2 # [1582725.332727] machine2 systemd[1]: Starting Address configuration of eth1... machine2 # [1582725.333530] machine2 systemd[1]: Starting Extra networking commands.... machine2 # [1582725.347760] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database. machine2 # [1582725.350299] machine2 network-addresses-eth1-start[172]: adding address 192.168.1.2/24... done machine2 # [1582725.352989] machine2 network-addresses-eth1-start[172]: adding address 2001:db8:1::2/64... done machine2 # [1582725.357212] machine2 systemd[1]: Finished Address configuration of eth1. machine2 # [1582725.398211] machine2 systemd[1]: nscd.service: Deactivated successfully. machine2 # [1582725.398364] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine2 # [1582725.400601] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [1582725.452390] machine2 systemd[1]: Finished Extra networking commands.. machine2 # [1582725.452634] machine2 systemd[1]: Reached target Network. machine2 # [1582725.720491] machine2 nsncd[243]: Sep 22 15:05:45.706 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine2 # [1582725.720918] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). machine2 # [1582725.721042] machine2 systemd[1]: Reached target Host and Network Name Lookups. machine2 # [1582725.721161] machine2 systemd[1]: Reached target User and Group Name Lookups. machine2 # [1582725.733538] machine2 systemd[1]: Starting User Login Management... machine2 # [1582725.734769] machine2 systemd[1]: Starting Permit User Sessions... machine2 # [1582725.744424] machine2 systemd[1]: Finished Permit User Sessions. machine2 # [1582725.745785] machine2 systemd[1]: Started Console Getty. machine2 # [1582725.745837] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine2 # [1582725.745856] machine2 systemd[1]: Reached target Login Prompts. machine2 # [1582726.139479] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: Generating public/private rsa key pair. machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: The key fingerprint is: machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: SHA256:9L2Nlc/55wrt1txtyjIhgULbrO6ugwxOxu/5meYc9DE root@machine2 machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: The key's randomart image is: machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: +---[RSA 4096]----+ machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | | machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | . | machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | . +.. | machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | o.+... . | machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: |. . ES ... o | machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: |.+ . o o . .* o.| machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: |+o.. o . .+.o+=| machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | .o.+.= o+. O| machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: | .o=@o +==+| machine2 # [1582727.016575] machine2 sshd-keygen-start[188]: +----[SHA256]-----+ machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: Generating public/private ed25519 key pair. machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: The key fingerprint is: machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: SHA256:NDkeQIPmKLrDbG7nl+iJgZcdTon66njQdK1BAhG3gQU root@machine2 machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: The key's randomart image is: machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: +--[ED25519 256]--+ machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |EB+ o+ | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |...o+ o . | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: | .* . * | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |. o.+..o + | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |.+..+o S | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |+..=.. | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |=+o + . | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |+B+o.o | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: |BB=+. | machine2 # [1582727.031133] machine2 sshd-keygen-start[274]: +----[SHA256]-----+ machine2 # [1582727.032918] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. machine2 # [1582727.033237] machine2 systemd[1]: Finished SSH Host Keys Generation. machine2 # [1582727.033355] machine2 systemd[1]: sshd-keygen.service: Consumed 3.377s CPU time over 1.702s wall clock time. machine2 # [1582727.035902] machine2 systemd[1]: Starting SSH Daemon... machine2 # [1582727.092265] machine2 sshd[276]: Server listening on 0.0.0.0 port 22. machine2 # [1582727.092310] machine2 sshd[276]: Server listening on :: port 22. machine2 # [1582727.092497] machine2 systemd[1]: Started SSH Daemon. machine2 # [1582727.782259] machine2 systemd-logind[259]: New seat seat0. machine2 # [1582727.813040] machine2 systemd[1]: Starting D-Bus System Message Bus... machine2 # [1582727.813166] machine2 systemd[1]: Started User Login Management. machine2 # [1582727.814292] machine2 systemd[1]: Starting linger-users.service... machine2 # [1582727.826042] machine2 systemd[1]: linger-users.service: Deactivated successfully. machine2 # [1582727.826203] machine2 systemd[1]: Finished linger-users.service. machine2 # [1582727.826426] machine2 systemd[1]: Reached target Multi-User System. machine1 # [1582727.817006] machine1 systemd-logind[259]: New seat seat0. machine1 # [1582727.817840] machine1 systemd[1]: Started User Login Management. machine1 # [1582727.819470] machine1 systemd[1]: Starting D-Bus System Message Bus... machine1 # [1582727.820792] machine1 systemd[1]: Starting linger-users.service... machine1 # [1582727.831874] machine1 systemd[1]: linger-users.service: Deactivated successfully. machine1 # [1582727.831930] machine1 systemd[1]: Finished linger-users.service. machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: Generating public/private rsa key pair. machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: The key fingerprint is: machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: SHA256:N5x19jZ9EcUokRiT3/IyuwnZ4EGZzKNQNZN8IoFMCQY root@machine1 machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: The key's randomart image is: machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: +---[RSA 4096]----+ machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | E.o+.oo+*=.o +o| machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | . +..o**+ . o| machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | . .B+.oo. | machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | . + +oo..o| machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | S B o .=| machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | o *o ..o| machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | + .+ | machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | ... | machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: | o. | machine1 # [1582728.594210] machine1 sshd-keygen-start[187]: +----[SHA256]-----+ machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: Generating public/private ed25519 key pair. machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: The key fingerprint is: machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: SHA256:v4ZXYLetl0gSIueZno5WBg7snlsOWaKzNphA79oTXgw root@machine1 machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: The key's randomart image is: machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: +--[ED25519 256]--+ machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: | | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: | | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: | . | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: | . Eo o o + . | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: |. ..+o.=S= + o | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: |. +.*. *.. + . | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: |.o=.=..+ o.+ o . | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: |o +*o+..+ o.o o | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: | oooooo..o. . | machine1 # [1582728.609778] machine1 sshd-keygen-start[278]: +----[SHA256]-----+ machine1 # [1582728.611807] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. machine1 # [1582728.611963] machine1 systemd[1]: Finished SSH Host Keys Generation. machine1 # [1582728.613672] machine1 systemd[1]: Starting SSH Daemon... machine1 # [1582728.627328] machine1 sshd[280]: Server listening on 0.0.0.0 port 22. machine1 # [1582728.627353] machine1 sshd[280]: Server listening on :: port 22. machine1 # [1582728.627519] machine1 systemd[1]: Started SSH Daemon. machine1 # [1582728.627573] machine1 systemd[1]: Reached target Multi-User System. machine1 # [1582728.801221] machine1 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'... machine1 # [1582728.801897] machine1 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync' machine1 # [1582728.801897] machine1 dbus-broker-launch[269]: Invalid user-name in /nix/store/q2j5z2r3vaff2mz4g5abjanb05bzg12a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine1 # [1582728.804493] machine1 systemd[1]: Started D-Bus System Message Bus. machine1 # [1582728.804866] machine1 systemd[1]: Startup finished in 4.141s. machine1 # [1582728.811360] machine1 dbus-broker-launch[269]: Ready machine1: (finished: must succeed: systemctl status sshd, in 4.69 seconds) machine2: must succeed: systemctl status sshd machine2: (finished: must succeed: systemctl status sshd, in 0.01 seconds) machine1: waiting for unit sshd machine1: (finished: waiting for unit sshd, in 0.01 seconds) machine2: waiting for unit sshd machine2: (finished: waiting for unit sshd, in 0.01 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 4.76 seconds) test script finished in 4.84s cleanup kill NspawnMachine (pid 52) machine2 # [1582728.809381] machine2 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'... machine2 # [1582728.810337] machine2 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync' machine2 # [1582728.810337] machine2 dbus-broker-launch[277]: Invalid user-name in /nix/store/q2j5z2r3vaff2mz4g5abjanb05bzg12a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync" machine2 # [1582728.810777] machine2 systemd[1]: Started D-Bus System Message Bus. machine2 # [1582728.811089] machine2 systemd[1]: Startup finished in 4.147s. machine2 # [1582728.820131] machine2 dbus-broker-launch[277]: Ready kill NspawnMachine (pid 53) Container machine1 terminated by signal KILL. Container machine2 terminated by signal KILL. (finished: cleanup, in 0.43 seconds)