container-test-run-container
checks.aarch64-linux.nixos-test-container
· build #748
· raw
1Machine state will be reset. To keep it, pass --keep-machine-state2start all VLans3(finished: start all VLans, in 0.00 seconds)45Test will time out and terminate in 3600.0 seconds6run the VM test script7additionally exposed symbols:8 machine1, machine2,9 vlan1,10 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_ssh11start all VMs12machine1: systemd-nspawn running (pid 52)13machine2: systemd-nspawn running (pid 53)14machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal...15machine2: Waiting for journal at /build/vm-state-machine2/var/log/journal...16(finished: start all VMs, in 0.00 seconds)17machine1: must succeed: systemctl status sshd18nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE19nixos-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.20nixos-nspawn(machine1): TAP vde-tap1 not found; container will be isolated from VDE21nixos-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.22Note: 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.23Note: 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.24░ Spawning container machine2 on /build/vm-state-machine2.25░ Spawning container machine1 on /build/vm-state-machine1.26machine2 # No journal files were found.27machine1 # No journal files were found.28machine2 # No journal boot entry found for the specified boot (+0).29machine1 # No journal boot entry found for the specified boot (+0).30machine2 # [1547182.759141] machine2 systemd-journald[57]: Journal started31machine1 # [1547182.758762] machine1 systemd-journald[57]: Journal started32machine2 # [1547182.759192] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/dc5e12e70e384cada477b55fd17f8708) is 8M, max 2.5G, 2.4G free.33machine1 # [1547182.758819] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/ee25fa4b1c1d48258653d023cbb1b798) is 8M, max 2.5G, 2.4G free.34machine2 # [1547182.797367] machine2 systemd[1]: Finished Apply Kernel Variables.35machine1 # [1547182.800024] machine1 systemd[1]: Finished Apply Kernel Variables.36machine2 # [1547182.813037] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.37machine2 # [1547182.826625] machine2 systemd[1]: Starting Flush Journal to Persistent Storage...38machine2 # [1547182.827332] machine2 systemd[1]: Starting Create Static Device Nodes in /dev...39machine2 # [1547182.882691] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/dc5e12e70e384cada477b55fd17f8708 is 1.605ms for 6 entries.40machine2 # [1547182.882691] machine2 systemd-journald[57]: System Journal (/var/log/journal/dc5e12e70e384cada477b55fd17f8708) is 8M, max 4G, 3.9G free.41machine2 # [1547182.892050] machine2 systemd[1]: Finished Create Static Device Nodes in /dev.42machine2 # [1547182.892820] machine2 systemd[1]: Reached target Preparation for Local File Systems.43machine2 # [1547182.892954] machine2 systemd[1]: Reached target Local File Systems.44machine2 # [1547182.893834] machine2 systemd[1]: Listening on Boot Loader Control Service Socket.45machine2 # [1547182.893885] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container46machine2 # [1547182.894764] machine2 systemd[1]: Starting Save Transient machine-id to Disk...47machine2 # [1547182.894802] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys48machine2 # [1547182.900673] machine2 systemd[1]: Finished Firewall.49machine2 # [1547182.900832] machine2 systemd[1]: Reached target Preparation for Network.50machine2 # [1547182.989497] machine2 systemd[1]: Finished Flush Journal to Persistent Storage.51machine2 # [1547182.991133] machine2 systemd[1]: Starting Create System Files and Directories...52machine2 # [1547183.053451] machine2 systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted53machine2 # [1547183.053649] machine2 systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted54machine2 # [1547183.053782] machine2 systemd-tmpfiles[160]: fchmod() of /var/log/journal/dc5e12e70e384cada477b55fd17f8708 failed: Operation not permitted55machine2 # [1547183.053977] machine2 systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted56machine2 # [1547183.055669] machine2 systemd[1]: Finished Create System Files and Directories.57machine2 # [1547183.056892] machine2 systemd[1]: Starting Rebuild Journal Catalog...58machine2 # [1547183.057692] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...59machine2 # [1547183.069969] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.60machine2 # [1547183.079330] machine2 systemd[1]: Finished Rebuild Journal Catalog.61machine2 # [1547183.080392] machine2 systemd[1]: Starting Update is Completed...62machine2 # [1547183.090726] machine2 systemd[1]: Finished Update is Completed.63machine2 # [1547183.090905] machine2 systemd[1]: Reached target System Initialization.64machine2 # [1547183.090986] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container65machine2 # [1547183.091025] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories.66machine2 # [1547183.091052] machine2 systemd[1]: Reached target Timer Units.67machine2 # [1547183.091161] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket.68machine2 # [1547183.091293] machine2 systemd[1]: Listening on Nix Daemon Socket.69machine2 # [1547183.092367] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).70machine2 # [1547183.093268] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).71machine2 # [1547183.093378] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.72machine2 # [1547183.093401] machine2 systemd[1]: Reached target Socket Units.73machine2 # [1547183.093442] machine2 systemd[1]: Reached target Basic System.74machine2 # [1547183.096696] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database...75machine2 # [1547183.097688] machine2 systemd[1]: Starting Address configuration of eth1...76machine2 # [1547183.098917] machine2 systemd[1]: Starting Extra networking commands....77machine2 # [1547183.100239] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...78machine2 # [1547183.101883] machine2 systemd[1]: Starting SSH Host Keys Generation...79machine2 # [1547183.115222] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database.80machine2 # [1547183.115456] machine2 network-addresses-eth1-start[169]: adding address 192.168.1.2/24... done81machine1 # [1547182.815329] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.82machine2 # [1547183.118351] machine2 network-addresses-eth1-start[169]: adding address 2001:db8:1::2/64... done83machine1 # [1547182.829233] machine1 systemd[1]: Starting Flush Journal to Persistent Storage...84machine2 # [1547183.122484] machine2 systemd[1]: Finished Address configuration of eth1.85machine1 # [1547182.829902] machine1 systemd[1]: Starting Create Static Device Nodes in /dev...86machine2 # [1547183.167942] machine2 systemd[1]: nscd.service: Deactivated successfully.87machine1 # [1547182.885030] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/ee25fa4b1c1d48258653d023cbb1b798 is 1.399ms for 6 entries.88machine2 # [1547183.168124] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd).89machine1 # [1547182.885030] machine1 systemd-journald[57]: System Journal (/var/log/journal/ee25fa4b1c1d48258653d023cbb1b798) is 8M, max 4G, 3.9G free.90machine2 # [1547183.170695] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...91machine1 # [1547182.889955] machine1 systemd[1]: Finished Create Static Device Nodes in /dev.92machine2 # [1547183.173445] machine2 systemd[1]: Finished Extra networking commands..93machine1 # [1547182.890651] machine1 systemd[1]: Reached target Preparation for Local File Systems.94machine2 # [1547183.173637] machine2 systemd[1]: Reached target Network.95machine1 # [1547182.890777] machine1 systemd[1]: Reached target Local File Systems.96machine2 # [1547183.354204] machine2 nsncd[245]: Sep 22 05:13:23.339 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"97machine1 # [1547182.891607] machine1 systemd[1]: Listening on Boot Loader Control Service Socket.98machine2 # [1547183.354298] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).99machine1 # [1547182.891658] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container100machine2 # [1547183.354360] machine2 systemd[1]: Reached target Host and Network Name Lookups.101machine1 # [1547182.892547] machine1 systemd[1]: Starting Save Transient machine-id to Disk...102machine2 # [1547183.354413] machine2 systemd[1]: Reached target User and Group Name Lookups.103machine1 # [1547182.892584] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys104machine2 # [1547183.355592] machine2 systemd[1]: Starting User Login Management...105machine1 # [1547182.899399] machine1 systemd[1]: Finished Firewall.106machine2 # [1547183.356479] machine2 systemd[1]: Starting Permit User Sessions...107machine1 # [1547182.899613] machine1 systemd[1]: Reached target Preparation for Network.108machine2 # [1547183.396245] machine2 systemd[1]: Finished Permit User Sessions.109machine1 # [1547182.991799] machine1 systemd[1]: Finished Flush Journal to Persistent Storage.110machine2 # [1547183.397317] machine2 systemd[1]: Started Console Getty.111machine1 # [1547182.992800] machine1 systemd[1]: Starting Create System Files and Directories...112machine2 # [1547183.397353] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0113machine1 # [1547183.053451] machine1 systemd-tmpfiles[160]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted114machine2 # [1547183.397373] machine2 systemd[1]: Reached target Login Prompts.115machine1 # [1547183.053649] machine1 systemd-tmpfiles[160]: fchmod() of /var/log/journal failed: Operation not permitted116machine1 # [1547183.053780] machine1 systemd-tmpfiles[160]: fchmod() of /var/log/journal/ee25fa4b1c1d48258653d023cbb1b798 failed: Operation not permitted117machine1 # [1547183.053979] machine1 systemd-tmpfiles[160]: fchmod() of /run/log/journal failed: Operation not permitted118machine1 # [1547183.055652] machine1 systemd[1]: Finished Create System Files and Directories.119machine1 # [1547183.056868] machine1 systemd[1]: Starting Rebuild Journal Catalog...120machine1 # [1547183.057638] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...121machine1 # [1547183.069965] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.122machine1 # [1547183.077077] machine1 systemd[1]: Finished Rebuild Journal Catalog.123machine1 # [1547183.078172] machine1 systemd[1]: Starting Update is Completed...124machine1 # [1547183.088349] machine1 systemd[1]: Finished Update is Completed.125machine1 # [1547183.088942] machine1 systemd[1]: Reached target System Initialization.126machine1 # [1547183.089115] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container127machine1 # [1547183.089160] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories.128machine1 # [1547183.089182] machine1 systemd[1]: Reached target Timer Units.129machine1 # [1547183.089312] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket.130machine1 # [1547183.089451] machine1 systemd[1]: Listening on Nix Daemon Socket.131machine1 # [1547183.090568] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).132machine1 # [1547183.091496] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).133machine1 # [1547183.091596] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.134machine1 # [1547183.091617] machine1 systemd[1]: Reached target Socket Units.135machine1 # [1547183.091658] machine1 systemd[1]: Reached target Basic System.136machine1 # [1547183.092856] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database...137machine1 # [1547183.093676] machine1 systemd[1]: Starting Address configuration of eth1...138machine1 # [1547183.094516] machine1 systemd[1]: Starting Extra networking commands....139machine1 # [1547183.095324] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...140machine1 # [1547183.096223] machine1 systemd[1]: Starting SSH Host Keys Generation...141machine1 # [1547183.111504] machine1 network-addresses-eth1-start[169]: adding address 192.168.1.1/24... done142machine1 # [1547183.113655] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database.143machine1 # [1547183.114479] machine1 network-addresses-eth1-start[169]: adding address 2001:db8:1::1/64... done144machine1 # [1547183.118716] machine1 systemd[1]: Finished Address configuration of eth1.145machine1 # [1547183.165809] machine1 systemd[1]: nscd.service: Deactivated successfully.146machine1 # [1547183.166005] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd).147machine1 # [1547183.168158] machine1 systemd[1]: nscd.service: Failed to spawn executor: No such file or directory148machine1 # [1547183.168189] machine1 systemd[1]: nscd.service: Failed to spawn 'start' task: No such file or directory149machine1 # [1547183.168218] machine1 systemd[1]: nscd.service: Failed with result 'resources'.150machine1 # [1547183.168318] machine1 systemd[1]: Failed to start Name Service Cache Daemon (nsncd).151machine1 # [1547183.168331] machine1 systemd[1]: Dependency failed for Host and Network Name Lookups.152machine1 # [1547183.168339] machine1 systemd[1]: nss-lookup.target: Job nss-lookup.target/start failed with result 'dependency'.153machine1 # [1547183.168349] machine1 systemd[1]: Dependency failed for User and Group Name Lookups.154machine1 # [1547183.168363] machine1 systemd[1]: nss-user-lookup.target: Job nss-user-lookup.target/start failed with result 'dependency'.155machine1 # [1547183.170468] machine1 systemd[1]: nscd.service: Scheduled restart job immediately on client request, restart counter is at 1.156machine1 # [1547183.171541] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...157machine1 # [1547183.171819] machine1 systemd[1]: Finished Extra networking commands..158machine1 # [1547183.172783] machine1 systemd[1]: Reached target Network.159machine1 # [1547183.354261] machine1 nsncd[247]: Sep 22 05:13:23.340 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"160machine1 # [1547183.354428] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).161machine1 # [1547183.354486] machine1 systemd[1]: Reached target Host and Network Name Lookups.162machine1 # [1547183.354538] machine1 systemd[1]: Reached target User and Group Name Lookups.163machine1 # [1547183.355604] machine1 systemd[1]: Starting User Login Management...164machine1 # [1547183.356402] machine1 systemd[1]: Starting Permit User Sessions...165machine1 # [1547183.397067] machine1 systemd[1]: Finished Permit User Sessions.166machine1 # [1547183.398015] machine1 systemd[1]: Started Console Getty.167machine1 # [1547183.398049] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0168machine1 # [1547183.398069] machine1 systemd[1]: Reached target Login Prompts.169machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: Generating public/private rsa key pair.170machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key171machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub172machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: The key fingerprint is:173machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: SHA256:vAtp9gjf16kJoWxMsKwJtbob/IZha9J4AVsNK40zqTQ root@machine2174machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: The key's randomart image is:175machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: +---[RSA 4096]----+176machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: | |177machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: | . |178machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: | +.+. |179machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |BE+o.o . |180machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |+Bo o . S |181machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |+=.o + o o |182machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |++*.. X o . . |183machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |o*+. * = o..o |184machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: |=o.. o +.o. |185machine2 # [1547183.953721] machine2 sshd-keygen-start[194]: +----[SHA256]-----+186machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: Generating public/private ed25519 key pair.187machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key188machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub189machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: The key fingerprint is:190machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: SHA256:PDnP8tuOXgO0U52n7ugnCZXu/0HwTwdWhI+fncuhIJo root@machine2191machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: The key's randomart image is:192machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: +--[ED25519 256]--+193machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | oo|194machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | ..o |195machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | . .o=o.|196machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | . o oo.++.|197machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | S +o .+*|198machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | *.+...==|199machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | + +o+.+.+|200machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | E o ++=.o.|201machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: | .=+=+o..|202machine2 # [1547183.967455] machine2 sshd-keygen-start[273]: +----[SHA256]-----+203machine2 # [1547183.970104] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully.204machine2 # [1547183.970289] machine2 systemd[1]: Finished SSH Host Keys Generation.205machine2 # [1547183.970361] machine2 systemd[1]: sshd-keygen.service: Consumed 1.737s CPU time over 868ms wall clock time.206machine2 # [1547183.971414] machine2 systemd[1]: Starting SSH Daemon...207machine2 # [1547184.026838] machine2 sshd[275]: Server listening on 0.0.0.0 port 22.208machine2 # [1547184.026863] machine2 sshd[275]: Server listening on :: port 22.209machine2 # [1547184.027375] machine2 systemd[1]: Started SSH Daemon.210machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: Generating public/private rsa key pair.211machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key212machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub213machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: The key fingerprint is:214machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: SHA256:pvSM557o/EXzVDJS0qWFatSciEdOY0EUttr64ucXxmE root@machine1215machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: The key's randomart image is:216machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: +---[RSA 4096]----+217machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | =%B.+o |218machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | .=+=*o |219machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | o+.+ . |220machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | oo.E+ |221machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | . S.+o.. |222machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | . * o ++ |223machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | o = .... |224machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | . +.+. . |225machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: | .++*+o. |226machine1 # [1547184.312886] machine1 sshd-keygen-start[192]: +----[SHA256]-----+227machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: Generating public/private ed25519 key pair.228machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key229machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub230machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: The key fingerprint is:231machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: SHA256:gjpdO/NRGCmayaPUsD4JqwfQjPm5QP7ZCG3Z1Enb6Sk root@machine1232machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: The key's randomart image is:233machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: +--[ED25519 256]--+234machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: | |235machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: | . . |236machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: | =. + * . |237machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |+.o= * = = |238machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |=.+.% o S o |239machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |oBoO + E + |240machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |.o@.= + o |241machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |. o* . + . |242machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: |.. . |243machine1 # [1547184.329126] machine1 sshd-keygen-start[275]: +----[SHA256]-----+244machine1 # [1547184.332282] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully.245machine1 # [1547184.332390] machine1 systemd[1]: Finished SSH Host Keys Generation.246machine1 # [1547184.333467] machine1 systemd[1]: Starting SSH Daemon...247machine1 # [1547184.390657] machine1 sshd[277]: Server listening on 0.0.0.0 port 22.248machine1 # [1547184.390684] machine1 sshd[277]: Server listening on :: port 22.249machine1 # [1547184.390869] machine1 systemd[1]: Started SSH Daemon.250machine2 # [1547184.925963] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.251machine2 # [1547184.927841] machine2 systemd[1]: Finished Save Transient machine-id to Disk.252machine1 # [1547184.952764] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.253machine1 # [1547184.953898] machine1 systemd[1]: Finished Save Transient machine-id to Disk.254machine2 # [1547185.669126] machine2 systemd-logind[258]: New seat seat0.255machine1 # [1547185.662745] machine1 systemd-logind[253]: New seat seat0.256machine1 # [1547185.663842] machine1 systemd[1]: Started User Login Management.257machine1 # [1547185.665497] machine1 systemd[1]: Starting D-Bus System Message Bus...258machine1 # [1547185.666521] machine1 systemd[1]: Starting linger-users.service...259machine1 # [1547185.712336] machine1 systemd[1]: linger-users.service: Deactivated successfully.260machine1 # [1547185.712536] machine1 systemd[1]: Finished linger-users.service.261machine1 # [1547185.712809] machine1 systemd[1]: Reached target Multi-User System.262machine2 # [1547185.669353] machine2 systemd[1]: Started User Login Management.263machine2 # [1547185.704492] machine2 systemd[1]: Starting D-Bus System Message Bus...264machine2 # [1547185.705442] machine2 systemd[1]: Starting linger-users.service...265machine2 # [1547185.718469] machine2 systemd[1]: linger-users.service: Deactivated successfully.266machine2 # [1547185.718548] machine2 systemd[1]: Finished linger-users.service.267machine2 # [1547185.718712] machine2 systemd[1]: Reached target Multi-User System.268machine1 # [1547186.415361] machine1 dbus-broker-launch[279]: Looking up NSS user entry for 'systemd-timesync'...269machine1 # [1547186.417280] machine1 dbus-broker-launch[279]: NSS returned no entry for 'systemd-timesync'270machine1 # [1547186.417280] machine1 dbus-broker-launch[279]: Invalid user-name in /nix/store/q2j5z2r3vaff2mz4g5abjanb05bzg12a-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"271machine1 # [1547186.418011] machine1 systemd[1]: Started D-Bus System Message Bus.272machine1 # [1547186.418705] machine1 systemd[1]: Startup finished in 4.112s.273machine1 # [1547186.425343] machine1 dbus-broker-launch[279]: Ready274machine2 # [1547186.409670] machine2 dbus-broker-launch[277]: Looking up NSS user entry for 'systemd-timesync'...275machine2 # [1547186.411140] machine2 dbus-broker-launch[277]: NSS returned no entry for 'systemd-timesync'276machine2 # [1547186.411140] 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"277machine2 # [1547186.411739] machine2 systemd[1]: Started D-Bus System Message Bus.278machine2 # [1547186.416802] machine2 systemd[1]: Startup finished in 4.116s.279machine2 # [1547186.420237] machine2 dbus-broker-launch[277]: Ready280machine1: (finished: must succeed: systemctl status sshd, in 5.15 seconds)281machine2: must succeed: systemctl status sshd282machine2: (finished: must succeed: systemctl status sshd, in 0.01 seconds)283machine1: waiting for unit sshd284machine1: (finished: waiting for unit sshd, in 0.01 seconds)285machine2: waiting for unit sshd286machine2: (finished: waiting for unit sshd, in 0.01 seconds)287machine1: must succeed: ip addr show eth1288machine1: (finished: must succeed: ip addr show eth1, in 0.01 seconds)289machine2: must succeed: ip addr show eth1290machine2: (finished: must succeed: ip addr show eth1, in 0.01 seconds)291machine1: must succeed: ping -c 1 machine2292machine1: (finished: must succeed: ping -c 1 machine2, in 0.01 seconds)293(finished: run the VM test script, in 5.22 seconds)294test script finished in 5.55s295cleanup296kill NspawnMachine (pid 52)297kill NspawnMachine (pid 53)298Container machine1 terminated by signal KILL.299Container machine2 terminated by signal KILL.300(finished: cleanup, in 0.43 seconds)