build01: building lww0am9gx2vn1vy41dsmqn5lpm9279ld-container-test-run-container.drv 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) machine2: systemd-nspawn running (pid 53) machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal... 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 # [1874276.202526] machine1 systemd-journald[57]: Journal started machine2 # [1874276.194548] machine2 systemd-journald[57]: Journal started machine1 # [1874276.202581] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/51268db163cd4604833108c2934c98cd) is 8M, max 2.5G, 2.4G free. machine2 # [1874276.194605] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/c5a15fb9d5db49899fdf8acd57f1e5f3) is 8M, max 2.5G, 2.4G free. machine1 # [1874276.215034] machine1 systemd[1]: Finished Apply Kernel Variables. machine2 # [1874276.197080] machine2 systemd[1]: Finished Apply Kernel Variables. machine1 # [1874276.232380] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine2 # [1874276.208264] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully. machine1 # [1874276.248204] machine1 systemd[1]: Starting Flush Journal to Persistent Storage... machine2 # [1874276.220935] machine2 systemd[1]: Starting Flush Journal to Persistent Storage... machine1 # [1874276.249182] machine1 systemd[1]: Starting Create Static Device Nodes in /dev... machine2 # [1874276.221752] machine2 systemd[1]: Starting Create Static Device Nodes in /dev... machine1 # [1874276.259920] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/51268db163cd4604833108c2934c98cd is 1.590ms for 6 entries. machine2 # [1874276.232887] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/c5a15fb9d5db49899fdf8acd57f1e5f3 is 2.358ms for 6 entries. machine1 # [1874276.259920] machine1 systemd-journald[57]: System Journal (/var/log/journal/51268db163cd4604833108c2934c98cd) is 8M, max 4G, 3.9G free. machine2 # [1874276.232887] machine2 systemd-journald[57]: System Journal (/var/log/journal/c5a15fb9d5db49899fdf8acd57f1e5f3) is 8M, max 4G, 3.9G free. machine1 # [1874276.266744] machine1 systemd[1]: Finished Create Static Device Nodes in /dev. machine2 # [1874276.238370] machine2 systemd[1]: Finished Create Static Device Nodes in /dev. machine1 # [1874276.267440] machine1 systemd[1]: Reached target Preparation for Local File Systems. machine2 # [1874276.238977] machine2 systemd[1]: Reached target Preparation for Local File Systems. machine1 # [1874276.267572] machine1 systemd[1]: Reached target Local File Systems. machine2 # [1874276.239101] machine2 systemd[1]: Reached target Local File Systems. machine1 # [1874276.268425] machine1 systemd[1]: Listening on Boot Loader Control Service Socket. machine2 # [1874276.239886] machine2 systemd[1]: Listening on Boot Loader Control Service Socket. machine1 # [1874276.268476] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine2 # [1874276.239930] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container machine1 # [1874276.269438] machine1 systemd[1]: Starting Save Transient machine-id to Disk... machine2 # [1874276.240730] machine2 systemd[1]: Starting Save Transient machine-id to Disk... machine1 # [1874276.269477] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine2 # [1874276.240764] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys machine1 # [1874276.269940] machine1 systemd[1]: Finished Flush Journal to Persistent Storage. machine2 # [1874276.259289] machine2 systemd[1]: Finished Flush Journal to Persistent Storage. machine1 # [1874276.271456] machine1 systemd[1]: Starting Create System Files and Directories... machine2 # [1874276.260895] machine2 systemd[1]: Starting Create System Files and Directories... machine1 # [1874276.286274] machine1 systemd-tmpfiles[111]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine2 # [1874276.280253] machine2 systemd-tmpfiles[110]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted machine1 # [1874276.286450] machine1 systemd-tmpfiles[111]: fchmod() of /var/log/journal failed: Operation not permitted machine2 # [1874276.280478] machine2 systemd-tmpfiles[110]: fchmod() of /var/log/journal failed: Operation not permitted machine1 # [1874276.286573] machine1 systemd-tmpfiles[111]: fchmod() of /var/log/journal/51268db163cd4604833108c2934c98cd failed: Operation not permitted machine2 # [1874276.280628] machine2 systemd-tmpfiles[110]: fchmod() of /var/log/journal/c5a15fb9d5db49899fdf8acd57f1e5f3 failed: Operation not permitted machine1 # [1874276.286759] machine1 systemd-tmpfiles[111]: fchmod() of /run/log/journal failed: Operation not permitted machine2 # [1874276.280853] machine2 systemd-tmpfiles[110]: fchmod() of /run/log/journal failed: Operation not permitted machine1 # [1874276.288811] machine1 systemd[1]: Finished Create System Files and Directories. machine2 # [1874276.282233] machine2 systemd[1]: Finished Create System Files and Directories. machine1 # [1874276.289828] machine1 systemd[1]: Starting Rebuild Journal Catalog... machine2 # [1874276.283391] machine2 systemd[1]: Starting Rebuild Journal Catalog... machine1 # [1874276.290565] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine2 # [1874276.284231] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP... machine1 # [1874276.304460] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine2 # [1874276.299117] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP. machine1 # [1874276.314109] machine1 systemd[1]: Finished Rebuild Journal Catalog. machine2 # [1874276.308188] machine2 systemd[1]: Finished Rebuild Journal Catalog. machine1 # [1874276.315051] machine1 systemd[1]: Starting Update is Completed... machine2 # [1874276.309565] machine2 systemd[1]: Starting Update is Completed... machine1 # [1874276.325790] machine1 systemd[1]: Finished Update is Completed. machine2 # [1874276.322394] machine2 systemd[1]: Finished Update is Completed. machine1 # [1874276.325955] machine1 systemd[1]: Reached target System Initialization. machine2 # [1874276.322593] machine2 systemd[1]: Reached target System Initialization. machine1 # [1874276.326039] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine2 # [1874276.322688] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container machine1 # [1874276.326077] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories. machine2 # [1874276.322729] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories. machine1 # [1874276.326094] machine1 systemd[1]: Reached target Timer Units. machine2 # [1874276.322753] machine2 systemd[1]: Reached target Timer Units. machine1 # [1874276.326206] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket. machine2 # [1874276.322889] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket. machine1 # [1874276.326329] machine1 systemd[1]: Listening on Nix Daemon Socket. machine2 # [1874276.323048] machine2 systemd[1]: Listening on Nix Daemon Socket. machine1 # [1874276.327372] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine2 # [1874276.324462] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export). machine1 # [1874276.328285] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine2 # [1874276.325710] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). machine1 # [1874276.328468] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine2 # [1874276.325837] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket. machine1 # [1874276.328491] machine1 systemd[1]: Reached target Socket Units. machine2 # [1874276.325863] machine2 systemd[1]: Reached target Socket Units. machine1 # [1874276.328533] machine1 systemd[1]: Reached target Basic System. machine2 # [1874276.325910] machine2 systemd[1]: Reached target Basic System. machine1 # [1874276.329494] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database... machine2 # [1874276.327090] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database... machine1 # [1874276.330271] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [1874276.328057] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [1874276.330968] machine1 systemd[1]: Starting SSH Host Keys Generation... machine2 # [1874276.328905] machine2 systemd[1]: Starting SSH Host Keys Generation... machine2 # [1874276.348158] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database. machine2 # [1874276.368171] machine2 systemd[1]: Finished Firewall. machine2 # [1874276.368365] machine2 systemd[1]: Reached target Preparation for Network. machine2 # [1874276.369318] machine2 systemd[1]: Starting Address configuration of eth1... machine2 # [1874276.370198] machine2 systemd[1]: Starting Extra networking commands.... machine2 # [1874276.391126] machine2 network-addresses-eth1-start[181]: adding address 192.168.1.2/24... done machine2 # [1874276.393966] machine2 network-addresses-eth1-start[181]: adding address 2001:db8:1::2/64... done machine2 # [1874276.398759] machine2 systemd[1]: Finished Address configuration of eth1. machine2 # [1874276.441963] machine2 systemd[1]: nscd.service: Deactivated successfully. machine2 # [1874276.442145] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine2 # [1874276.444953] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine2 # [1874276.452280] machine2 systemd[1]: Finished Extra networking commands.. machine2 # [1874276.452458] machine2 systemd[1]: Reached target Network. machine1 # [1874276.356087] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database. machine1 # [1874276.373825] machine1 systemd[1]: Finished Firewall. machine1 # [1874276.374037] machine1 systemd[1]: Reached target Preparation for Network. machine1 # [1874276.375109] machine1 systemd[1]: Starting Address configuration of eth1... machine1 # [1874276.376215] machine1 systemd[1]: Starting Extra networking commands.... machine1 # [1874276.395859] machine1 network-addresses-eth1-start[181]: adding address 192.168.1.1/24... done machine1 # [1874276.399186] machine1 network-addresses-eth1-start[181]: adding address 2001:db8:1::1/64... done machine1 # [1874276.403951] machine1 systemd[1]: Finished Address configuration of eth1. machine1 # [1874276.448368] machine1 systemd[1]: nscd.service: Deactivated successfully. machine1 # [1874276.448511] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd). machine1 # [1874276.451054] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)... machine1 # [1874276.458506] machine1 systemd[1]: Finished Extra networking commands.. machine1 # [1874276.458795] machine1 systemd[1]: Reached target Network. machine1 # [1874276.576316] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd). machine1 # [1874276.576758] machine1 nsncd[241]: Sep 26 00:04:56.562 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine1 # [1874276.576444] machine1 systemd[1]: Reached target Host and Network Name Lookups. machine1 # [1874276.576554] machine1 systemd[1]: Reached target User and Group Name Lookups. machine1 # [1874276.578753] machine1 systemd[1]: Starting User Login Management... machine1 # [1874276.580018] machine1 systemd[1]: Starting Permit User Sessions... machine1 # [1874276.617414] machine1 systemd[1]: Finished Permit User Sessions. machine1 # [1874276.618998] machine1 systemd[1]: Started Console Getty. machine1 # [1874276.619073] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine1 # [1874276.619116] machine1 systemd[1]: Reached target Login Prompts. machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: Generating public/private rsa key pair. machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: The key fingerprint is: machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: SHA256:uJ7AMi+8PIIZOAo9etbaBvY3vQz78HlDi9EpBTrdv/g root@machine1 machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: The key's randomart image is: machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: +---[RSA 4096]----+ machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: | | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: | . | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: | o o | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: | o.. o | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: |.. ..So o | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: |= =. .o + . | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: |+Bo=o +. = o . | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: |*.*=+ooBo.= . | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: | ++*o.+o*o oE | machine1 # [1874276.701445] machine1 sshd-keygen-start[167]: +----[SHA256]-----+ machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: Generating public/private ed25519 key pair. machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: The key fingerprint is: machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: SHA256:dwiADnQWyaxZrqwRDnqLmEy5MghnvmHJQ1rpSPLzzKc root@machine1 machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: The key's randomart image is: machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: +--[ED25519 256]--+ machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: | ..o++. | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: | .o* . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: | B . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |o o.o . . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |=+=. S o . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |*%*. . . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |OX@. | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |O+oB . | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: |....Eo | machine1 # [1874276.719564] machine1 sshd-keygen-start[273]: +----[SHA256]-----+ machine1 # [1874276.721323] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully. machine1 # [1874276.721500] machine1 systemd[1]: Finished SSH Host Keys Generation. machine1 # [1874276.722595] machine1 systemd[1]: Starting SSH Daemon... machine1 # [1874276.780957] machine1 sshd[275]: Server listening on 0.0.0.0 port 22. machine1 # [1874276.781009] machine1 sshd[275]: Server listening on :: port 22. machine1 # [1874276.781430] machine1 systemd[1]: Started SSH Daemon. machine2 # [1874276.576257] machine2 nsncd[241]: Sep 26 00:04:56.561 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket" machine2 # [1874276.576296] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd). machine2 # [1874276.576401] machine2 systemd[1]: Reached target Host and Network Name Lookups. machine2 # [1874276.576511] machine2 systemd[1]: Reached target User and Group Name Lookups. machine2 # [1874276.578573] machine2 systemd[1]: Starting User Login Management... machine2 # [1874276.579909] machine2 systemd[1]: Starting Permit User Sessions... machine2 # [1874276.618006] machine2 systemd[1]: Finished Permit User Sessions. machine2 # [1874276.619612] machine2 systemd[1]: Started Console Getty. machine2 # [1874276.619688] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0 machine2 # [1874276.619726] machine2 systemd[1]: Reached target Login Prompts. machine1 # [1874277.227021] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine1 # [1874277.228066] machine1 systemd[1]: Finished Save Transient machine-id to Disk. machine1 # [1874277.349457] machine1 systemd-logind[258]: New seat seat0. machine1 # [1874277.349752] machine1 systemd[1]: Started User Login Management. machine1 # [1874277.351358] machine1 systemd[1]: Starting D-Bus System Message Bus... machine1 # [1874277.352808] machine1 systemd[1]: Starting linger-users.service... machine1 # [1874277.398493] machine1 systemd[1]: linger-users.service: Deactivated successfully. machine1 # [1874277.398757] machine1 systemd[1]: Finished linger-users.service. machine1 # [1874277.399174] machine1 systemd[1]: Reached target Multi-User System. machine2 # [1874277.219792] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. machine2 # [1874277.220877] machine2 systemd[1]: Finished Save Transient machine-id to Disk. machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: Generating public/private rsa key pair. machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: The key fingerprint is: machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: SHA256:DKakXQ4r5MeFIqCeRhsVnBYYvaMOA++iwuF6Tk9lXFE root@machine2 machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: The key's randomart image is: machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: +---[RSA 4096]----+ machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |..=++ ..E | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |o..= . . | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |oo+ = = . | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |+++O X + | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |.*= B = S | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |+o.o o | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |=oo . | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |o=oo | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: |*+. . | machine2 # [1874277.222433] machine2 sshd-keygen-start[171]: +----[SHA256]-----+ machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: Generating public/private ed25519 key pair. machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: The key fingerprint is: machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: SHA256:nmenXVnBnXuAj6pRb6c/YFWT1Im1PM8O2UfrzAnA+u0 root@machine2 machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: The key's randomart image is: machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: +--[ED25519 256]--+ machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | +o+| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | . oo+*| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | o. .O+| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | . .o.**| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | S.. .o+oB| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | . o.o+ ==+| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | + +o+o+=.| machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | = =.+. | machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: | . . oE.. | machine2 # [1874277.239107] machine2 sshd-keygen-start[274]: +----[SHA256]-----+ machine2 # [1874277.240895] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully. machine2 # [1874277.241050] machine2 systemd[1]: Finished SSH Host Keys Generation. machine2 # [1874277.241091] machine2 systemd[1]: sshd-keygen.service: Consumed 1.295s CPU time over 912ms wall clock time. machine2 # [1874277.242088] machine2 systemd[1]: Starting SSH Daemon... machine2 # [1874277.292978] machine2 sshd[276]: Server listening on 0.0.0.0 port 22. machine2 # [1874277.293018] machine2 sshd[276]: Server listening on :: port 22. machine2 # [1874277.293334] machine2 systemd[1]: Started SSH Daemon. machine2 # [1874277.367708] machine2 systemd-logind[258]: New seat seat0. machine2 # [1874277.368072] machine2 systemd[1]: Started User Login Management. machine2 # [1874277.388588] machine2 systemd[1]: Starting D-Bus System Message Bus... machine2 # [1874277.389806] machine2 systemd[1]: Starting linger-users.service... machine2 # [1874277.402712] machine2 systemd[1]: linger-users.service: Deactivated successfully. machine2 # [1874277.402845] machine2 systemd[1]: Finished linger-users.service. machine2 # [1874277.403173] machine2 systemd[1]: Reached target Multi-User System. machine1 # [1874277.659005] machine1 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'... machine2 # [1874277.636542] machine2 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'... machine1 # [1874277.659961] machine1 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync' machine2 # [1874277.637939] machine2 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync' machine1 # [1874277.659961] machine1 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 # [1874277.637939] 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" machine1 # [1874277.660477] machine1 systemd[1]: Started D-Bus System Message Bus. machine2 # [1874277.638731] machine2 systemd[1]: Started D-Bus System Message Bus. machine1 # [1874277.660973] machine1 systemd[1]: Startup finished in 1.853s. machine2 # [1874277.639284] machine2 systemd[1]: Startup finished in 1.825s. machine1 # [1874277.668562] machine1 dbus-broker-launch[277]: Ready machine2 # [1874277.647582] machine2 dbus-broker-launch[277]: Ready machine1: (finished: must succeed: systemctl status sshd, in 2.65 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.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 2.73 seconds) test script finished in 2.77s cleanup kill NspawnMachine (pid 52) kill NspawnMachine (pid 53) Container machine1 terminated by signal KILL. (finished: cleanup, in 0.33 seconds) Container machine2 terminated by signal KILL.