container-test-run-container
checks.aarch64-linux.nixos-test-container
· build #805
· raw
1build01: building dndbjh6fpwr2cv94db1jsva3a0rac8jf-container-test-run-container.drv2Machine state will be reset. To keep it, pass --keep-machine-state3start all VLans4(finished: start all VLans, in 0.00 seconds)56Test will time out and terminate in 3600.0 seconds7run the VM test script8additionally exposed symbols:9 machine1, machine2,10 vlan1,11 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_ssh12start all VMs13machine1: systemd-nspawn running (pid 52)14machine2: systemd-nspawn running (pid 53)15machine1: Waiting for journal at /build/vm-state-machine1/var/log/journal...16machine2: Waiting for journal at /build/vm-state-machine2/var/log/journal...17(finished: start all VMs, in 0.00 seconds)18machine1: must succeed: systemctl status sshd19nixos-nspawn(machine2): TAP vde-tap1 not found; container will be isolated from VDE20nixos-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.21nixos-nspawn(machine1): TAP vde-tap1 not found; container will be isolated from VDE22nixos-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.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.24Note: 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.25░ Spawning container machine2 on /build/vm-state-machine2.26░ Spawning container machine1 on /build/vm-state-machine1.27machine1 # [2170626.370472] machine1 systemd-journald[57]: Journal started28machine1 # [2170626.370529] machine1 systemd-journald[57]: Runtime Journal (/run/log/journal/6a55c176a6a248c2971b26e7584fbb92) is 8M, max 2.5G, 2.4G free.29machine1 # [2170626.372971] machine1 systemd[1]: Finished Apply Kernel Variables.30machine1 # [2170626.383928] machine1 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.31machine1 # [2170626.396774] machine1 systemd[1]: Starting Flush Journal to Persistent Storage...32machine1 # [2170626.397483] machine1 systemd[1]: Starting Create Static Device Nodes in /dev...33machine1 # [2170626.405504] machine1 systemd-journald[57]: Time spent on flushing to /var/log/journal/6a55c176a6a248c2971b26e7584fbb92 is 1.206ms for 6 entries.34machine1 # [2170626.405504] machine1 systemd-journald[57]: System Journal (/var/log/journal/6a55c176a6a248c2971b26e7584fbb92) is 8M, max 4G, 3.9G free.35machine1 # [2170626.414610] machine1 systemd[1]: Finished Create Static Device Nodes in /dev.36machine1 # [2170626.415248] machine1 systemd[1]: Reached target Preparation for Local File Systems.37machine1 # [2170626.415366] machine1 systemd[1]: Reached target Local File Systems.38machine1 # [2170626.416195] machine1 systemd[1]: Listening on Boot Loader Control Service Socket.39machine1 # [2170626.416246] machine1 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container40machine1 # [2170626.417053] machine1 systemd[1]: Starting Save Transient machine-id to Disk...41machine1 # [2170626.417087] machine1 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys42machine1 # [2170626.417544] machine1 systemd[1]: Finished Flush Journal to Persistent Storage.43machine1 # [2170626.418798] machine1 systemd[1]: Starting Create System Files and Directories...44machine1 # [2170626.433537] machine1 systemd-tmpfiles[103]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted45machine1 # [2170626.433714] machine1 systemd-tmpfiles[103]: fchmod() of /var/log/journal failed: Operation not permitted46machine1 # [2170626.433828] machine1 systemd-tmpfiles[103]: fchmod() of /var/log/journal/6a55c176a6a248c2971b26e7584fbb92 failed: Operation not permitted47machine1 # [2170626.434000] machine1 systemd-tmpfiles[103]: fchmod() of /run/log/journal failed: Operation not permitted48machine1 # [2170626.436527] machine1 systemd[1]: Finished Create System Files and Directories.49machine1 # [2170626.437914] machine1 systemd[1]: Starting Rebuild Journal Catalog...50machine1 # [2170626.438746] machine1 systemd[1]: Starting Record System Boot/Shutdown in UTMP...51machine1 # [2170626.440672] machine1 systemd[1]: Finished Save Transient machine-id to Disk.52machine1 # [2170626.452440] machine1 systemd[1]: Finished Record System Boot/Shutdown in UTMP.53machine1 # [2170626.458309] machine1 systemd[1]: Finished Rebuild Journal Catalog.54machine1 # [2170626.459444] machine1 systemd[1]: Starting Update is Completed...55machine1 # [2170626.470992] machine1 systemd[1]: Finished Update is Completed.56machine1 # [2170626.471173] machine1 systemd[1]: Reached target System Initialization.57machine1 # [2170626.471266] machine1 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container58machine1 # [2170626.471305] machine1 systemd[1]: Started Daily Cleanup of Temporary Directories.59machine1 # [2170626.471323] machine1 systemd[1]: Reached target Timer Units.60machine1 # [2170626.471446] machine1 systemd[1]: Listening on D-Bus System Message Bus Socket.61machine1 # [2170626.471584] machine1 systemd[1]: Listening on Nix Daemon Socket.62machine1 # [2170626.472766] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).63machine1 # [2170626.473759] machine1 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).64machine1 # [2170626.473868] machine1 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.65machine1 # [2170626.473891] machine1 systemd[1]: Reached target Socket Units.66machine1 # [2170626.473939] machine1 systemd[1]: Reached target Basic System.67machine1 # [2170626.475027] machine1 systemd[1]: Starting Import lastlog data into lastlog2 database...68machine1 # [2170626.476072] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...69machine1 # [2170626.476752] machine1 systemd[1]: Starting SSH Host Keys Generation...70machine1 # [2170626.496286] machine1 systemd[1]: Finished Import lastlog data into lastlog2 database.71machine2 # [2170626.317856] machine2 systemd-journald[57]: Journal started72machine2 # [2170626.317913] machine2 systemd-journald[57]: Runtime Journal (/run/log/journal/8ca740977c5d4b7bb0b7672eeab22772) is 8M, max 2.5G, 2.4G free.73machine2 # [2170626.325518] machine2 systemd[1]: Finished Apply Kernel Variables.74machine2 # [2170626.337815] machine2 systemd[1]: Finished Create Static Device Nodes in /dev gracefully.75machine2 # [2170626.351410] machine2 systemd[1]: Starting Flush Journal to Persistent Storage...76machine2 # [2170626.352264] machine2 systemd[1]: Starting Create Static Device Nodes in /dev...77machine2 # [2170626.362163] machine2 systemd-journald[57]: Time spent on flushing to /var/log/journal/8ca740977c5d4b7bb0b7672eeab22772 is 1.193ms for 6 entries.78machine2 # [2170626.362163] machine2 systemd-journald[57]: System Journal (/var/log/journal/8ca740977c5d4b7bb0b7672eeab22772) is 8M, max 4G, 3.9G free.79machine2 # [2170626.369063] machine2 systemd[1]: Finished Flush Journal to Persistent Storage.80machine2 # [2170626.369416] machine2 systemd[1]: Finished Create Static Device Nodes in /dev.81machine2 # [2170626.370394] machine2 systemd[1]: Reached target Preparation for Local File Systems.82machine2 # [2170626.370505] machine2 systemd[1]: Reached target Local File Systems.83machine2 # [2170626.371316] machine2 systemd[1]: Listening on Boot Loader Control Service Socket.84machine2 # [2170626.371361] machine2 systemd[1]: Update Boot Loader Random Seed skipped, unmet condition check ConditionVirtualization=!container85machine2 # [2170626.372189] machine2 systemd[1]: Starting Save Transient machine-id to Disk...86machine2 # [2170626.372878] machine2 systemd[1]: Starting Create System Files and Directories...87machine2 # [2170626.372914] machine2 systemd[1]: Rule-based Manager for Device Events and Files skipped, unmet condition check ConditionPathIsReadWrite=/sys88machine2 # [2170626.390702] machine2 systemd-tmpfiles[106]: Cannot set file attributes for '/var/empty', value=0x00000010, mask=0x00000010, ignoring: Operation not permitted89machine2 # [2170626.390928] machine2 systemd-tmpfiles[106]: fchmod() of /var/log/journal failed: Operation not permitted90machine2 # [2170626.391092] machine2 systemd-tmpfiles[106]: fchmod() of /var/log/journal/8ca740977c5d4b7bb0b7672eeab22772 failed: Operation not permitted91machine2 # [2170626.391337] machine2 systemd-tmpfiles[106]: fchmod() of /run/log/journal failed: Operation not permitted92machine2 # [2170626.392784] machine2 systemd[1]: Finished Create System Files and Directories.93machine2 # [2170626.393806] machine2 systemd[1]: Starting Rebuild Journal Catalog...94machine2 # [2170626.394519] machine2 systemd[1]: Starting Record System Boot/Shutdown in UTMP...95machine2 # [2170626.406107] machine2 systemd[1]: Finished Save Transient machine-id to Disk.96machine2 # [2170626.408275] machine2 systemd[1]: Finished Record System Boot/Shutdown in UTMP.97machine2 # [2170626.416742] machine2 systemd[1]: Finished Rebuild Journal Catalog.98machine2 # [2170626.417761] machine2 systemd[1]: Starting Update is Completed...99machine2 # [2170626.427524] machine2 systemd[1]: Finished Update is Completed.100machine2 # [2170626.427689] machine2 systemd[1]: Reached target System Initialization.101machine2 # [2170626.427767] machine2 systemd[1]: Discard unused filesystem blocks once a week skipped, unmet condition check ConditionVirtualization=!container102machine2 # [2170626.427805] machine2 systemd[1]: Started Daily Cleanup of Temporary Directories.103machine2 # [2170626.427824] machine2 systemd[1]: Reached target Timer Units.104machine2 # [2170626.427933] machine2 systemd[1]: Listening on D-Bus System Message Bus Socket.105machine2 # [2170626.428072] machine2 systemd[1]: Listening on Nix Daemon Socket.106machine2 # [2170626.429098] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Export).107machine2 # [2170626.429932] machine2 systemd[1]: Listening on OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).108machine2 # [2170626.430034] machine2 systemd[1]: Listening on Virtual Machine and Container Registration Service Socket.109machine2 # [2170626.430053] machine2 systemd[1]: Reached target Socket Units.110machine2 # [2170626.430095] machine2 systemd[1]: Reached target Basic System.111machine2 # [2170626.431102] machine2 systemd[1]: Starting Import lastlog data into lastlog2 database...112machine2 # [2170626.432404] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...113machine2 # [2170626.433097] machine2 systemd[1]: Starting SSH Host Keys Generation...114machine2 # [2170626.450184] machine2 systemd[1]: Finished Import lastlog data into lastlog2 database.115machine2 # [2170626.482035] machine2 systemd[1]: Finished Firewall.116machine2 # [2170626.482186] machine2 systemd[1]: Reached target Preparation for Network.117machine2 # [2170626.483264] machine2 systemd[1]: Starting Address configuration of eth1...118machine2 # [2170626.484220] machine2 systemd[1]: Starting Extra networking commands....119machine2 # [2170626.504309] machine2 network-addresses-eth1-start[182]: adding address 192.168.1.2/24... done120machine2 # [2170626.506782] machine2 network-addresses-eth1-start[182]: adding address 2001:db8:1::2/64... done121machine2 # [2170626.511619] machine2 systemd[1]: Finished Address configuration of eth1.122machine2 # [2170626.554399] machine2 systemd[1]: nscd.service: Deactivated successfully.123machine2 # [2170626.554528] machine2 systemd[1]: Stopped Name Service Cache Daemon (nsncd).124machine2 # [2170626.556544] machine2 systemd[1]: Starting Name Service Cache Daemon (nsncd)...125machine2 # [2170626.562778] machine2 systemd[1]: Finished Extra networking commands..126machine2 # [2170626.562959] machine2 systemd[1]: Reached target Network.127machine1 # [2170626.531531] machine1 systemd[1]: Finished Firewall.128machine1 # [2170626.531692] machine1 systemd[1]: Reached target Preparation for Network.129machine1 # [2170626.532558] machine1 systemd[1]: Starting Address configuration of eth1...130machine1 # [2170626.533302] machine1 systemd[1]: Starting Extra networking commands....131machine1 # [2170626.552075] machine1 network-addresses-eth1-start[182]: adding address 192.168.1.1/24... done132machine1 # [2170626.554852] machine1 network-addresses-eth1-start[182]: adding address 2001:db8:1::1/64... done133machine1 # [2170626.559588] machine1 systemd[1]: Finished Address configuration of eth1.134machine1 # [2170626.597087] machine1 systemd[1]: nscd.service: Deactivated successfully.135machine1 # [2170626.597220] machine1 systemd[1]: Stopped Name Service Cache Daemon (nsncd).136machine1 # [2170626.599300] machine1 systemd[1]: Starting Name Service Cache Daemon (nsncd)...137machine1 # [2170626.604745] machine1 systemd[1]: Finished Extra networking commands..138machine1 # [2170626.604907] machine1 systemd[1]: Reached target Network.139machine1 # [2170626.724536] machine1 systemd[1]: Started Name Service Cache Daemon (nsncd).140machine1 # [2170626.724646] machine1 systemd[1]: Reached target Host and Network Name Lookups.141machine1 # [2170626.724737] machine1 systemd[1]: Reached target User and Group Name Lookups.142machine2 # [2170626.711738] machine2 nsncd[243]: Sep 29 10:24:06.697 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"143machine2 # [2170626.711823] machine2 systemd[1]: Started Name Service Cache Daemon (nsncd).144machine2 # [2170626.711928] machine2 systemd[1]: Reached target Host and Network Name Lookups.145machine2 # [2170626.712067] machine2 systemd[1]: Reached target User and Group Name Lookups.146machine2 # [2170626.713978] machine2 systemd[1]: Starting User Login Management...147machine2 # [2170626.715310] machine2 systemd[1]: Starting Permit User Sessions...148machine2 # [2170626.772200] machine2 systemd[1]: Finished Permit User Sessions.149machine2 # [2170626.773425] machine2 systemd[1]: Started Console Getty.150machine2 # [2170626.773480] machine2 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0151machine2 # [2170626.773504] machine2 systemd[1]: Reached target Login Prompts.152machine1 # [2170626.725127] machine1 nsncd[244]: Sep 29 10:24:06.710 INFO started, config: Config { ignored_request_types: {}, worker_count: 8, handoff_timeout: 10s }, path: "/var/run/nscd/socket"153machine1 # [2170626.764693] machine1 systemd[1]: Starting User Login Management...154machine1 # [2170626.766050] machine1 systemd[1]: Starting Permit User Sessions...155machine1 # [2170626.780827] machine1 systemd[1]: Finished Permit User Sessions.156machine1 # [2170626.782000] machine1 systemd[1]: Started Console Getty.157machine1 # [2170626.782046] machine1 systemd[1]: Getty on tty1 skipped, unmet condition check ConditionPathExists=/dev/tty0158machine1 # [2170626.782069] machine1 systemd[1]: Reached target Login Prompts.159machine2 # [2170627.324846] machine2 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.160machine2 # [2170627.467618] machine2 systemd-logind[259]: New seat seat0.161machine2 # [2170627.467908] machine2 systemd[1]: Started User Login Management.162machine2 # [2170627.469010] machine2 systemd[1]: Starting D-Bus System Message Bus...163machine2 # [2170627.469708] machine2 systemd[1]: Starting linger-users.service...164machine2 # [2170627.522100] machine2 systemd[1]: linger-users.service: Deactivated successfully.165machine2 # [2170627.522356] machine2 systemd[1]: Finished linger-users.service.166machine1 # [2170627.377961] machine1 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.167machine1 # [2170627.488620] machine1 systemd-logind[259]: New seat seat0.168machine1 # [2170627.488902] machine1 systemd[1]: Started User Login Management.169machine1 # [2170627.512487] machine1 systemd[1]: Starting D-Bus System Message Bus...170machine1 # [2170627.513722] machine1 systemd[1]: Starting linger-users.service...171machine1 # [2170627.529679] machine1 systemd[1]: linger-users.service: Deactivated successfully.172machine1 # [2170627.529815] machine1 systemd[1]: Finished linger-users.service.173machine2 # [2170627.809215] machine2 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'...174machine2 # [2170627.810568] machine2 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync'175machine2 # [2170627.810568] machine2 dbus-broker-launch[269]: Invalid user-name in /nix/store/4ckq522appv84b4qp4d8af5czsdx0lz2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"176machine2 # [2170627.811020] machine2 systemd[1]: Started D-Bus System Message Bus.177machine2 # [2170627.819696] machine2 dbus-broker-launch[269]: Ready178machine1 # [2170627.795499] machine1 dbus-broker-launch[269]: Looking up NSS user entry for 'systemd-timesync'...179machine1 # [2170627.796485] machine1 dbus-broker-launch[269]: NSS returned no entry for 'systemd-timesync'180machine1 # [2170627.796485] machine1 dbus-broker-launch[269]: Invalid user-name in /nix/store/4ckq522appv84b4qp4d8af5czsdx0lz2-system-path/share/dbus-1/system.d/org.freedesktop.timesync1.conf +16: user="systemd-timesync"181machine1 # [2170627.797221] machine1 systemd[1]: Started D-Bus System Message Bus.182machine1 # [2170627.805899] machine1 dbus-broker-launch[269]: Ready183machine1: (finished: must succeed: systemctl status sshd, in 2.65 seconds)184machine2: must succeed: systemctl status sshd185machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: Generating public/private rsa key pair.186machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key187machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub188machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: The key fingerprint is:189machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: SHA256:x29zXCtb8hFUOyyMgng+4XV6rJsAQt48CCQuHzMDtVY root@machine1190machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: The key's randomart image is:191machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: +---[RSA 4096]----+192machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: |oo. E .|193machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: |+. o . . o . o|194machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: |.oB. . + o o o = |195machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: |.o+=+ + o.= o .|196machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: | .+ = +S.oo ..|197machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: | . o ..o. . .o|198machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: | . . +oo+ |199machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: | . o. o* .|200machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: | o . . |201machine1 # [2170628.073826] machine1 sshd-keygen-start[160]: +----[SHA256]-----+202machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: Generating public/private ed25519 key pair.203machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key204machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub205machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: The key fingerprint is:206machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: SHA256:7HU9dAmM5lsFLcCdCXkVFtVMbE3ZlufR66zcrrnw6IU root@machine1207machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: The key's randomart image is:208machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: +--[ED25519 256]--+209machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | .oBo*X#|210machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | = Bo*%|211machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | o . +==|212machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | . . + o.|213machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | S . + = |214machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | . . o. + |215machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | . E..o |216machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | =o.. |217machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: | .o =+. |218machine1 # [2170628.092162] machine1 sshd-keygen-start[279]: +----[SHA256]-----+219machine1 # [2170628.093539] machine1 systemd[1]: sshd-keygen.service: Deactivated successfully.220machine1 # [2170628.093811] machine1 systemd[1]: Finished SSH Host Keys Generation.221machine1 # [2170628.094266] machine1 systemd[1]: sshd-keygen.service: Consumed 3.269s CPU time over 1.616s wall clock time.222machine1 # [2170628.096824] machine1 systemd[1]: Starting SSH Daemon...223machine1 # [2170628.152149] machine1 sshd[281]: Server listening on 0.0.0.0 port 22.224machine1 # [2170628.152191] machine1 sshd[281]: Server listening on :: port 22.225machine1 # [2170628.152442] machine1 systemd[1]: Started SSH Daemon.226machine1 # [2170628.152909] machine1 systemd[1]: Reached target Multi-User System.227machine1 # [2170628.153266] machine1 systemd[1]: Startup finished in 2.158s.228machine2: (finished: must succeed: systemctl status sshd, in 1.15 seconds)229machine1: waiting for unit sshd230machine1: (finished: waiting for unit sshd, in 0.02 seconds)231machine2: waiting for unit sshd232machine2: (finished: waiting for unit sshd, in 0.02 seconds)233machine1: must succeed: ip addr show eth1234machine1: (finished: must succeed: ip addr show eth1, in 0.01 seconds)235machine2: must succeed: ip addr show eth1236machine2: (finished: must succeed: ip addr show eth1, in 0.01 seconds)237machine1: must succeed: ping -c 1 machine2238machine1: (finished: must succeed: ping -c 1 machine2, in 0.01 seconds)239(finished: run the VM test script, in 3.87 seconds)240test script finished in 3.91s241cleanup242kill NspawnMachine (pid 52)243kill NspawnMachine (pid 53)244machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: Generating public/private rsa key pair.245machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key246machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub247machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: The key fingerprint is:248machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: SHA256:zFq+vaMC1rRvBwqlQ1HaxJZx6+cZAt3s60rwruHEZnY root@machine2249machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: The key's randomart image is:250machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: +---[RSA 4096]----+251machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | o+o. |252machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | .++o + |253machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | .oo o o |254machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | . o= . |255machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | . =..S + |256machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | *.o*.+ + |257machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | . +OoE.+ |258machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | *o=o+o |259machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: | o+=++o |260machine2 # [2170629.136469] machine2 sshd-keygen-start[166]: +----[SHA256]-----+261machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: Generating public/private ed25519 key pair.262machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key263machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub264machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: The key fingerprint is:265machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: SHA256:YpY/Pqzb1rOQlCGD+K+Gmo4nseuQeaKps5DUz3Pnykc root@machine2266machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: The key's randomart image is:267machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: +--[ED25519 256]--+268machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: | |269machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: | . . |270machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: | . . o . |271machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: | .. + o |272machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: | . .. = S |273machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: |o+ o+ + E |274machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: |Bo.. +.o*o |275machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: |O=o ..+o*+o |276machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: |&X .. o*=o.o |277machine2 # [2170629.154868] machine2 sshd-keygen-start[279]: +----[SHA256]-----+278machine2 # [2170629.157014] machine2 systemd[1]: sshd-keygen.service: Deactivated successfully.279machine2 # [2170629.157175] machine2 systemd[1]: Finished SSH Host Keys Generation.280machine2 # [2170629.159102] machine2 systemd[1]: Starting SSH Daemon...281machine2 # [2170629.208719] machine2 sshd[281]: Server listening on 0.0.0.0 port 22.282machine2 # [2170629.208757] machine2 sshd[281]: Server listening on :: port 22.283machine2 # [2170629.209198] machine2 systemd[1]: Started SSH Daemon.284machine2 # [2170629.209517] machine2 systemd[1]: Reached target Multi-User System.285machine2 # [2170629.209905] machine2 systemd[1]: Startup finished in 3.263s.286Container machine1 terminated by signal KILL.287(finished: cleanup, in 0.28 seconds)288Container machine2 terminated by signal KILL.